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/slowlink/upgrade/difficult/rand289.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/difficult/rand289.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/upgrade/difficult/rand289.cudf.result -sum(changed,installedsize),-count(removed),-notuptodate(solution),-count(changed) 

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


[startup+0 s]
/proc/loadavg: 1.05 1.06 1.03 2/59 23195
/proc/meminfo: memFree=620424/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 425 0 0 0 0 0 0 0 20 0 1 0 121077755 9388032 326 18446744073709551615 4194304 5129932 140735514922656 140735514920328 140311742760736 0 0 16781316 65536 0 0 0 17 0 0 0 0 0 0
/proc/23195/statm: 2292 326 275 229 0 52 0

[startup+0.171098 s]
/proc/loadavg: 1.05 1.06 1.03 2/59 23195
/proc/meminfo: memFree=620424/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200318 s]
/proc/loadavg: 1.05 1.06 1.03 2/59 23195
/proc/meminfo: memFree=620424/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300357 s]
/proc/loadavg: 1.05 1.06 1.03 2/59 23195
/proc/meminfo: memFree=620424/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700321 s]
/proc/loadavg: 1.05 1.06 1.03 2/59 23195
/proc/meminfo: memFree=620424/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 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.05 1.05 1.03 2/70 23207
/proc/meminfo: memFree=603092/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=957648 CPUtime=0.59
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 8805 0 24 0 55 4 0 0 20 0 10 0 121077757 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 239412 7846 2326 9 0 226762 0
[pid=23198/tid=23199] ppid=23195 vsize=957648 CPUtime=0.36
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 4790 0 12 0 34 2 0 0 20 0 10 0 121077773 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634600870496 140634498340192 0 4 0 16800975 0 0 0 -1 0 0 0 74 0 0
[pid=23198/tid=23200] ppid=23195 vsize=957648 CPUtime=0
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 2 0 0 0 0 0 0 0 20 0 10 0 121077783 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=957648 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 121077794 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=957648 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 121077794 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23203] ppid=23195 vsize=957648 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 121077806 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=957648 CPUtime=0.1
/proc/23198/task/23204/stat : 23204 (java) R 23195 23195 1750 0 -1 4202560 1559 0 0 0 10 0 0 0 20 0 10 0 121077806 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634489291368 140634580851917 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=957648 CPUtime=0.1
/proc/23198/task/23205/stat : 23205 (java) R 23195 23195 1750 0 -1 4202560 1178 0 0 0 10 0 0 0 20 0 10 0 121077806 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634488237912 140634580043827 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=957648 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121077806 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=957648 CPUtime=0
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121077806 980631552 7846 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 0.59
Current children cumulated vsize (KiB) 966816

[startup+3.1007 s]
/proc/loadavg: 1.05 1.05 1.03 4/70 23207
/proc/meminfo: memFree=533156/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1053720 CPUtime=2.17
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 43393 0 24 0 199 18 0 0 20 0 10 0 121077757 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 263430 38155 2346 9 0 250780 0
[pid=23198/tid=23199] ppid=23195 vsize=1053720 CPUtime=0.93
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 22448 0 12 0 85 8 0 0 20 0 10 0 121077773 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634600870592 140634578250403 0 4 0 16800975 0 0 0 -1 0 0 0 74 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1053720 CPUtime=0
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 121077783 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1053720 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 121077794 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1053720 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 121077794 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1053720 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 121077806 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1053720 CPUtime=0.6
/proc/23198/task/23204/stat : 23204 (java) R 23195 23195 1750 0 -1 4202560 10410 0 0 0 55 5 0 0 20 0 10 0 121077806 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634489305872 140634580011560 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1053720 CPUtime=0.6
/proc/23198/task/23205/stat : 23205 (java) R 23195 23195 1750 0 -1 4202560 9255 0 0 0 56 4 0 0 20 0 10 0 121077806 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634488253200 140634576252640 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1053720 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121077806 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1053720 CPUtime=0
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121077806 1079009280 38155 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 2.17
Current children cumulated vsize (KiB) 1062888
heavy processes:

[startup+6.30078 s]
/proc/loadavg: 1.04 1.05 1.03 2/70 23207
/proc/meminfo: memFree=209392/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1026452 CPUtime=5.26
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 113605 0 24 0 486 40 0 0 20 0 10 0 121077757 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 256613 101901 2368 9 0 243963 0
[pid=23198/tid=23199] ppid=23195 vsize=1026452 CPUtime=2.9
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47381 0 12 0 272 18 0 0 20 0 10 0 121077773 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634600870592 140634498942423 0 4 0 16800975 0 0 0 -1 0 0 0 80 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1026452 CPUtime=0.95
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 45263 0 0 0 83 12 0 0 20 0 10 0 121077783 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1026452 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 121077794 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1026452 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 121077794 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1026452 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 121077806 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1026452 CPUtime=0.67
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10410 0 0 0 62 5 0 0 20 0 10 0 121077806 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1026452 CPUtime=0.7
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 9270 0 0 0 66 4 0 0 20 0 10 0 121077806 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1026452 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121077806 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1026452 CPUtime=0.01
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 1 0 0 20 0 10 0 121077806 1051086848 101901 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 5.26
Current children cumulated vsize (KiB) 1035620

[startup+12.7008 s]
/proc/loadavg: 1.04 1.05 1.03 2/71 23208
/proc/meminfo: memFree=10364/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=11.5
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 155781 0 24 0 1096 54 0 0 20 0 11 0 121077757 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 144077 2381 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=6.21
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47766 0 12 0 601 20 0 0 20 0 11 0 121077773 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634600878040 140634499586528 0 4 0 16800975 0 0 0 -1 0 0 0 93 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=1.79
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 83895 0 0 0 157 22 0 0 20 0 11 0 121077783 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.74
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 169 5 0 0 20 0 11 0 121077806 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=1.72
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12196 0 0 0 167 5 0 0 20 0 11 0 121077806 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.01
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 1 0 0 20 0 11 0 121077806 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 144077 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 11.5
Current children cumulated vsize (KiB) 1048224

[startup+25.5006 s]
/proc/loadavg: 1.03 1.05 1.03 2/71 23208
/proc/meminfo: memFree=10120/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=24.12
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 163090 0 24 0 2356 56 0 0 20 0 11 0 121077757 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 151386 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=16.84
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47768 0 12 0 1664 20 0 0 20 0 11 0 121077773 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634600878072 140634498896828 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=3.42
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 91170 0 0 0 318 24 0 0 20 0 11 0 121077783 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.83
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 178 5 0 0 20 0 11 0 121077806 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=1.97
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12228 0 0 0 192 5 0 0 20 0 11 0 121077806 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.02
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 2 0 0 20 0 11 0 121077806 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 151386 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.12
Current children cumulated vsize (KiB) 1048224

[startup+51.1006 s]
/proc/loadavg: 1.02 1.04 1.02 2/71 23208
/proc/meminfo: memFree=10120/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=49.45
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 163401 0 24 0 4889 56 0 0 20 0 11 0 121077757 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 151693 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=38.27
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47769 0 12 0 3807 20 0 0 20 0 11 0 121077773 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634600878056 140634498896940 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=7.1
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 91456 0 0 0 686 24 0 0 20 0 11 0 121077783 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.16
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 211 5 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.03
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 4 0 0 0 0 3 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 49.45
Current children cumulated vsize (KiB) 1048224

[startup+102.301 s]
/proc/loadavg: 1.01 1.04 1.02 2/71 23208
/proc/meminfo: memFree=10120/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=100.15
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 163405 0 24 0 9959 56 0 0 20 0 11 0 121077757 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 151693 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=84.9
/proc/23198/task/23199/stat : 23199 (java) S 23195 23195 1750 0 -1 4202560 47769 0 12 0 8470 20 0 0 20 0 11 0 121077773 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634600877856 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=11.14
/proc/23198/task/23200/stat : 23200 (java) R 23195 23195 1750 0 -1 4202560 91457 0 0 0 1090 24 0 0 20 0 11 0 121077783 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634493515888 140634580519128 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.16
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 211 5 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.05
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 7 0 0 0 0 5 0 0 20 0 11 0 121077806 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 151693 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.15
Current children cumulated vsize (KiB) 1048224

[startup+162.301 s]
/proc/loadavg: 1.05 1.04 1.02 2/71 23208
/proc/meminfo: memFree=9996/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=159.56
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 164497 0 24 0 15900 56 0 0 20 0 11 0 121077757 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 152777 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=141.32
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47769 0 12 0 14112 20 0 0 20 0 11 0 121077773 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634600878056 140634499293088 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=14.11
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 92543 0 0 0 1387 24 0 0 20 0 11 0 121077783 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.16
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 211 5 0 0 20 0 11 0 121077806 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.07
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 13 0 0 0 0 7 0 0 20 0 11 0 121077806 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 152777 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 159.56
Current children cumulated vsize (KiB) 1048224

