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/trendy-size/upgrade/easy/rand414.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/easy/rand414.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/upgrade/easy/rand414.cudf.result -count(removed),-sum(solution,installedsize),-notuptodate(solution),-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.40 1.36 1.20 2/59 3447
/proc/meminfo: memFree=487896/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=3152 CPUtime=0
/proc/3447/stat : 3447 (runsolver) R 3446 1733 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 119881535 3227648 33 18446744073709551615 134512640 134586868 4289878288 4289876336 4151735344 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 788 33 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.163154 s]
/proc/loadavg: 1.40 1.36 1.20 2/59 3447
/proc/meminfo: memFree=487896/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200307 s]
/proc/loadavg: 1.40 1.36 1.20 2/59 3447
/proc/meminfo: memFree=487896/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300311 s]
/proc/loadavg: 1.40 1.36 1.20 2/59 3447
/proc/meminfo: memFree=487896/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700232 s]
/proc/loadavg: 1.40 1.36 1.20 2/59 3447
/proc/meminfo: memFree=487896/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50074 s]
/proc/loadavg: 1.40 1.36 1.20 4/70 3459
/proc/meminfo: memFree=439068/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061228 CPUtime=1.47
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 33372 0 0 0 133 14 0 0 20 0 10 0 119881536 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265307 30006 2349 9 0 252657 0
[pid=3450/tid=3451] ppid=3447 vsize=1061228 CPUtime=0.68
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 14502 0 0 0 58 10 0 0 20 0 10 0 119881536 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140133038559904 140132936378907 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061228 CPUtime=0
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 119881539 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061228 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 119881540 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061228 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 119881540 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061228 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119881543 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061228 CPUtime=0.41
/proc/3450/task/3456/stat : 3456 (java) R 3447 3447 1733 0 -1 4202560 9125 0 0 0 40 1 0 0 20 0 10 0 119881543 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132926983320 140133016092832 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061228 CPUtime=0.36
/proc/3450/task/3457/stat : 3457 (java) R 3447 3447 1733 0 -1 4202560 8453 0 0 0 34 2 0 0 20 0 10 0 119881543 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132925928072 140133019488832 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061228 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119881543 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061228 CPUtime=0
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119881543 1086697472 30006 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 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) 1070396

[startup+3.10077 s]
/proc/loadavg: 1.40 1.36 1.20 4/70 3459
/proc/meminfo: memFree=335652/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1060104 CPUtime=3.05
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 75819 0 0 0 279 26 0 0 20 0 10 0 119881536 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265026 71144 2369 9 0 252376 0
[pid=3450/tid=3451] ppid=3447 vsize=1060104 CPUtime=1.54
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47411 0 0 0 134 20 0 0 20 0 10 0 119881536 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140133038559936 140132936378707 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1060104 CPUtime=0.16
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 7956 0 0 0 14 2 0 0 20 0 10 0 119881539 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119881540 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119881540 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119881543 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1060104 CPUtime=0.71
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 9975 0 0 0 70 1 0 0 20 0 10 0 119881543 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1060104 CPUtime=0.6
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9184 0 0 0 58 2 0 0 20 0 10 0 119881543 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119881543 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119881543 1085546496 71144 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 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) 1069272

[startup+6.30079 s]
/proc/loadavg: 1.37 1.35 1.20 3/70 3459
/proc/meminfo: memFree=42392/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1060104 CPUtime=6.21
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 113597 0 0 0 586 35 0 0 20 0 10 0 119881536 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265026 108527 2372 9 0 252376 0
[pid=3450/tid=3451] ppid=3447 vsize=1060104 CPUtime=3.21
/proc/3450/task/3451/stat : 3451 (java) S 3447 3447 1733 0 -1 4202560 47480 0 0 0 300 21 0 0 20 0 10 0 119881536 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140133038567712 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1060104 CPUtime=1.08
/proc/3450/task/3452/stat : 3452 (java) R 3447 3447 1733 0 -1 4202560 45536 0 0 0 99 9 0 0 20 0 10 0 119881539 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132931205648 140133018213161 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119881540 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119881540 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119881543 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1060104 CPUtime=0.99
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 10049 0 0 0 98 1 0 0 20 0 10 0 119881543 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1060104 CPUtime=0.88
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9239 0 0 0 86 2 0 0 20 0 10 0 119881543 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119881543 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1060104 CPUtime=0
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119881543 1085546496 108527 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.21
Current children cumulated vsize (KiB) 1069272

[startup+12.7008 s]
/proc/loadavg: 1.50 1.38 1.21 2/71 3460
/proc/meminfo: memFree=9648/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=12.47
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 126311 0 0 0 1205 42 0 0 20 0 11 0 119881536 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 119915 2381 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=6.57
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47795 0 0 0 633 24 0 0 20 0 11 0 119881536 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140133038567288 140132937425575 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=1.32
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 53197 0 0 0 120 12 0 0 20 0 11 0 119881539 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.31
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14668 0 0 0 228 3 0 0 20 0 11 0 119881543 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.23
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9352 0 0 0 221 2 0 0 20 0 11 0 119881543 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 119915 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.47
Current children cumulated vsize (KiB) 1070300

