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/2c3aece6-c8b2-11df-a040-00163e3d3b7c.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/real/2c3aece6-c8b2-11df-a040-00163e3d3b7c.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/upgrade/real/2c3aece6-c8b2-11df-a040-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

[startup+0 s]
/proc/loadavg: 1.05 1.02 0.99 2/61 16160
/proc/meminfo: memFree=569568/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=3152 CPUtime=0
/proc/16160/stat : 16160 (runsolver) R 16159 32685 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 39151781 3227648 33 18446744073709551615 134512640 134586868 4287656176 4287654224 4151534640 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 788 33 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.193584 s]
/proc/loadavg: 1.05 1.02 0.99 2/61 16160
/proc/meminfo: memFree=569568/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.200327 s]
/proc/loadavg: 1.05 1.02 0.99 2/61 16160
/proc/meminfo: memFree=569568/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.300303 s]
/proc/loadavg: 1.05 1.02 0.99 2/61 16160
/proc/meminfo: memFree=569568/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.700232 s]
/proc/loadavg: 1.05 1.02 0.99 2/61 16160
/proc/meminfo: memFree=569568/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+1.50076 s]
/proc/loadavg: 1.04 1.02 0.99 4/72 16172
/proc/meminfo: memFree=522600/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1045444 CPUtime=1.44
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 31540 0 0 0 131 13 0 0 20 0 10 0 39151782 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 261361 27906 2348 9 0 248711 0
[pid=16163/tid=16164] ppid=16160 vsize=1045444 CPUtime=0.63
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 13590 0 0 0 57 6 0 0 20 0 10 0 39151782 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844871824032 139844769618486 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1045444 CPUtime=0
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 39151784 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1045444 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 39151785 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1045444 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 39151785 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1045444 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1045444 CPUtime=0.4
/proc/16163/task/16169/stat : 16169 (java) R 16160 16160 32685 0 -1 4202560 9546 0 0 0 38 2 0 0 20 0 10 0 39151787 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844760247448 139844851143680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1045444 CPUtime=0.37
/proc/16163/task/16170/stat : 16170 (java) R 16160 16160 32685 0 -1 4202560 7113 0 0 0 34 3 0 0 20 0 10 0 39151787 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844759194456 139844851984100 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1045444 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1045444 CPUtime=0
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1070534656 27906 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 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) 1054616

[startup+3.10079 s]
/proc/loadavg: 1.04 1.02 0.99 3/72 16172
/proc/meminfo: memFree=424640/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1045124 CPUtime=3.02
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 75244 0 0 0 273 29 0 0 20 0 10 0 39151782 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 261281 70320 2368 9 0 248631 0
[pid=16163/tid=16164] ppid=16160 vsize=1045124 CPUtime=1.56
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47405 0 0 0 136 20 0 0 20 0 10 0 39151782 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844871824064 139844769687178 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1045124 CPUtime=0.18
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 8815 0 0 0 16 2 0 0 20 0 10 0 39151784 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1045124 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 39151785 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1045124 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 39151785 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1045124 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1045124 CPUtime=0.65
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10144 0 0 0 62 3 0 0 20 0 10 0 39151787 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1045124 CPUtime=0.59
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 7589 0 0 0 56 3 0 0 20 0 10 0 39151787 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1045124 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1045124 CPUtime=0
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1070206976 70320 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.02
Current children cumulated vsize (KiB) 1054296

[startup+6.30085 s]
/proc/loadavg: 1.12 1.03 1.00 4/72 16172
/proc/meminfo: memFree=157296/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1036320 CPUtime=6.17
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 107168 0 0 0 577 40 0 0 20 0 10 0 39151782 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 259080 100129 2372 9 0 246430 0
[pid=16163/tid=16164] ppid=16160 vsize=1036320 CPUtime=3.22
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47475 0 0 0 302 20 0 0 20 0 10 0 39151782 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844871832536 139844770119140 0 4 0 16800975 0 0 0 -1 0 0 0 5 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1036320 CPUtime=0.88
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 40426 0 0 0 75 13 0 0 20 0 10 0 39151784 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1036320 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 39151785 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1036320 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 39151785 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1036320 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1036320 CPUtime=1.04
/proc/16163/task/16169/stat : 16169 (java) R 16160 16160 32685 0 -1 4202560 10213 0 0 0 101 3 0 0 20 0 10 0 39151787 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844760259344 139844850871416 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1036320 CPUtime=0.99
/proc/16163/task/16170/stat : 16170 (java) R 16160 16160 32685 0 -1 4202560 7763 0 0 0 95 4 0 0 20 0 10 0 39151787 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844759206672 139844847206072 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1036320 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1036320 CPUtime=0
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 39151787 1061191680 100129 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.17
Current children cumulated vsize (KiB) 1045492

