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/install/rand411.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/install/rand411.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/install/rand411.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.01 1.05 1.00 2/59 18246
/proc/meminfo: memFree=622132/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=3152 CPUtime=0
/proc/18246/stat : 18246 (runsolver) R 18245 1750 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 119775034 3227648 32 18446744073709551615 134512640 134586868 4290981200 4290979248 4151952432 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.119939 s]
/proc/loadavg: 1.01 1.05 1.00 2/59 18246
/proc/meminfo: memFree=622132/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200286 s]
/proc/loadavg: 1.01 1.05 1.00 2/59 18246
/proc/meminfo: memFree=622132/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300327 s]
/proc/loadavg: 1.01 1.05 1.00 2/59 18246
/proc/meminfo: memFree=622132/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700238 s]
/proc/loadavg: 1.01 1.05 1.00 2/59 18246
/proc/meminfo: memFree=622132/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50078 s]
/proc/loadavg: 1.01 1.05 1.00 4/70 18258
/proc/meminfo: memFree=570204/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1049360 CPUtime=1.46
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 33477 0 0 0 131 15 0 0 20 0 10 0 119775034 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262340 30151 2346 9 0 249690 0
[pid=18249/tid=18250] ppid=18246 vsize=1049360 CPUtime=0.66
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 14355 0 0 0 58 8 0 0 20 0 10 0 119775035 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649119814304 139649017702356 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1049360 CPUtime=0
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 119775037 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1049360 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 119775037 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1049360 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 119775037 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1049360 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119775040 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1049360 CPUtime=0.39
/proc/18249/task/18255/stat : 18255 (java) R 18246 18246 1750 0 -1 4202560 8853 0 0 0 37 2 0 0 20 0 10 0 119775040 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649008237720 139649095131392 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1049360 CPUtime=0.38
/proc/18249/task/18256/stat : 18256 (java) R 18246 18246 1750 0 -1 4202560 8980 0 0 0 33 5 0 0 20 0 10 0 119775040 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649007184952 139649099120363 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1049360 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119775040 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1049360 CPUtime=0
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119775040 1074544640 30151 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.46
Current children cumulated vsize (KiB) 1058528

[startup+3.10079 s]
/proc/loadavg: 1.17 1.08 1.01 4/70 18258
/proc/meminfo: memFree=471004/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1047724 CPUtime=3.03
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 75776 0 0 0 272 31 0 0 20 0 10 0 119775034 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 261931 71370 2366 9 0 249281 0
[pid=18249/tid=18250] ppid=18246 vsize=1047724 CPUtime=1.51
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47413 0 0 0 131 20 0 0 20 0 10 0 119775035 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649119814336 139649017871144 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1047724 CPUtime=0.17
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 7975 0 0 0 15 2 0 0 20 0 10 0 119775037 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119775037 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119775037 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1047724 CPUtime=0.7
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 9500 0 0 0 67 3 0 0 20 0 10 0 119775040 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1047724 CPUtime=0.62
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9598 0 0 0 57 5 0 0 20 0 10 0 119775040 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 71370 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.03
Current children cumulated vsize (KiB) 1056892

[startup+6.30079 s]
/proc/loadavg: 1.17 1.08 1.01 2/70 18258
/proc/meminfo: memFree=177000/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1047724 CPUtime=6.01
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 121196 0 0 0 555 46 0 0 20 0 10 0 119775034 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 261931 116501 2368 9 0 249281 0
[pid=18249/tid=18250] ppid=18246 vsize=1047724 CPUtime=3.32
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47416 0 0 0 311 21 0 0 20 0 10 0 119775035 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649119814336 139649017861705 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1047724 CPUtime=1.26
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 53381 0 0 0 110 16 0 0 20 0 10 0 119775037 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119775037 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119775037 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1047724 CPUtime=0.74
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 9505 0 0 0 71 3 0 0 20 0 10 0 119775040 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1047724 CPUtime=0.65
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9603 0 0 0 60 5 0 0 20 0 10 0 119775040 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 116501 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.01
Current children cumulated vsize (KiB) 1056892

