runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: runsolver -s SIGUSR1 -M 1124 -C 300 -d 10 -w /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/upgrade/real/7c834c0e-51b8-11e0-a49e-00163e1e087d.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/real/7c834c0e-51b8-11e0-a49e-00163e1e087d.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/upgrade/real/7c834c0e-51b8-11e0-a49e-00163e1e087d.cudf.result -count(down),-notuptodate(solution),-aligned(solution,source,sourceversion),-unsat_recommends(solution),-count(new) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 300 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 330 seconds
Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB
Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB

[startup+0 s]
/proc/loadavg: 1.06 1.08 1.02 2/60 16989
/proc/meminfo: memFree=539372/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=3152 CPUtime=0
/proc/16989/stat : 16989 (runsolver) R 16988 32685 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 39421387 3227648 33 18446744073709551615 134512640 134586868 4292400464 4292398512 4151333936 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 788 33 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.153911 s]
/proc/loadavg: 1.06 1.08 1.02 2/60 16989
/proc/meminfo: memFree=539372/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.200306 s]
/proc/loadavg: 1.06 1.08 1.02 2/60 16989
/proc/meminfo: memFree=539372/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.30031 s]
/proc/loadavg: 1.06 1.08 1.02 2/60 16989
/proc/meminfo: memFree=539372/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.700247 s]
/proc/loadavg: 1.06 1.08 1.02 2/60 16989
/proc/meminfo: memFree=539372/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+1.5008 s]
/proc/loadavg: 1.06 1.08 1.02 4/71 17001
/proc/meminfo: memFree=493520/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1054000 CPUtime=1.44
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 30479 0 0 0 127 17 0 0 20 0 10 0 39421388 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 263500 27325 2348 9 0 250850 0
[pid=16992/tid=16993] ppid=16989 vsize=1054000 CPUtime=0.65
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 13172 0 0 0 55 10 0 0 20 0 10 0 39421388 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356525089440 140356422519357 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1054000 CPUtime=0
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 39421390 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1054000 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 39421391 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1054000 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 10 0 39421391 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1054000 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1054000 CPUtime=0.39
/proc/16992/task/16998/stat : 16998 (java) R 16989 16989 32685 0 -1 4202560 9069 0 0 0 34 5 0 0 20 0 10 0 39421393 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356413512808 140356502162031 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1054000 CPUtime=0.38
/proc/16992/task/16999/stat : 16999 (java) R 16989 16989 32685 0 -1 4202560 6949 0 0 0 36 2 0 0 20 0 10 0 39421393 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356412459912 140356511100410 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1054000 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1054000 CPUtime=0
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1079296000 27325 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.44
Current children cumulated vsize (KiB) 1063172

[startup+3.10081 s]
/proc/loadavg: 1.22 1.11 1.03 3/71 17001
/proc/meminfo: memFree=397792/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1050348 CPUtime=3.01
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 75710 0 0 0 271 30 0 0 20 0 10 0 39421388 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262587 70273 2368 9 0 249937 0
[pid=16992/tid=16993] ppid=16989 vsize=1050348 CPUtime=1.61
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47412 0 0 0 141 20 0 0 20 0 10 0 39421388 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356525089472 140356422559076 0 4 0 16800975 0 0 0 -1 0 0 0 5 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1050348 CPUtime=0.14
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 8802 0 0 0 11 3 0 0 20 0 10 0 39421390 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1050348 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 39421391 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1050348 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 39421391 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1050348 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1050348 CPUtime=0.61
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 9698 0 0 0 56 5 0 0 20 0 10 0 39421393 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1050348 CPUtime=0.63
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8509 0 0 0 60 3 0 0 20 0 10 0 39421393 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1050348 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1050348 CPUtime=0
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1075556352 70273 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.01
Current children cumulated vsize (KiB) 1059520