[startup+12.7009 s]
/proc/loadavg: 1.11 1.03 1.00 2/73 16173
/proc/meminfo: memFree=116616/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=12.49
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 119382 0 0 0 1202 47 0 0 20 0 11 0 39151782 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 110142 2379 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=6.37
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47801 0 0 0 617 20 0 0 20 0 11 0 39151782 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844871831752 139844770683854 0 4 0 16800975 0 0 0 -1 0 0 0 5 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=1.12
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 48851 0 0 0 96 16 0 0 20 0 11 0 39151784 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.56
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10335 0 0 0 253 3 0 0 20 0 11 0 39151787 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.4
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11098 0 0 0 234 6 0 0 20 0 11 0 39151787 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 110142 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 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) 1050640

[startup+25.5009 s]
/proc/loadavg: 1.22 1.06 1.00 2/73 16181
/proc/meminfo: memFree=35768/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=25.09
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 139443 0 0 0 2453 56 0 0 20 0 11 0 39151782 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 130203 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=16.47
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 1626 21 0 0 20 0 11 0 39151782 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770871187 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=2.97
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 68871 0 0 0 273 24 0 0 20 0 11 0 39151784 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.87
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10352 0 0 0 283 4 0 0 20 0 11 0 39151787 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.74
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11117 0 0 0 268 6 0 0 20 0 11 0 39151787 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.01
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 1 0 0 20 0 11 0 39151787 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 130203 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.09
Current children cumulated vsize (KiB) 1050640

[startup+51.1009 s]
/proc/loadavg: 1.14 1.05 1.00 2/73 16181
/proc/meminfo: memFree=23740/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=50.37
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 142378 0 0 0 4980 57 0 0 20 0 11 0 39151782 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 133134 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=39.14
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 3893 21 0 0 20 0 11 0 39151782 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770868224 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=5.51
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 71800 0 0 0 525 26 0 0 20 0 11 0 39151784 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.9
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10353 0 0 0 286 4 0 0 20 0 11 0 39151787 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.77
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11119 0 0 0 271 6 0 0 20 0 11 0 39151787 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.02
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 39151787 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 133134 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.37
Current children cumulated vsize (KiB) 1050640

[startup+102.301 s]
/proc/loadavg: 1.11 1.06 1.00 2/73 16181
/proc/meminfo: memFree=10352/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=100.93
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 148372 0 0 0 10033 60 0 0 20 0 11 0 39151782 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 139124 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=85.65
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 8544 21 0 0 20 0 11 0 39151782 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770619439 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=9.53
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 77791 0 0 0 924 29 0 0 20 0 11 0 39151784 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.9
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10353 0 0 0 286 4 0 0 20 0 11 0 39151787 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.77
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11119 0 0 0 271 6 0 0 20 0 11 0 39151787 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.05
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 5 0 0 20 0 11 0 39151787 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 139124 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.93
Current children cumulated vsize (KiB) 1050640

[startup+162.301 s]
/proc/loadavg: 1.04 1.05 1.00 2/73 16181
/proc/meminfo: memFree=10236/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=160.26
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 160612 0 0 0 15960 66 0 0 20 0 11 0 39151782 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 151356 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=141.14
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 14093 21 0 0 20 0 11 0 39151782 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770844962 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=13.31
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 90021 0 0 0 1297 34 0 0 20 0 11 0 39151784 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.9
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10354 0 0 0 286 4 0 0 20 0 11 0 39151787 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.78
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11122 0 0 0 272 6 0 0 20 0 11 0 39151787 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.08
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 12 0 0 0 0 8 0 0 20 0 11 0 39151787 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 151356 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.26
Current children cumulated vsize (KiB) 1050640

[startup+222.304 s]
/proc/loadavg: 1.06 1.05 1.00 2/72 16181
/proc/meminfo: memFree=10056/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=219.71
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 166225 0 0 0 21903 68 0 0 20 0 11 0 39151782 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 156961 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=197.96
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 19775 21 0 0 20 0 11 0 39151782 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770318076 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=15.92
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 95628 0 0 0 1555 37 0 0 20 0 11 0 39151784 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.9
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10354 0 0 0 286 4 0 0 20 0 11 0 39151787 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.78
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11122 0 0 0 272 6 0 0 20 0 11 0 39151787 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.11
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 18 0 0 0 0 11 0 0 20 0 11 0 39151787 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 156961 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.71
Current children cumulated vsize (KiB) 1050640

