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/6b0d1da0-c730-11df-a7c5-00163e3d3b7c.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/real/6b0d1da0-c730-11df-a7c5-00163e3d3b7c.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/upgrade/real/6b0d1da0-c730-11df-a7c5-00163e3d3b7c.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
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 0.93 1.04 1.00 2/56 16603
/proc/meminfo: memFree=597156/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9168 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) R 16602 16603 32685 0 -1 4202496 417 0 0 0 0 0 0 0 20 0 1 0 39270365 9388032 325 18446744073709551615 4194304 5129932 140735863195424 140735863192784 140403103394150 0 65538 16781316 65536 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2292 325 275 229 0 52 0

[startup+0.161461 s]
/proc/loadavg: 0.93 1.04 1.00 2/56 16603
/proc/meminfo: memFree=597156/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.200298 s]
/proc/loadavg: 0.93 1.04 1.00 2/56 16603
/proc/meminfo: memFree=597156/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.300302 s]
/proc/loadavg: 0.93 1.04 1.00 2/56 16603
/proc/meminfo: memFree=597156/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.700234 s]
/proc/loadavg: 0.93 1.04 1.00 2/56 16603
/proc/meminfo: memFree=597156/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+1.50069 s]
/proc/loadavg: 1.10 1.07 1.01 4/67 16615
/proc/meminfo: memFree=549816/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1046572 CPUtime=1.47
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 31714 0 0 0 132 15 0 0 20 0 10 0 39270366 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 261643 28195 2347 9 0 248993 0
[pid=16606/tid=16607] ppid=16603 vsize=1046572 CPUtime=0.67
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 13708 0 0 0 57 10 0 0 20 0 10 0 39270367 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731728671392 140731626143714 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1046572 CPUtime=0
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 39270370 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1046572 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 39270371 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1046572 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 39270371 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1046572 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1046572 CPUtime=0.39
/proc/16606/task/16612/stat : 16612 (java) R 16603 16603 32685 0 -1 4202560 8068 0 0 0 38 1 0 0 20 0 10 0 39270374 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731617094536 140731707872678 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1046572 CPUtime=0.38
/proc/16606/task/16613/stat : 16613 (java) R 16603 16603 32685 0 -1 4202560 8645 0 0 0 36 2 0 0 20 0 10 0 39270374 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731616042088 140731706207071 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1046572 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1046572 CPUtime=0
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1071689728 28195 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.47
Current children cumulated vsize (KiB) 1055744

[startup+3.10078 s]
/proc/loadavg: 1.10 1.07 1.01 3/67 16615
/proc/meminfo: memFree=454088/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1042344 CPUtime=3.05
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 76079 0 0 0 271 34 0 0 20 0 10 0 39270366 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 260586 70120 2367 9 0 247936 0
[pid=16606/tid=16607] ppid=16603 vsize=1042344 CPUtime=1.6
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47400 0 0 0 134 26 0 0 20 0 10 0 39270367 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731728671424 140731626541488 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1042344 CPUtime=0.18
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 8807 0 0 0 16 2 0 0 20 0 10 0 39270370 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 39270371 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 39270371 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1042344 CPUtime=0.62
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9434 0 0 0 60 2 0 0 20 0 10 0 39270374 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1042344 CPUtime=0.62
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 9146 0 0 0 59 3 0 0 20 0 10 0 39270374 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1067360256 70120 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.05
Current children cumulated vsize (KiB) 1051516

[startup+6.30079 s]
/proc/loadavg: 1.09 1.07 1.01 2/67 16615
/proc/meminfo: memFree=145080/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1042344 CPUtime=6.19
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 116622 0 0 0 570 49 0 0 20 0 10 0 39270366 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 260586 110274 2370 9 0 247936 0
[pid=16606/tid=16607] ppid=16603 vsize=1042344 CPUtime=3.3
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47469 0 0 0 302 28 0 0 20 0 10 0 39270367 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731728678608 140731626593309 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1042344 CPUtime=1.14
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 49202 0 0 0 100 14 0 0 20 0 10 0 39270370 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 39270371 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 39270371 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1042344 CPUtime=0.86
/proc/16606/task/16612/stat : 16612 (java) R 16603 16603 32685 0 -1 4202560 9454 0 0 0 84 2 0 0 20 0 10 0 39270374 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731617106704 140731706450081 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1042344 CPUtime=0.87
/proc/16606/task/16613/stat : 16613 (java) R 16603 16603 32685 0 -1 4202560 9205 0 0 0 84 3 0 0 20 0 10 0 39270374 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731616054032 140731704930108 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1042344 CPUtime=0
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39270374 1067360256 110274 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.19
Current children cumulated vsize (KiB) 1051516