[startup+12.7008 s]
/proc/loadavg: 1.16 1.08 1.01 4/70 18258
/proc/meminfo: memFree=29688/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1047724 CPUtime=12.27
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 155676 0 0 0 1168 59 0 0 20 0 10 0 119775034 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 261931 150062 2376 9 0 249281 0
[pid=18249/tid=18250] ppid=18246 vsize=1047724 CPUtime=6.07
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47721 0 0 0 583 24 0 0 20 0 10 0 119775035 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649119822168 139649017294416 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1047724 CPUtime=2.14
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 83683 0 0 0 190 24 0 0 20 0 10 0 119775037 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119775037 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119775037 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1047724 CPUtime=2.05
/proc/18249/task/18255/stat : 18255 (java) R 18246 18246 1750 0 -1 4202560 13253 0 0 0 199 6 0 0 20 0 10 0 119775040 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649008236088 139649095196362 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1047724 CPUtime=2
/proc/18249/task/18256/stat : 18256 (java) R 18246 18246 1750 0 -1 4202560 9728 0 0 0 194 6 0 0 20 0 10 0 119775040 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649007196944 139649096251097 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1047724 CPUtime=0
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119775040 1072869376 150062 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.27
Current children cumulated vsize (KiB) 1056892

[startup+25.5008 s]
/proc/loadavg: 1.32 1.12 1.03 2/71 18259
/proc/meminfo: memFree=18728/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=24.89
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 155900 0 0 0 2424 65 0 0 20 0 11 0 119775034 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 147793 2381 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=16.91
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47802 0 0 0 1666 25 0 0 20 0 11 0 119775035 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649119813536 139649018697775 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=2.16
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 83684 0 0 0 191 25 0 0 20 0 11 0 119775037 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.88
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 282 6 0 0 20 0 11 0 119775040 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=2.9
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 283 7 0 0 20 0 11 0 119775040 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.01
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 1 0 0 20 0 11 0 119775040 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 147793 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.89
Current children cumulated vsize (KiB) 1057920

[startup+51.1009 s]
/proc/loadavg: 1.21 1.11 1.02 2/71 18259
/proc/meminfo: memFree=10032/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=50.1
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 166864 0 0 0 4940 70 0 0 20 0 11 0 119775034 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 158749 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=40.66
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47804 0 0 0 4041 25 0 0 20 0 11 0 119775035 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649119822024 139649018487084 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=3.54
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 94640 0 0 0 324 30 0 0 20 0 11 0 119775037 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.91
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 284 7 0 0 20 0 11 0 119775040 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=2.93
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 286 7 0 0 20 0 11 0 119775040 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.02
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 7 0 0 0 1 1 0 0 20 0 11 0 119775040 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 158749 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.1
Current children cumulated vsize (KiB) 1057920

[startup+102.301 s]
/proc/loadavg: 1.08 1.09 1.02 2/71 18259
/proc/meminfo: memFree=10032/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=100.61
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167784 0 0 0 9989 72 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=87.01
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 8676 25 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018693989 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=7.57
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95556 0 0 0 726 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.97
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 290 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=2.97
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 290 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.05
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 10 0 0 0 2 3 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.61
Current children cumulated vsize (KiB) 1057920

[startup+162.301 s]
/proc/loadavg: 1.07 1.08 1.02 2/71 18259
/proc/meminfo: memFree=10032/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=159.8
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167792 0 0 0 15908 72 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=142.44
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 14219 25 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018723186 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=11.27
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95558 0 0 0 1096 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.97
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 290 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=2.99
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 292 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.08
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 4 4 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 159.8
Current children cumulated vsize (KiB) 1057920

[startup+222.304 s]
/proc/loadavg: 1.06 1.08 1.02 2/71 18259
/proc/meminfo: memFree=10032/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=219.08
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167799 0 0 0 21836 72 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=198.83
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 19858 25 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018694298 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=14.11
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95559 0 0 0 1380 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.98
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 291 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.12
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 22 0 0 0 6 6 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.08
Current children cumulated vsize (KiB) 1057920