[startup+282.301 s]
/proc/loadavg: 1.02 1.04 1.00 2/72 16181
/proc/meminfo: memFree=10068/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=279.16
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 173762 0 0 0 27844 72 0 0 20 0 11 0 39151782 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 164491 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=255.22
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 25501 21 0 0 20 0 11 0 39151782 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770683411 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=18.05
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 103156 0 0 0 1765 40 0 0 20 0 11 0 39151784 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.92
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10357 0 0 0 288 4 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.78
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11122 0 0 0 272 6 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.14
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 24 0 0 0 0 14 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 279.16
Current children cumulated vsize (KiB) 1050640

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

[startup+288.001 s]
/proc/loadavg: 1.02 1.04 1.00 2/72 16181
/proc/meminfo: memFree=10068/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=284.8
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 173763 0 0 0 28408 72 0 0 20 0 11 0 39151782 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 164491 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=260.54
/proc/16163/task/16164/stat : 16164 (java) S 16160 16160 32685 0 -1 4202560 47806 0 0 0 26033 21 0 0 20 0 11 0 39151782 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844871831328 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=18.38
/proc/16163/task/16165/stat : 16165 (java) R 16160 16160 32685 0 -1 4202560 103157 0 0 0 1798 40 0 0 20 0 11 0 39151784 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844764469360 139844852168340 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.92
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10357 0 0 0 288 4 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.78
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11122 0 0 0 272 6 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.14
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 24 0 0 0 0 14 0 0 20 0 11 0 39151787 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 164491 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.8
Current children cumulated vsize (KiB) 1050640

[startup+291.201 s]
/proc/loadavg: 1.02 1.04 1.00 2/72 16181
/proc/meminfo: memFree=10060/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=287.96
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 175310 0 0 0 28724 72 0 0 20 0 11 0 39151782 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 166038 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=263.48
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 26327 21 0 0 20 0 11 0 39151782 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770318059 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=18.6
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 104704 0 0 0 1820 40 0 0 20 0 11 0 39151784 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.92
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10357 0 0 0 288 4 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.78
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11122 0 0 0 272 6 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.14
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 24 0 0 0 0 14 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.96
Current children cumulated vsize (KiB) 1050640

[startup+294.401 s]
/proc/loadavg: 1.02 1.04 1.00 2/72 16181
/proc/meminfo: memFree=10060/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1041468 CPUtime=291.14
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 175310 0 0 0 29042 72 0 0 20 0 11 0 39151782 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260367 166038 2393 9 0 247717 0
[pid=16163/tid=16164] ppid=16160 vsize=1041468 CPUtime=266.65
/proc/16163/task/16164/stat : 16164 (java) R 16160 16160 32685 0 -1 4202560 47806 0 0 0 26644 21 0 0 20 0 11 0 39151782 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844871831528 139844770844941 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1041468 CPUtime=18.6
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 104704 0 0 0 1820 40 0 0 20 0 11 0 39151784 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1041468 CPUtime=2.92
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10357 0 0 0 288 4 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1041468 CPUtime=2.78
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11122 0 0 0 272 6 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1041468 CPUtime=0.15
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 24 0 0 0 0 15 0 0 20 0 11 0 39151787 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16173] ppid=16160 vsize=1041468 CPUtime=0
/proc/16163/task/16173/stat : 16173 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39152857 1066463232 166038 18446744073709551615 4194304 4228012 140734448038592 139844755900256 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.14
Current children cumulated vsize (KiB) 1050640

[startup+296.006 s]
/proc/loadavg: 1.02 1.04 1.00 1/72 16182
/proc/meminfo: memFree=10060/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1042496 CPUtime=292.54
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 175325 0 0 0 29182 72 0 0 20 0 11 0 39151782 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260624 166051 2393 9 0 247974 0
[pid=16163/tid=16164] ppid=16160 vsize=1042496 CPUtime=268.03
/proc/16163/task/16164/stat : 16164 (java) S 16160 16160 32685 0 -1 4202560 47815 0 0 0 26782 21 0 0 20 0 11 0 39151782 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844871832496 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1042496 CPUtime=18.6
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 104704 0 0 0 1820 40 0 0 20 0 11 0 39151784 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1042496 CPUtime=2.93
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10357 0 0 0 289 4 0 0 20 0 11 0 39151787 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1042496 CPUtime=2.8
/proc/16163/task/16170/stat : 16170 (java) S 16160 16160 32685 0 -1 4202560 11122 0 0 0 274 6 0 0 20 0 11 0 39151787 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844759206928 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1042496 CPUtime=0.15
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 24 0 0 0 0 15 0 0 20 0 11 0 39151787 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16182] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16182/stat : 16182 (java) S 16160 16160 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 39181363 1067515904 166051 18446744073709551615 4194304 4228012 140734448038592 139844754847920 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.54
Current children cumulated vsize (KiB) 1051668