[startup+6.30076 s]
/proc/loadavg: 1.22 1.11 1.03 3/71 17001
/proc/meminfo: memFree=117428/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1050824 CPUtime=6.15
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 108996 0 0 0 572 43 0 0 20 0 10 0 39421388 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262706 103559 2374 9 0 250056 0
[pid=16992/tid=16993] ppid=16989 vsize=1050824 CPUtime=3.02
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47711 0 0 0 281 21 0 0 20 0 10 0 39421388 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356525097432 140356422608737 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1050824 CPUtime=0.88
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 41472 0 0 0 75 13 0 0 20 0 10 0 39421390 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1050824 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 39421391 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1050824 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 39421391 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1050824 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1050824 CPUtime=1.04
/proc/16992/task/16998/stat : 16998 (java) R 16989 16989 32685 0 -1 4202560 9826 0 0 0 99 5 0 0 20 0 10 0 39421393 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356413524752 140356502868175 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1050824 CPUtime=1.17
/proc/16992/task/16999/stat : 16999 (java) R 16989 16989 32685 0 -1 4202560 8698 0 0 0 114 3 0 0 20 0 10 0 39421393 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356412458424 140356502867667 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1050824 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1050824 CPUtime=0
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39421393 1076043776 103559 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.15
Current children cumulated vsize (KiB) 1059996

[startup+12.7009 s]
/proc/loadavg: 1.33 1.14 1.04 3/72 17002
/proc/meminfo: memFree=74268/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=12.48
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 119553 0 0 0 1200 48 0 0 20 0 11 0 39421388 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 114023 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=5.92
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47808 0 0 0 570 22 0 0 20 0 11 0 39421388 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356525097064 140356423898577 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=1.63
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 51658 0 0 0 146 17 0 0 20 0 11 0 39421390 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.53
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 248 5 0 0 20 0 11 0 39421393 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.36
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 232 4 0 0 20 0 11 0 39421393 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 114023 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.48
Current children cumulated vsize (KiB) 1061024

[startup+25.5009 s]
/proc/loadavg: 1.28 1.13 1.04 2/72 17002
/proc/meminfo: memFree=58396/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=25.12
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 123432 0 0 0 2463 49 0 0 20 0 11 0 39421388 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 117902 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=15.73
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 1551 22 0 0 20 0 11 0 39421388 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423937305 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=4.3
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 55536 0 0 0 412 18 0 0 20 0 11 0 39421390 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.61
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 256 5 0 0 20 0 11 0 39421393 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.43
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 239 4 0 0 20 0 11 0 39421393 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.01
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 39421393 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 117902 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.12
Current children cumulated vsize (KiB) 1061024

[startup+51.1008 s]
/proc/loadavg: 1.18 1.12 1.04 2/72 17002
/proc/meminfo: memFree=43020/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=50.41
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 127252 0 0 0 4991 50 0 0 20 0 11 0 39421388 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 121718 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=37.11
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 3689 22 0 0 20 0 11 0 39421388 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423932499 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=8.2
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 59353 0 0 0 801 19 0 0 20 0 11 0 39421390 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.62
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 257 5 0 0 20 0 11 0 39421393 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.44
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 240 4 0 0 20 0 11 0 39421393 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.02
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 39421393 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 121718 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.41
Current children cumulated vsize (KiB) 1061024

[startup+102.301 s]
/proc/loadavg: 1.12 1.11 1.04 2/72 17002
/proc/meminfo: memFree=10160/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=101.03
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 137193 0 0 0 10051 52 0 0 20 0 11 0 39421388 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 131655 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=82.21
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 8199 22 0 0 20 0 11 0 39421388 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423359058 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=13.62
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 69291 0 0 0 1341 21 0 0 20 0 11 0 39421390 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.47
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 243 4 0 0 20 0 11 0 39421393 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.05
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 5 0 0 0 20 0 11 0 39421393 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 131655 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 101.03
Current children cumulated vsize (KiB) 1061024

[startup+162.301 s]
/proc/loadavg: 1.04 1.09 1.03 2/72 17010
/proc/meminfo: memFree=10120/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=160.37
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 141573 0 0 0 15983 54 0 0 20 0 11 0 39421388 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 136023 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=136.91
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 13669 22 0 0 20 0 11 0 39421388 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423346480 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=18.22
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 73662 0 0 0 1799 23 0 0 20 0 11 0 39421390 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.08
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 15 0 0 0 8 0 0 0 20 0 11 0 39421393 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 136023 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.37
Current children cumulated vsize (KiB) 1061024