[startup+282.301 s]
/proc/loadavg: 1.18 1.10 1.02 2/75 18465
/proc/meminfo: memFree=9948/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=273.19
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167807 0 0 0 27244 75 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=251.29
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 25101 28 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018697535 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=15.73
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95561 0 0 0 1542 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.98
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 291 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.14
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 28 0 0 0 7 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 273.19
Current children cumulated vsize (KiB) 1057920

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

[startup+288.001 s]
/proc/loadavg: 1.32 1.13 1.03 2/71 18510
/proc/meminfo: memFree=19548/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=278.05
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167807 0 0 0 27729 76 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=256.15
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 25586 29 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018487084 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=15.73
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95561 0 0 0 1542 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.98
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 291 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.15
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 28 0 0 0 7 8 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.05
Current children cumulated vsize (KiB) 1057920

[startup+294.401 s]
/proc/loadavg: 1.27 1.12 1.03 2/71 18510
/proc/meminfo: memFree=19672/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=284.38
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167808 0 0 0 28362 76 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=261.68
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 26139 29 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018667102 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=16.52
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95562 0 0 0 1621 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.98
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 291 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.15
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 28 0 0 0 7 8 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.38
Current children cumulated vsize (KiB) 1057920

[startup+297.601 s]
/proc/loadavg: 1.27 1.12 1.03 2/71 18510
/proc/meminfo: memFree=19672/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=287.55
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167808 0 0 0 28679 76 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=264.85
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 26456 29 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018487084 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=16.52
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95562 0 0 0 1621 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.98
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 291 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.16
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 28 0 0 0 8 8 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.55
Current children cumulated vsize (KiB) 1057920

[startup+299.201 s]
/proc/loadavg: 1.25 1.12 1.03 2/71 18510
/proc/meminfo: memFree=19672/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1048752 CPUtime=289.13
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167808 0 0 0 28837 76 0 0 20 0 11 0 119775034 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262188 159665 2393 9 0 249538 0
[pid=18249/tid=18250] ppid=18246 vsize=1048752 CPUtime=266.43
/proc/18249/task/18250/stat : 18250 (java) R 18246 18246 1750 0 -1 4202560 47805 0 0 0 26614 29 0 0 20 0 11 0 119775035 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649119821896 139649018697688 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1048752 CPUtime=16.52
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95562 0 0 0 1621 31 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1048752 CPUtime=2.98
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13281 0 0 0 291 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1048752 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1048752 CPUtime=0.16
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 28 0 0 0 8 8 0 0 20 0 11 0 119775040 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18259] ppid=18246 vsize=1048752 CPUtime=0
/proc/18249/task/18259/stat : 18259 (java) S 18246 18246 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119776473 1073922048 159665 18446744073709551615 4194304 4228012 140736883629536 139649003894624 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.13
Current children cumulated vsize (KiB) 1057920

[startup+299.601 s]
/proc/loadavg: 1.25 1.12 1.03 2/71 18510
/proc/meminfo: memFree=19672/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1049780 CPUtime=289.38
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167825 0 0 0 28862 76 0 0 20 0 11 0 119775034 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262445 159680 2393 9 0 249795 0
[pid=18249/tid=18250] ppid=18246 vsize=1049780 CPUtime=266.65
/proc/18249/task/18250/stat : 18250 (java) S 18246 18246 1750 0 -1 4202560 47813 0 0 0 26636 29 0 0 20 0 11 0 119775035 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649119822768 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1049780 CPUtime=16.52
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95562 0 0 0 1621 31 0 0 20 0 11 0 119775037 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1049780 CPUtime=3
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13284 0 0 0 293 7 0 0 20 0 11 0 119775040 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1049780 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1049780 CPUtime=0.16
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 28 0 0 0 8 8 0 0 20 0 11 0 119775040 1074974720 159680 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.38
Current children cumulated vsize (KiB) 1058948