[startup+12.7009 s]
/proc/loadavg: 1.08 1.07 1.01 3/68 16616
/proc/meminfo: memFree=111716/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1044420 CPUtime=12.49
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 124810 0 0 0 1195 54 0 0 20 0 11 0 39270366 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 261105 117862 2381 9 0 248455 0
[pid=16606/tid=16607] ppid=16603 vsize=1044420 CPUtime=6.03
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47799 0 0 0 574 29 0 0 20 0 11 0 39270367 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731728679000 140731627499437 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1044420 CPUtime=1.33
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 55735 0 0 0 116 17 0 0 20 0 11 0 39270370 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1044420 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1044420 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1044420 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1044420 CPUtime=2.63
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9775 0 0 0 260 3 0 0 20 0 11 0 39270374 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1044420 CPUtime=2.47
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10203 0 0 0 243 4 0 0 20 0 11 0 39270374 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1044420 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1044420 CPUtime=0
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1044420 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1069486080 117862 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.49
Current children cumulated vsize (KiB) 1053592

[startup+25.5006 s]
/proc/loadavg: 1.07 1.06 1.01 2/68 16616
/proc/meminfo: memFree=39548/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1041540 CPUtime=25.18
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 143472 0 0 0 2458 60 0 0 20 0 11 0 39270366 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 260385 135804 2393 9 0 247735 0
[pid=16606/tid=16607] ppid=16603 vsize=1041540 CPUtime=16.9
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 1661 29 0 0 20 0 11 0 39270367 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731728679096 140731627503958 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1041540 CPUtime=3.02
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 74388 0 0 0 280 22 0 0 20 0 11 0 39270370 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1041540 CPUtime=2.69
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9777 0 0 0 266 3 0 0 20 0 11 0 39270374 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1041540 CPUtime=2.53
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 249 4 0 0 20 0 11 0 39270374 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1041540 CPUtime=0.01
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 1 0 0 20 0 11 0 39270374 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1066536960 135804 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.18
Current children cumulated vsize (KiB) 1050712

[startup+51.1009 s]
/proc/loadavg: 1.04 1.06 1.01 2/68 16616
/proc/meminfo: memFree=10296/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1041540 CPUtime=50.46
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 154030 0 0 0 4983 63 0 0 20 0 11 0 39270366 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 260385 146358 2393 9 0 247735 0
[pid=16606/tid=16607] ppid=16603 vsize=1041540 CPUtime=38.69
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 3840 29 0 0 20 0 11 0 39270367 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731728678888 140731627054645 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1041540 CPUtime=6.47
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 84943 0 0 0 622 25 0 0 20 0 11 0 39270370 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1041540 CPUtime=2.71
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9777 0 0 0 268 3 0 0 20 0 11 0 39270374 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1041540 CPUtime=2.54
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 250 4 0 0 20 0 11 0 39270374 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1041540 CPUtime=0.02
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 39270374 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1066536960 146358 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.46
Current children cumulated vsize (KiB) 1050712

[startup+102.301 s]
/proc/loadavg: 1.02 1.05 1.00 2/72 16631
/proc/meminfo: memFree=10424/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1041540 CPUtime=100.98
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 160968 0 0 0 10032 66 0 0 20 0 11 0 39270366 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 260385 153288 2393 9 0 247735 0
[pid=16606/tid=16607] ppid=16603 vsize=1041540 CPUtime=85.71
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 8542 29 0 0 20 0 11 0 39270367 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731728678888 140731627673661 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1041540 CPUtime=9.94
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 91875 0 0 0 965 29 0 0 20 0 11 0 39270370 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1041540 CPUtime=2.71
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9777 0 0 0 268 3 0 0 20 0 11 0 39270374 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1041540 CPUtime=2.54
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 250 4 0 0 20 0 11 0 39270374 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1041540 CPUtime=0.05
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 9 0 0 0 0 5 0 0 20 0 11 0 39270374 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1066536960 153288 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.98
Current children cumulated vsize (KiB) 1050712