[startup+222.304 s]
/proc/loadavg: 1.07 1.09 1.03 2/72 17010
/proc/meminfo: memFree=9776/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=219.72
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 145128 0 0 0 21916 56 0 0 20 0 11 0 39421388 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 139570 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=192.47
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 19225 22 0 0 20 0 11 0 39421388 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423661952 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=21.98
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 77211 0 0 0 2172 26 0 0 20 0 11 0 39421390 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.12
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 21 0 0 0 12 0 0 0 20 0 11 0 39421393 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 139570 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.72
Current children cumulated vsize (KiB) 1061024

[startup+282.301 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9920/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=279.19
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 150408 0 0 0 27861 58 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 144846 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=248.79
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 24857 22 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423375333 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=25.09
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 82488 0 0 0 2482 27 0 0 20 0 11 0 39421390 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.14
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 279.19
Current children cumulated vsize (KiB) 1061024

Solver just ended. Dumping a history of the last processes samples

[startup+288.001 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9796/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=284.84
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 150408 0 0 0 28426 58 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 144846 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=254.45
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 25423 22 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423359084 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=25.09
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 82488 0 0 0 2482 27 0 0 20 0 11 0 39421390 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.15
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 24 0 0 0 15 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.84
Current children cumulated vsize (KiB) 1061024

[startup+291.201 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9796/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=288.02
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 150412 0 0 0 28744 58 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 144846 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=257.14
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 25692 22 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423359024 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=25.57
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 82489 0 0 0 2530 27 0 0 20 0 11 0 39421390 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.15
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 27 0 0 0 15 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 288.02
Current children cumulated vsize (KiB) 1061024

[startup+292.801 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9796/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=289.61
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 150412 0 0 0 28903 58 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 144846 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=258.73
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 25851 22 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423375245 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=25.57
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 82489 0 0 0 2530 27 0 0 20 0 11 0 39421390 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.15
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 27 0 0 0 15 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.61
Current children cumulated vsize (KiB) 1061024

[startup+293.601 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9796/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1051852 CPUtime=290.4
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 150412 0 0 0 28982 58 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 262963 144846 2393 9 0 250313 0
[pid=16992/tid=16993] ppid=16989 vsize=1051852 CPUtime=259.52
/proc/16992/task/16993/stat : 16993 (java) R 16989 16989 32685 0 -1 4202560 47809 0 0 0 25930 22 0 0 20 0 11 0 39421388 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356525096936 140356423298390 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1051852 CPUtime=25.57
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 82489 0 0 0 2530 27 0 0 20 0 11 0 39421390 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1051852 CPUtime=2.65
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 260 5 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1051852 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1051852 CPUtime=0.15
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 27 0 0 0 15 0 0 0 20 0 11 0 39421393 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17002] ppid=16989 vsize=1051852 CPUtime=0
/proc/16992/task/17002/stat : 17002 (java) S 16989 16989 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39422269 1077096448 144846 18446744073709551615 4194304 4228012 140735694974064 140356409165664 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.4
Current children cumulated vsize (KiB) 1061024

[startup+294.401 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9796/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1052880 CPUtime=290.68
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 150425 0 0 0 29010 58 0 0 20 0 11 0 39421388 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 263220 144857 2393 9 0 250570 0
[pid=16992/tid=16993] ppid=16989 vsize=1052880 CPUtime=259.77
/proc/16992/task/16993/stat : 16993 (java) S 16989 16989 32685 0 -1 4202560 47815 0 0 0 25955 22 0 0 20 0 11 0 39421388 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356525097904 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1052880 CPUtime=25.57
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 82489 0 0 0 2530 27 0 0 20 0 11 0 39421390 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1052880 CPUtime=2.67
/proc/16992/task/16998/stat : 16998 (java) S 16989 16989 32685 0 -1 4202560 10000 0 0 0 262 5 0 0 20 0 11 0 39421393 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356413525008 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1052880 CPUtime=2.48
/proc/16992/task/16999/stat : 16999 (java) S 16989 16989 32685 0 -1 4202560 8792 0 0 0 244 4 0 0 20 0 11 0 39421393 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356412472336 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1052880 CPUtime=0.15
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 27 0 0 0 15 0 0 0 20 0 11 0 39421393 1078149120 144857 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.68
Current children cumulated vsize (KiB) 1062052

[startup+294.601 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9796/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=1052880 CPUtime=290.88
/proc/16992/stat : 16992 (java) S 16989 16989 32685 0 -1 4202496 150428 0 0 0 29027 61 0 0 20 0 11 0 39421388 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140735694965088 140356518563445 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 263220 144860 2394 9 0 250570 0
[pid=16992/tid=16993] ppid=16989 vsize=1052880 CPUtime=259.77
/proc/16992/task/16993/stat : 16993 (java) S 16989 16989 32685 0 -1 4202560 47815 0 0 0 25955 22 0 0 20 0 11 0 39421388 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356525097904 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=16992/tid=16994] ppid=16989 vsize=1052880 CPUtime=25.57
/proc/16992/task/16994/stat : 16994 (java) S 16989 16989 32685 0 -1 4202560 82489 0 0 0 2530 27 0 0 20 0 11 0 39421390 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356417735808 140356518577739 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16995] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/16995/stat : 16995 (java) S 16989 16989 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39421391 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356416682000 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16996] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/16996/stat : 16996 (java) S 16989 16989 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 39421391 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356415629216 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16997] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/16997/stat : 16997 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356414577648 140356518585376 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16998] ppid=16989 vsize=1052880 CPUtime=2.69
/proc/16992/task/16998/stat : 16998 (java) R 16989 16989 32685 0 -1 4202560 10001 0 0 0 264 5 0 0 20 0 11 0 39421393 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356413524752 140356500813302 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=16999] ppid=16989 vsize=1052880 CPUtime=2.52
/proc/16992/task/16999/stat : 16999 (java) R 16989 16989 32685 0 -1 4202560 8792 0 0 0 248 4 0 0 20 0 11 0 39421393 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356412472080 140356499935707 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17000] ppid=16989 vsize=1052880 CPUtime=0
/proc/16992/task/17000/stat : 17000 (java) S 16989 16989 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39421393 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356411419856 140356518576852 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16992/tid=17001] ppid=16989 vsize=1052880 CPUtime=0.15
/proc/16992/task/17001/stat : 17001 (java) S 16989 16989 32685 0 -1 4202560 27 0 0 0 15 0 0 0 20 0 11 0 39421393 1078149120 144860 18446744073709551615 4194304 4228012 140735694974064 140356410367360 140356518577739 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.88
Current children cumulated vsize (KiB) 1062052