[startup+300.001 s]
/proc/loadavg: 1.25 1.12 1.03 2/71 18510
/proc/meminfo: memFree=19672/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=1049780 CPUtime=289.43
/proc/18249/stat : 18249 (java) S 18246 18246 1750 0 -1 4202496 167827 0 0 0 28865 78 0 0 20 0 11 0 119775034 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 140736883620560 139649113288309 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 262445 159682 2393 9 0 249795 0
[pid=18249/tid=18250] ppid=18246 vsize=1049780 CPUtime=266.65
/proc/18249/task/18250/stat : 18250 (java) S 18246 18246 1750 0 -1 4202560 47813 0 0 0 26636 29 0 0 20 0 11 0 119775035 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649119822768 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=18249/tid=18251] ppid=18246 vsize=1049780 CPUtime=16.52
/proc/18249/task/18251/stat : 18251 (java) S 18246 18246 1750 0 -1 4202560 95562 0 0 0 1621 31 0 0 20 0 11 0 119775037 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649012460672 139649113302603 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18252] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18252/stat : 18252 (java) S 18246 18246 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119775037 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649011406864 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18253] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18253/stat : 18253 (java) S 18246 18246 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119775037 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649010354080 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18254] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18254/stat : 18254 (java) S 18246 18246 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119775040 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649009302512 139649113310240 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18255] ppid=18246 vsize=1049780 CPUtime=3
/proc/18249/task/18255/stat : 18255 (java) S 18246 18246 1750 0 -1 4202560 13284 0 0 0 293 7 0 0 20 0 11 0 119775040 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649008249872 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18256] ppid=18246 vsize=1049780 CPUtime=3.01
/proc/18249/task/18256/stat : 18256 (java) S 18246 18246 1750 0 -1 4202560 9836 0 0 0 294 7 0 0 20 0 11 0 119775040 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649007197200 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18257] ppid=18246 vsize=1049780 CPUtime=0
/proc/18249/task/18257/stat : 18257 (java) S 18246 18246 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119775040 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649006144720 139649113301716 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18249/tid=18258] ppid=18246 vsize=1049780 CPUtime=0.16
/proc/18249/task/18258/stat : 18258 (java) S 18246 18246 1750 0 -1 4202560 28 0 0 0 8 8 0 0 20 0 11 0 119775040 1074974720 159682 18446744073709551615 4194304 4228012 140736883629536 139649005092224 139649113302603 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.43
Current children cumulated vsize (KiB) 1058948

[startup+300.101 s]
/proc/loadavg: 1.25 1.12 1.03 2/71 18510
/proc/meminfo: memFree=19672/1022884 swapFree=0/0
[pid=18246] ppid=18245 vsize=9168 CPUtime=0
/proc/18246/stat : 18246 (p2cudf-full-1.1) S 18245 18246 1750 0 -1 4202496 454 291 0 0 0 0 0 0 20 0 1 0 119775034 9388032 330 18446744073709551615 4194304 5129932 140733208150544 140733208148688 140587987633246 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18246/statm: 2292 330 280 229 0 52 0
[pid=18249] ppid=18246 vsize=0 CPUtime=289.53
/proc/18249/stat : 18249 (java) Z 18246 18246 1750 0 -1 4203532 167838 0 0 0 28872 81 0 0 20 0 2 0 119775034 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18249/statm: 0 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.53
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 300.149
CPU time (s): 289.586
CPU user time (s): 288.726
CPU system time (s): 0.860053
CPU usage (%): 96.4808
Max. virtual memory (cumulated for all children) (KiB): 1061868

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 288.726
system time used= 0.860053
maximum resident set size= 638764
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 168596
page faults= 0
swaps= 0
block input operations= 68424
block output operations= 576
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7422
involuntary context switches= 45591

runsolver used 0.988061 second user time and 2.04413 second system time

The end