[startup+222.304 s]
/proc/loadavg: 1.02 1.03 1.01 2/71 23208
/proc/meminfo: memFree=10372/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=219
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166340 0 24 0 21844 56 0 0 20 0 11 0 121077757 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 154612 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=197.92
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47769 0 12 0 19772 20 0 0 20 0 11 0 121077773 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634600878056 140634499613443 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=16.9
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 94380 0 0 0 1666 24 0 0 20 0 11 0 121077783 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.09
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 19 0 0 0 0 9 0 0 20 0 11 0 121077806 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 154612 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219
Current children cumulated vsize (KiB) 1048224

[startup+282.301 s]
/proc/loadavg: 1.00 1.02 1.01 2/71 23208
/proc/meminfo: memFree=10372/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=278.48
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166842 0 24 0 27792 56 0 0 20 0 11 0 121077757 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 155107 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=255.3
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47769 0 12 0 25510 20 0 0 20 0 11 0 121077773 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634600878056 140634499168617 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=18.99
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 94876 0 0 0 1875 24 0 0 20 0 11 0 121077783 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.1
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 25 0 0 0 0 10 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.48
Current children cumulated vsize (KiB) 1048224

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

[startup+288.001 s]
/proc/loadavg: 1.00 1.02 1.01 2/71 23208
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=284.13
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166843 0 24 0 28357 56 0 0 20 0 11 0 121077757 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 155107 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=260.94
/proc/23198/task/23199/stat : 23199 (java) S 23195 23195 1750 0 -1 4202560 47769 0 12 0 26074 20 0 0 20 0 11 0 121077773 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634600877856 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=19.01
/proc/23198/task/23200/stat : 23200 (java) R 23195 23195 1750 0 -1 4202560 94877 0 0 0 1877 24 0 0 20 0 11 0 121077783 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634493516336 140634578706160 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.11
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 25 0 0 0 0 11 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.13
Current children cumulated vsize (KiB) 1048224

[startup+291.201 s]
/proc/loadavg: 1.00 1.02 1.01 2/71 23208
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=287.3
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166843 0 24 0 28674 56 0 0 20 0 11 0 121077757 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 155107 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=263.42
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47769 0 12 0 26322 20 0 0 20 0 11 0 121077773 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634600878056 140634499168685 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=19.69
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 94877 0 0 0 1945 24 0 0 20 0 11 0 121077783 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.11
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 25 0 0 0 0 11 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.3
Current children cumulated vsize (KiB) 1048224

[startup+294.401 s]
/proc/loadavg: 1.00 1.02 1.01 2/71 23208
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=290.48
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166843 0 24 0 28992 56 0 0 20 0 11 0 121077757 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 155107 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=266.6
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47769 0 12 0 26640 20 0 0 20 0 11 0 121077773 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634600878056 140634498934390 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=19.69
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 94877 0 0 0 1945 24 0 0 20 0 11 0 121077783 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.11
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 25 0 0 0 0 11 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.48
Current children cumulated vsize (KiB) 1048224

[startup+295.201 s]
/proc/loadavg: 1.00 1.02 1.01 2/71 23208
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1039056 CPUtime=291.27
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166843 0 24 0 29071 56 0 0 20 0 11 0 121077757 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 259764 155107 2393 9 0 247114 0
[pid=23198/tid=23199] ppid=23195 vsize=1039056 CPUtime=267.39
/proc/23198/task/23199/stat : 23199 (java) R 23195 23195 1750 0 -1 4202560 47769 0 12 0 26719 20 0 0 20 0 11 0 121077773 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634600878056 140634499586826 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1039056 CPUtime=19.69
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 94877 0 0 0 1945 24 0 0 20 0 11 0 121077783 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1039056 CPUtime=1.86
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 181 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1039056 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1039056 CPUtime=0.11
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 25 0 0 0 0 11 0 0 20 0 11 0 121077806 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23208] ppid=23195 vsize=1039056 CPUtime=0
/proc/23198/task/23208/stat : 23208 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121078821 1063993344 155107 18446744073709551615 4194304 4228012 140736496133088 140634484950880 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.27
Current children cumulated vsize (KiB) 1048224

[startup+296.004 s]
/proc/loadavg: 1.00 1.02 1.01 1/71 23209
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1040084 CPUtime=291.77
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166857 0 24 0 29121 56 0 0 20 0 11 0 121077757 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 260021 155119 2393 9 0 247371 0
[pid=23198/tid=23199] ppid=23195 vsize=1040084 CPUtime=267.87
/proc/23198/task/23199/stat : 23199 (java) S 23195 23195 1750 0 -1 4202560 47777 0 12 0 26767 20 0 0 20 0 11 0 121077773 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634600879024 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1040084 CPUtime=19.69
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 94877 0 0 0 1945 24 0 0 20 0 11 0 121077783 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1040084 CPUtime=1.88
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 183 5 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1040084 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1040084 CPUtime=0.11
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 25 0 0 0 0 11 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23209] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23209/stat : 23209 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121107326 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634483898544 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.77
Current children cumulated vsize (KiB) 1049252