[startup+294.701 s]
/proc/loadavg: 1.02 1.07 1.03 2/72 17010
/proc/meminfo: memFree=9796/1022884 swapFree=0/0
[pid=16989] ppid=16988 vsize=9172 CPUtime=0
/proc/16989/stat : 16989 (p2cudf-full-1.1) S 16988 16989 32685 0 -1 4202496 459 291 0 0 0 0 0 0 20 0 1 0 39421387 9392128 332 18446744073709551615 4194304 5129932 140736236450400 140736236448544 139671891711070 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16989/statm: 2293 332 280 229 0 53 0
[pid=16992] ppid=16989 vsize=0 CPUtime=290.98
/proc/16992/stat : 16992 (java) Z 16989 16989 32685 0 -1 4203532 150439 0 0 0 29034 64 0 0 20 0 2 0 39421388 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16992/statm: 0 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.98
Current children cumulated vsize (KiB) 9172

Child status: 0
Real time (s): 294.733
CPU time (s): 291.022
CPU user time (s): 290.346
CPU system time (s): 0.676042
CPU usage (%): 98.7408
Max. virtual memory (cumulated for all children) (KiB): 1064516

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 290.346
system time used= 0.676042
maximum resident set size= 579476
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 151203
page faults= 0
swaps= 0
block input operations= 34080
block output operations= 736
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7327
involuntary context switches= 13377

runsolver used 0.940058 second user time and 2.05213 second system time

The end