[startup+162.301 s]
/proc/loadavg: 1.00 1.03 1.00 2/72 16631
/proc/meminfo: memFree=9096/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1041540 CPUtime=160.28
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 168470 0 0 0 15957 71 0 0 20 0 11 0 39270366 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 260385 160782 2393 9 0 247735 0
[pid=16606/tid=16607] ppid=16603 vsize=1041540 CPUtime=141.29
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 14099 30 0 0 20 0 11 0 39270367 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731728678888 140731627504474 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1041540 CPUtime=13.58
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 99371 0 0 0 1326 32 0 0 20 0 11 0 39270370 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1041540 CPUtime=2.71
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9777 0 0 0 268 3 0 0 20 0 11 0 39270374 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1041540 CPUtime=2.58
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 254 4 0 0 20 0 11 0 39270374 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1041540 CPUtime=0.08
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 15 0 0 0 0 8 0 0 20 0 11 0 39270374 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1066536960 160782 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.28
Current children cumulated vsize (KiB) 1050712

[startup+222.304 s]
/proc/loadavg: 1.00 1.03 1.00 2/72 16631
/proc/meminfo: memFree=9348/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1049196 CPUtime=219.7
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 198985 0 0 0 21886 84 0 0 20 0 11 0 39270366 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 262299 185585 2393 9 0 249649 0
[pid=16606/tid=16607] ppid=16603 vsize=1049196 CPUtime=196.12
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 19582 30 0 0 20 0 11 0 39270367 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731728678888 140731627715214 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1049196 CPUtime=18.11
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 129878 0 0 0 1766 45 0 0 20 0 11 0 39270370 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1049196 CPUtime=2.73
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9779 0 0 0 270 3 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1049196 CPUtime=2.58
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 254 4 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1049196 CPUtime=0.11
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 21 0 0 0 0 11 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.7
Current children cumulated vsize (KiB) 1058368

[startup+282.301 s]
/proc/loadavg: 1.00 1.02 1.00 2/72 16631
/proc/meminfo: memFree=9348/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1049196 CPUtime=279.19
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 214200 0 0 0 27829 90 0 0 20 0 11 0 39270366 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 262299 185585 2393 9 0 249649 0
[pid=16606/tid=16607] ppid=16603 vsize=1049196 CPUtime=250.65
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 25035 30 0 0 20 0 11 0 39270367 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731728678888 140731627503942 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1049196 CPUtime=23.04
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 145087 0 0 0 2253 51 0 0 20 0 11 0 39270370 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1049196 CPUtime=2.73
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9779 0 0 0 270 3 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1049196 CPUtime=2.58
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 254 4 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1049196 CPUtime=0.14
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 27 0 0 0 0 14 0 0 20 0 11 0 39270374 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1074376704 185585 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 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) 1058368

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

[startup+288.001 s]
/proc/loadavg: 1.00 1.02 1.00 2/72 16631
/proc/meminfo: memFree=9348/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1041540 CPUtime=284.84
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 216103 0 0 0 28393 91 0 0 20 0 11 0 39270366 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 260385 183685 2393 9 0 247735 0
[pid=16606/tid=16607] ppid=16603 vsize=1041540 CPUtime=255.55
/proc/16606/task/16607/stat : 16607 (java) S 16603 16603 32685 0 -1 4202560 47802 0 0 0 25525 30 0 0 20 0 11 0 39270367 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731728678688 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1041540 CPUtime=23.8
/proc/16606/task/16608/stat : 16608 (java) R 16603 16603 32685 0 -1 4202560 146990 0 0 0 2327 53 0 0 20 0 11 0 39270370 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731621316456 140731706507629 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1041540 CPUtime=2.73
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9779 0 0 0 270 3 0 0 20 0 11 0 39270374 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1041540 CPUtime=2.58
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 254 4 0 0 20 0 11 0 39270374 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1041540 CPUtime=0.15
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 27 0 0 0 0 15 0 0 20 0 11 0 39270374 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1041540 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1066536960 183685 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 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) 1050712

[startup+291.201 s]
/proc/loadavg: 1.00 1.02 1.00 2/72 16631
/proc/meminfo: memFree=9348/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1049196 CPUtime=288.01
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 218004 0 0 0 28710 91 0 0 20 0 11 0 39270366 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 262299 185586 2393 9 0 249649 0
[pid=16606/tid=16607] ppid=16603 vsize=1049196 CPUtime=258.27
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 25797 30 0 0 20 0 11 0 39270367 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731728678888 140731626470202 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1049196 CPUtime=24.25
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 148891 0 0 0 2372 53 0 0 20 0 11 0 39270370 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1049196 CPUtime=2.73
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9779 0 0 0 270 3 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1049196 CPUtime=2.58
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 254 4 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1049196 CPUtime=0.15
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 27 0 0 0 0 15 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 288.01
Current children cumulated vsize (KiB) 1058368