[startup+296.201 s]
/proc/loadavg: 1.00 1.02 1.01 1/71 23209
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=1040084 CPUtime=291.77
/proc/23198/stat : 23198 (java) S 23195 23195 1750 0 -1 4202496 166857 0 24 0 29121 56 0 0 20 0 11 0 121077757 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140736496124112 140634594344565 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 260021 155119 2393 9 0 247371 0
[pid=23198/tid=23199] ppid=23195 vsize=1040084 CPUtime=267.87
/proc/23198/task/23199/stat : 23199 (java) S 23195 23195 1750 0 -1 4202560 47777 0 12 0 26767 20 0 0 20 0 11 0 121077773 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634600879024 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 95 0 0
[pid=23198/tid=23200] ppid=23195 vsize=1040084 CPUtime=19.69
/proc/23198/task/23200/stat : 23200 (java) S 23195 23195 1750 0 -1 4202560 94877 0 0 0 1945 24 0 0 20 0 11 0 121077783 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634493516928 140634594358859 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23201] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23201/stat : 23201 (java) S 23195 23195 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121077794 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634492463120 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23202] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23202/stat : 23202 (java) S 23195 23195 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121077794 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634491410336 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=23198/tid=23203] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23203/stat : 23203 (java) S 23195 23195 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634490358768 140634594366496 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23204] ppid=23195 vsize=1040084 CPUtime=1.88
/proc/23198/task/23204/stat : 23204 (java) S 23195 23195 1750 0 -1 4202560 10637 0 0 0 183 5 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634489306128 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23205] ppid=23195 vsize=1040084 CPUtime=2.18
/proc/23198/task/23205/stat : 23205 (java) S 23195 23195 1750 0 -1 4202560 12249 0 0 0 213 5 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634488253456 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23206] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23206/stat : 23206 (java) S 23195 23195 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634487200976 140634594357972 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23207] ppid=23195 vsize=1040084 CPUtime=0.11
/proc/23198/task/23207/stat : 23207 (java) S 23195 23195 1750 0 -1 4202560 25 0 0 0 0 11 0 0 20 0 11 0 121077806 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634486148480 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=23198/tid=23209] ppid=23195 vsize=1040084 CPUtime=0
/proc/23198/task/23209/stat : 23209 (java) S 23195 23195 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121107326 1065046016 155119 18446744073709551615 4194304 4228012 140736496133088 140634483898544 140634594358859 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.77
Current children cumulated vsize (KiB) 1049252

[startup+296.323 s]
/proc/loadavg: 1.00 1.02 1.01 1/71 23209
/proc/meminfo: memFree=10248/1022884 swapFree=0/0
[pid=23195] ppid=23194 vsize=9168 CPUtime=0
/proc/23195/stat : 23195 (p2cudf-full-1.1) S 23194 23195 1750 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 121077755 9388032 331 18446744073709551615 4194304 5129932 140735514922656 140735514920800 140311742616670 0 65536 16781316 65538 0 0 0 17 0 0 0 1 0 0
/proc/23195/statm: 2292 331 280 229 0 52 0
[pid=23198] ppid=23195 vsize=0 CPUtime=291.89
/proc/23198/stat : 23198 (java) Z 23195 23195 1750 0 -1 4203532 166870 0 24 0 29131 58 0 0 20 0 2 0 121077757 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 15 0 0
/proc/23198/statm: 0 0 0 0 0 0 0
[pid=23198/tid=23209] ppid=23195 vsize=0 CPUtime=0.09
/proc/23198/task/23209/stat : 23209 (java) R 23195 23195 1750 0 -1 4203588 8 0 0 0 6 3 0 0 20 0 2 0 121107326 0 0 18446744073709551615 0 0 0 0 0 0 4 0 16800975 18446744073709551615 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.89
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 296.325
CPU time (s): 291.906
CPU user time (s): 291.318
CPU system time (s): 0.588036
CPU usage (%): 98.5088
Max. virtual memory (cumulated for all children) (KiB): 1063360

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 291.318
system time used= 0.588036
maximum resident set size= 620520
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 167631
page faults= 24
swaps= 0
block input operations= 82288
block output operations= 520
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7817
involuntary context switches= 41503

runsolver used 0.744046 second user time and 1.6481 second system time

The end