[startup+25.5008 s]
/proc/loadavg: 1.39 1.36 1.20 2/71 3460
/proc/meminfo: memFree=9648/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=25.1
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 126322 0 0 0 2467 43 0 0 20 0 11 0 119881536 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 119795 2381 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=19.04
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47797 0 0 0 1880 24 0 0 20 0 11 0 119881536 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140133038567448 140132937425004 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=1.33
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 53197 0 0 0 120 13 0 0 20 0 11 0 119881539 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.35
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14668 0 0 0 232 3 0 0 20 0 11 0 119881543 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.33
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 231 2 0 0 20 0 11 0 119881543 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.01
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 119881543 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 119795 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.1
Current children cumulated vsize (KiB) 1070300

[startup+51.1008 s]
/proc/loadavg: 1.26 1.33 1.20 2/71 3460
/proc/meminfo: memFree=9096/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=50.28
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 137391 0 0 0 4981 47 0 0 20 0 11 0 119881536 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 130860 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=42.01
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 4177 24 0 0 20 0 11 0 119881536 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140133038565576 140132937425004 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=3.16
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64144 0 0 0 300 16 0 0 20 0 11 0 119881539 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.71
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 267 4 0 0 20 0 11 0 119881543 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.35
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 233 2 0 0 20 0 11 0 119881543 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.02
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 119881543 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 130860 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.28
Current children cumulated vsize (KiB) 1070300

[startup+102.301 s]
/proc/loadavg: 1.21 1.31 1.19 2/71 3460
/proc/meminfo: memFree=10212/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=100.78
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 137752 0 0 0 10031 47 0 0 20 0 11 0 119881536 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131214 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=89.76
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 8952 24 0 0 20 0 11 0 119881536 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140133038559136 140132937425552 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=5.73
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64499 0 0 0 557 16 0 0 20 0 11 0 119881539 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.78
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 274 4 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.05
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 5 0 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.78
Current children cumulated vsize (KiB) 1070300

[startup+162.301 s]
/proc/loadavg: 1.08 1.25 1.18 2/71 3460
/proc/meminfo: memFree=10212/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=160.02
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 137757 0 0 0 15955 47 0 0 20 0 11 0 119881536 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131214 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=145.6
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 14536 24 0 0 20 0 11 0 119881536 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140133038567496 140132936882618 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=9.08
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64501 0 0 0 892 16 0 0 20 0 11 0 119881539 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.08
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 119881543 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131214 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.02
Current children cumulated vsize (KiB) 1070300

[startup+222.31 s]
/proc/loadavg: 1.03 1.20 1.17 2/71 3460
/proc/meminfo: memFree=10212/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=219.35
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 137820 0 0 0 21888 47 0 0 20 0 11 0 119881536 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131269 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=202.45
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 20221 24 0 0 20 0 11 0 119881536 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140133038567496 140132937416868 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=11.54
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64558 0 0 0 1138 16 0 0 20 0 11 0 119881539 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.12
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 18 0 0 0 12 0 0 0 20 0 11 0 119881543 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131269 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.35
Current children cumulated vsize (KiB) 1070300

[startup+282.301 s]
/proc/loadavg: 1.01 1.16 1.15 2/71 3460
/proc/meminfo: memFree=10336/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=278.51
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 138207 0 0 0 27803 48 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131649 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=259.53
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 25928 25 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140133038567496 140132937416868 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=13.58
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64939 0 0 0 1342 16 0 0 20 0 11 0 119881539 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.15
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 24 0 0 0 15 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.51
Current children cumulated vsize (KiB) 1070300

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

[startup+288.001 s]
/proc/loadavg: 1.01 1.16 1.15 2/71 3460
/proc/meminfo: memFree=10336/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=284.15
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 138208 0 0 0 28367 48 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131649 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=264.64
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 26439 25 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140133038567496 140132937425046 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=14.1
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64940 0 0 0 1394 16 0 0 20 0 11 0 119881539 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.15
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 24 0 0 0 15 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.15
Current children cumulated vsize (KiB) 1070300

[startup+291.201 s]
/proc/loadavg: 1.01 1.16 1.15 2/71 3460
/proc/meminfo: memFree=10336/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=287.32
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 138208 0 0 0 28684 48 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131649 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=267.81
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 26756 25 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140133038567496 140132937151151 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=14.1
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64940 0 0 0 1394 16 0 0 20 0 11 0 119881539 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.15
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 24 0 0 0 15 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.32
Current children cumulated vsize (KiB) 1070300

[startup+292.801 s]
/proc/loadavg: 1.01 1.16 1.15 2/71 3460
/proc/meminfo: memFree=10336/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=288.9
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 138208 0 0 0 28842 48 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131649 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=269.39
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 26914 25 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140133038567496 140132937416868 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=14.1
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64940 0 0 0 1394 16 0 0 20 0 11 0 119881539 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.15
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 24 0 0 0 15 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 288.9
Current children cumulated vsize (KiB) 1070300