[startup+294.401 s]
/proc/loadavg: 1.00 1.02 1.00 2/72 16631
/proc/meminfo: memFree=9348/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1049196 CPUtime=291.18
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 218004 0 0 0 29027 91 0 0 20 0 11 0 39270366 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 262299 185586 2393 9 0 249649 0
[pid=16606/tid=16607] ppid=16603 vsize=1049196 CPUtime=261.44
/proc/16606/task/16607/stat : 16607 (java) R 16603 16603 32685 0 -1 4202560 47802 0 0 0 26114 30 0 0 20 0 11 0 39270367 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731728678888 140731626916283 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1049196 CPUtime=24.25
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 148891 0 0 0 2372 53 0 0 20 0 11 0 39270370 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1049196 CPUtime=2.73
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9779 0 0 0 270 3 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1049196 CPUtime=2.58
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 254 4 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1049196 CPUtime=0.15
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 27 0 0 0 0 15 0 0 20 0 11 0 39270374 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16616] ppid=16603 vsize=1049196 CPUtime=0
/proc/16606/task/16616/stat : 16616 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39271441 1074376704 185586 18446744073709551615 4194304 4228012 140735544612192 140731612747616 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.18
Current children cumulated vsize (KiB) 1058368

[startup+296.005 s]
/proc/loadavg: 1.00 1.02 1.00 1/72 16632
/proc/meminfo: memFree=9224/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1050224 CPUtime=292.58
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 218018 0 0 0 29167 91 0 0 20 0 11 0 39270366 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 262556 185598 2393 9 0 249906 0
[pid=16606/tid=16607] ppid=16603 vsize=1050224 CPUtime=262.81
/proc/16606/task/16607/stat : 16607 (java) S 16603 16603 32685 0 -1 4202560 47809 0 0 0 26251 30 0 0 20 0 11 0 39270367 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731728679856 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1050224 CPUtime=24.25
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 148891 0 0 0 2372 53 0 0 20 0 11 0 39270370 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1050224 CPUtime=2.75
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9780 0 0 0 272 3 0 0 20 0 11 0 39270374 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1050224 CPUtime=2.59
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10207 0 0 0 255 4 0 0 20 0 11 0 39270374 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1050224 CPUtime=0.15
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 27 0 0 0 0 15 0 0 20 0 11 0 39270374 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16632] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16632/stat : 16632 (java) S 16603 16603 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39299946 1075429376 185598 18446744073709551615 4194304 4228012 140735544612192 140731611695280 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.58
Current children cumulated vsize (KiB) 1059396

[startup+296.401 s]
/proc/loadavg: 1.00 1.02 1.00 1/72 16632
/proc/meminfo: memFree=9224/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1050224 CPUtime=292.67
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 218022 0 0 0 29173 94 0 0 20 0 11 0 39270366 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 262556 185602 2393 9 0 249906 0
[pid=16606/tid=16607] ppid=16603 vsize=1050224 CPUtime=262.81
/proc/16606/task/16607/stat : 16607 (java) S 16603 16603 32685 0 -1 4202560 47809 0 0 0 26251 30 0 0 20 0 11 0 39270367 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731728679856 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1050224 CPUtime=24.25
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 148891 0 0 0 2372 53 0 0 20 0 11 0 39270370 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1050224 CPUtime=2.75
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9780 0 0 0 272 3 0 0 20 0 11 0 39270374 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1050224 CPUtime=2.6
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10208 0 0 0 256 4 0 0 20 0 11 0 39270374 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1050224 CPUtime=0.15
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 27 0 0 0 0 15 0 0 20 0 11 0 39270374 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16632] ppid=16603 vsize=1050224 CPUtime=0.07
/proc/16606/task/16632/stat : 16632 (java) R 16603 16603 32685 0 -1 4202560 9 0 0 0 4 3 0 0 20 0 11 0 39299946 1075429376 185602 18446744073709551615 4194304 4228012 140735544612192 140731611686192 140731626157492 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.67
Current children cumulated vsize (KiB) 1059396