[startup+296.401 s]
/proc/loadavg: 1.02 1.04 1.00 1/72 16182
/proc/meminfo: memFree=10060/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=1042496 CPUtime=292.63
/proc/16163/stat : 16163 (java) S 16160 16160 32685 0 -1 4202496 175328 0 0 0 29187 76 0 0 20 0 11 0 39151782 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 140734448029616 139844865298037 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 260624 166054 2393 9 0 247974 0
[pid=16163/tid=16164] ppid=16160 vsize=1042496 CPUtime=268.03
/proc/16163/task/16164/stat : 16164 (java) S 16160 16160 32685 0 -1 4202560 47815 0 0 0 26782 21 0 0 20 0 11 0 39151782 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844871832496 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=16163/tid=16165] ppid=16160 vsize=1042496 CPUtime=18.6
/proc/16163/task/16165/stat : 16165 (java) S 16160 16160 32685 0 -1 4202560 104704 0 0 0 1820 40 0 0 20 0 11 0 39151784 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844764470400 139844865312331 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16166] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16166/stat : 16166 (java) S 16160 16160 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 39151785 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844763416592 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16167] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16167/stat : 16167 (java) S 16160 16160 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 39151785 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844762363808 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16168] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16168/stat : 16168 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844761312240 139844865319968 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16169] ppid=16160 vsize=1042496 CPUtime=2.93
/proc/16163/task/16169/stat : 16169 (java) S 16160 16160 32685 0 -1 4202560 10357 0 0 0 289 4 0 0 20 0 11 0 39151787 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844760259600 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16170] ppid=16160 vsize=1042496 CPUtime=2.8
/proc/16163/task/16170/stat : 16170 (java) R 16160 16160 32685 0 -1 4202560 11122 0 0 0 274 6 0 0 20 0 11 0 39151787 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844759206672 139844851997566 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16171] ppid=16160 vsize=1042496 CPUtime=0
/proc/16163/task/16171/stat : 16171 (java) S 16160 16160 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 39151787 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844758154448 139844865311444 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16172] ppid=16160 vsize=1042496 CPUtime=0.15
/proc/16163/task/16172/stat : 16172 (java) S 16160 16160 32685 0 -1 4202560 24 0 0 0 0 15 0 0 20 0 11 0 39151787 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844757101952 139844865312331 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=16163/tid=16182] ppid=16160 vsize=1042496 CPUtime=0.06
/proc/16163/task/16182/stat : 16182 (java) R 16160 16160 32685 0 -1 4202560 9 0 0 0 2 4 0 0 20 0 11 0 39181363 1067515904 166054 18446744073709551615 4194304 4228012 140734448038592 139844754838832 139844769293831 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.63
Current children cumulated vsize (KiB) 1051668

[startup+296.601 s]
/proc/loadavg: 1.02 1.04 1.00 1/72 16182
/proc/meminfo: memFree=10060/1022884 swapFree=0/0
[pid=16160] ppid=16159 vsize=9172 CPUtime=0
/proc/16160/stat : 16160 (p2cudf-full-1.1) S 16159 16160 32685 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 39151781 9392128 332 18446744073709551615 4194304 5129932 140734479406512 140734479404656 139926378034270 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/16160/statm: 2293 332 280 229 0 53 0
[pid=16163] ppid=16160 vsize=0 CPUtime=292.82
/proc/16163/stat : 16163 (java) Z 16160 16160 32685 0 -1 4203532 175352 0 0 0 29200 82 0 0 20 0 2 0 39151782 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/16163/statm: 0 0 0 0 0 0 0
[pid=16163/tid=16182] ppid=16160 vsize=0 CPUtime=0.2
/proc/16163/task/16182/stat : 16182 (java) R 16160 16160 32685 0 -1 4203588 10 0 0 0 9 11 0 0 20 0 2 0 39181363 0 0 18446744073709551615 0 0 0 0 0 0 4 0 16800975 18446744073709551615 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.82
Current children cumulated vsize (KiB) 9172

Child status: 0
Real time (s): 296.631
CPU time (s): 292.874
CPU user time (s): 292.022
CPU system time (s): 0.852053
CPU usage (%): 98.7336
Max. virtual memory (cumulated for all children) (KiB): 1058776

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 292.022
system time used= 0.852053
maximum resident set size= 664304
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 176115
page faults= 0
swaps= 0
block input operations= 40784
block output operations= 800
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7240
involuntary context switches= 13229

runsolver used 0.900056 second user time and 2.09613 second system time

The end