[startup+294.401 s]
/proc/loadavg: 1.01 1.15 1.15 2/71 3460
/proc/meminfo: memFree=10336/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1061132 CPUtime=290.49
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 138208 0 0 0 29001 48 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265283 131649 2393 9 0 252633 0
[pid=3450/tid=3451] ppid=3447 vsize=1061132 CPUtime=270.97
/proc/3450/task/3451/stat : 3451 (java) R 3447 3447 1733 0 -1 4202560 47798 0 0 0 27072 25 0 0 20 0 11 0 119881536 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140133038567496 140132937424918 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1061132 CPUtime=14.1
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64940 0 0 0 1394 16 0 0 20 0 11 0 119881539 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1061132 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1061132 CPUtime=2.42
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 240 2 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1061132 CPUtime=0.15
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 24 0 0 0 15 0 0 0 20 0 11 0 119881543 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3460] ppid=3447 vsize=1061132 CPUtime=0
/proc/3450/task/3460/stat : 3460 (java) S 3447 3447 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119882526 1086599168 131649 18446744073709551615 4194304 4228012 140734992355648 140132922632032 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.49
Current children cumulated vsize (KiB) 1070300

[startup+295.201 s]
/proc/loadavg: 1.01 1.15 1.15 2/71 3460
/proc/meminfo: memFree=10336/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=1062160 CPUtime=291.03
/proc/3450/stat : 3450 (java) S 3447 3447 1733 0 -1 4202496 138223 0 0 0 29055 48 0 0 20 0 11 0 119881536 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140734992346672 140133032033909 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 265540 131662 2393 9 0 252890 0
[pid=3450/tid=3451] ppid=3447 vsize=1062160 CPUtime=271.5
/proc/3450/task/3451/stat : 3451 (java) S 3447 3447 1733 0 -1 4202560 47807 0 0 0 27125 25 0 0 20 0 11 0 119881536 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140133038568368 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=3450/tid=3452] ppid=3447 vsize=1062160 CPUtime=14.1
/proc/3450/task/3452/stat : 3452 (java) S 3447 3447 1733 0 -1 4202560 64940 0 0 0 1394 16 0 0 20 0 11 0 119881539 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132931206272 140133032048203 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3453] ppid=3447 vsize=1062160 CPUtime=0
/proc/3450/task/3453/stat : 3453 (java) S 3447 3447 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119881540 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132930152464 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3454] ppid=3447 vsize=1062160 CPUtime=0
/proc/3450/task/3454/stat : 3454 (java) S 3447 3447 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119881540 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132929099680 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3455] ppid=3447 vsize=1062160 CPUtime=0
/proc/3450/task/3455/stat : 3455 (java) S 3447 3447 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119881543 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132928048112 140133032055840 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3456] ppid=3447 vsize=1062160 CPUtime=2.79
/proc/3450/task/3456/stat : 3456 (java) S 3447 3447 1733 0 -1 4202560 14786 0 0 0 275 4 0 0 20 0 11 0 119881543 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132926995472 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3457] ppid=3447 vsize=1062160 CPUtime=2.44
/proc/3450/task/3457/stat : 3457 (java) S 3447 3447 1733 0 -1 4202560 9361 0 0 0 242 2 0 0 20 0 11 0 119881543 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132925942800 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3458] ppid=3447 vsize=1062160 CPUtime=0
/proc/3450/task/3458/stat : 3458 (java) S 3447 3447 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119881543 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132924890320 140133032047316 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3450/tid=3459] ppid=3447 vsize=1062160 CPUtime=0.16
/proc/3450/task/3459/stat : 3459 (java) S 3447 3447 1733 0 -1 4202560 24 0 0 0 16 0 0 0 20 0 11 0 119881543 1087651840 131662 18446744073709551615 4194304 4228012 140734992355648 140132923837824 140133032048203 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.03
Current children cumulated vsize (KiB) 1071328

[startup+295.606 s]
/proc/loadavg: 1.01 1.15 1.15 2/71 3460
/proc/meminfo: memFree=10336/1022884 swapFree=0/0
[pid=3447] ppid=3446 vsize=9168 CPUtime=0
/proc/3447/stat : 3447 (p2cudf-full-1.1) S 3446 3447 1733 0 -1 4202496 455 292 0 0 0 0 0 0 20 0 1 0 119881535 9388032 331 18446744073709551615 4194304 5129932 140734971175824 140734971173968 140256893445214 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3447/statm: 2292 331 280 229 0 52 0
[pid=3450] ppid=3447 vsize=0 CPUtime=291.17
/proc/3450/stat : 3450 (java) Z 3447 3447 1733 0 -1 4203532 138236 0 0 0 29065 52 0 0 20 0 2 0 119881536 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3450/statm: 0 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.17
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 295.639
CPU time (s): 291.226
CPU user time (s): 290.678
CPU system time (s): 0.548034
CPU usage (%): 98.5075
Max. virtual memory (cumulated for all children) (KiB): 1074656

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 290.678
system time used= 0.548034
maximum resident set size= 526692
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 138996
page faults= 0
swaps= 0
block input operations= 42368
block output operations= 528
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7189
involuntary context switches= 41549

runsolver used 0.992062 second user time and 2.05213 second system time

The end