[startup+296.601 s]
/proc/loadavg: 1.00 1.02 1.00 1/72 16632
/proc/meminfo: memFree=9224/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=1050224 CPUtime=292.87
/proc/16606/stat : 16606 (java) S 16603 16603 32685 0 -1 4202496 218035 0 0 0 29189 98 0 0 20 0 11 0 39270366 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140735544603216 140731722145397 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 262556 185615 2394 9 0 249906 0
[pid=16606/tid=16607] ppid=16603 vsize=1050224 CPUtime=262.81
/proc/16606/task/16607/stat : 16607 (java) S 16603 16603 32685 0 -1 4202560 47809 0 0 0 26251 30 0 0 20 0 11 0 39270367 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731728679856 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=16606/tid=16608] ppid=16603 vsize=1050224 CPUtime=24.25
/proc/16606/task/16608/stat : 16608 (java) S 16603 16603 32685 0 -1 4202560 148891 0 0 0 2372 53 0 0 20 0 11 0 39270370 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731621317760 140731722159691 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16609] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16609/stat : 16609 (java) S 16603 16603 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39270371 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731620263952 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16610] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16610/stat : 16610 (java) S 16603 16603 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39270371 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731619211168 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16611] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16611/stat : 16611 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731618159600 140731722167328 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16612] ppid=16603 vsize=1050224 CPUtime=2.79
/proc/16606/task/16612/stat : 16612 (java) S 16603 16603 32685 0 -1 4202560 9790 0 0 0 276 3 0 0 20 0 11 0 39270374 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731617106960 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16613] ppid=16603 vsize=1050224 CPUtime=2.64
/proc/16606/task/16613/stat : 16613 (java) S 16603 16603 32685 0 -1 4202560 10211 0 0 0 260 4 0 0 20 0 11 0 39270374 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731616054288 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16614] ppid=16603 vsize=1050224 CPUtime=0
/proc/16606/task/16614/stat : 16614 (java) S 16603 16603 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39270374 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731615001808 140731722158804 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16606/tid=16615] ppid=16603 vsize=1050224 CPUtime=0.15
/proc/16606/task/16615/stat : 16615 (java) S 16603 16603 32685 0 -1 4202560 27 0 0 0 0 15 0 0 20 0 11 0 39270374 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731613949312 140731722159691 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=16606/tid=16632] ppid=16603 vsize=1050224 CPUtime=0.19
/proc/16606/task/16632/stat : 16632 (java) R 16603 16603 32685 0 -1 4202560 9 0 0 0 12 7 0 0 20 0 11 0 39299946 1075429376 185615 18446744073709551615 4194304 4228012 140735544612192 140731611686864 140731722170669 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.87
Current children cumulated vsize (KiB) 1059396

[startup+296.701 s]
/proc/loadavg: 1.00 1.02 1.00 1/72 16632
/proc/meminfo: memFree=9224/1022884 swapFree=0/0
[pid=16603] ppid=16602 vsize=9172 CPUtime=0
/proc/16603/stat : 16603 (p2cudf-full-1.1) S 16602 16603 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39270365 9392128 331 18446744073709551615 4194304 5129932 140735863195424 140735863193568 140403103392862 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16603/statm: 2293 331 280 229 0 53 0
[pid=16606] ppid=16603 vsize=0 CPUtime=292.97
/proc/16606/stat : 16606 (java) Z 16603 16603 32685 0 -1 4203532 218046 0 0 0 29193 104 0 0 20 0 2 0 39270366 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16606/statm: 0 0 0 0 0 0 0
[pid=16606/tid=16608] ppid=16603 vsize=0 CPUtime=24.33
/proc/16606/task/16608/stat : 16608 (java) R 16603 16603 32685 0 -1 4202564 148894 0 0 0 2376 57 0 0 20 0 2 0 39270370 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.97
Current children cumulated vsize (KiB) 9172

Child status: 0
Real time (s): 296.712
CPU time (s): 292.998
CPU user time (s): 291.93
CPU system time (s): 1.06807
CPU usage (%): 98.7486
Max. virtual memory (cumulated for all children) (KiB): 1059396

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 291.93
system time used= 1.06807
maximum resident set size= 742492
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 218809
page faults= 0
swaps= 0
block input operations= 43848
block output operations= 792
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7380
involuntary context switches= 13392

runsolver used 0.904056 second user time and 1.99612 second system time

The end