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/install/rand96.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/install/rand96.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/install/rand96.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.02 1.07 1.02 2/64 21831
/proc/meminfo: memFree=669908/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=3152 CPUtime=0
/proc/21831/stat : 21831 (runsolver) R 21830 1745 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 120804047 3227648 32 18446744073709551615 134512640 134586868 4289559920 4289557968 4151297072 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 788 32 0 19 0 73 0

[startup+0.19879 s]
/proc/loadavg: 1.02 1.07 1.02 2/64 21831
/proc/meminfo: memFree=669908/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200306 s]
/proc/loadavg: 1.02 1.07 1.02 2/64 21831
/proc/meminfo: memFree=669908/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300241 s]
/proc/loadavg: 1.02 1.07 1.02 2/64 21831
/proc/meminfo: memFree=669908/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700222 s]
/proc/loadavg: 1.02 1.07 1.02 2/64 21831
/proc/meminfo: memFree=669908/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50074 s]
/proc/loadavg: 1.18 1.10 1.03 4/75 21843
/proc/meminfo: memFree=611036/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1054748 CPUtime=1.47
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 34849 0 0 0 130 17 0 0 20 0 10 0 120804048 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 263687 30926 2346 9 0 251037 0
[pid=21834/tid=21835] ppid=21831 vsize=1054748 CPUtime=0.65
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 15089 0 0 0 57 8 0 0 20 0 10 0 120804048 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140461087748768 140460985178551 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1054748 CPUtime=0
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 120804050 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1054748 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 120804051 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1054748 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 120804051 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1054748 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120804053 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1054748 CPUtime=0.41
/proc/21834/task/21840/stat : 21840 (java) R 21831 21831 1745 0 -1 4202560 8533 0 0 0 39 2 0 0 20 0 10 0 120804053 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460976172136 140461066948454 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1054748 CPUtime=0.38
/proc/21834/task/21841/stat : 21841 (java) R 21831 21831 1745 0 -1 4202560 9938 0 0 0 32 6 0 0 20 0 10 0 120804053 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460975119512 140461063130851 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1054748 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120804053 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1054748 CPUtime=0
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120804053 1080061952 30926 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.47
Current children cumulated vsize (KiB) 1063916

[startup+3.10076 s]
/proc/loadavg: 1.18 1.10 1.03 4/75 21843
/proc/meminfo: memFree=514068/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1054956 CPUtime=3.04
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 76326 0 0 0 272 32 0 0 20 0 10 0 120804048 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 263739 71059 2366 9 0 251089 0
[pid=21834/tid=21835] ppid=21831 vsize=1054956 CPUtime=1.55
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47412 0 0 0 135 20 0 0 20 0 10 0 120804048 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140461087748800 140460985565190 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1054956 CPUtime=0.16
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 7952 0 0 0 14 2 0 0 20 0 10 0 120804050 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1054956 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120804051 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1054956 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120804051 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1054956 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120804053 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1054956 CPUtime=0.67
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 8940 0 0 0 65 2 0 0 20 0 10 0 120804053 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1054956 CPUtime=0.63
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 10732 0 0 0 56 7 0 0 20 0 10 0 120804053 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1054956 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120804053 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1054956 CPUtime=0
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120804053 1080274944 71059 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.04
Current children cumulated vsize (KiB) 1064124

[startup+6.30076 s]
/proc/loadavg: 1.17 1.10 1.03 2/75 21843
/proc/meminfo: memFree=233332/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1044856 CPUtime=6.19
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 121937 0 0 0 573 46 0 0 20 0 10 0 120804048 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261214 114118 2368 9 0 248564 0
[pid=21834/tid=21835] ppid=21831 vsize=1044856 CPUtime=3.41
/proc/21834/task/21835/stat : 21835 (java) S 21831 21831 1745 0 -1 4202560 47414 0 0 0 320 21 0 0 20 0 10 0 120804048 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140461087756768 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1044856 CPUtime=1.38
/proc/21834/task/21836/stat : 21836 (java) R 21831 21831 1745 0 -1 4202560 53554 0 0 0 123 15 0 0 20 0 10 0 120804050 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460980394512 140461067402025 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1044856 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120804051 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1044856 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 120804051 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1044856 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120804053 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1044856 CPUtime=0.7
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 8940 0 0 0 68 2 0 0 20 0 10 0 120804053 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1044856 CPUtime=0.68
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 10738 0 0 0 61 7 0 0 20 0 10 0 120804053 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1044856 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120804053 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1044856 CPUtime=0
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120804053 1069932544 114118 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.19
Current children cumulated vsize (KiB) 1054024

[startup+12.7008 s]
/proc/loadavg: 1.31 1.13 1.04 2/75 21843
/proc/meminfo: memFree=62460/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=12.51
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 153100 0 0 0 1194 57 0 0 20 0 11 0 120804048 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 145281 2378 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=6.11
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47777 0 0 0 589 22 0 0 20 0 11 0 120804048 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140461087756808 140460986195800 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=2.21
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 83810 0 0 0 197 24 0 0 20 0 11 0 120804050 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.1
/proc/21834/task/21840/stat : 21840 (java) R 21831 21831 1745 0 -1 4202560 9026 0 0 0 208 2 0 0 20 0 11 0 120804053 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460976184080 140461064675552 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.05
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11190 0 0 0 198 7 0 0 20 0 11 0 120804053 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 145281 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.51
Current children cumulated vsize (KiB) 1055560

[startup+25.5008 s]
/proc/loadavg: 1.24 1.12 1.04 2/76 21844
/proc/meminfo: memFree=34676/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=25.11
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 160479 0 0 0 2448 63 0 0 20 0 11 0 120804048 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 152660 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=15.65
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 1541 24 0 0 20 0 11 0 120804048 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460986165574 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=4.65
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 91140 0 0 0 437 28 0 0 20 0 11 0 120804050 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.39
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 237 2 0 0 20 0 11 0 120804053 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.38
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 231 7 0 0 20 0 11 0 120804053 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.01
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 1 0 0 20 0 11 0 120804053 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 152660 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.11
Current children cumulated vsize (KiB) 1055560

[startup+51.1006 s]
/proc/loadavg: 1.16 1.11 1.04 2/76 21844
/proc/meminfo: memFree=29220/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=50.39
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 161266 0 0 0 4973 66 0 0 20 0 11 0 120804048 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 153443 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=36.37
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 3611 26 0 0 20 0 11 0 120804048 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140461087756392 140460986195104 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=9.12
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 91924 0 0 0 883 29 0 0 20 0 11 0 120804050 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.42
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 240 2 0 0 20 0 11 0 120804053 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.41
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 234 7 0 0 20 0 11 0 120804053 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.02
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 4 0 0 0 0 2 0 0 20 0 11 0 120804053 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 153443 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.39
Current children cumulated vsize (KiB) 1055560

[startup+102.301 s]
/proc/loadavg: 1.07 1.09 1.04 2/76 21844
/proc/meminfo: memFree=15332/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=101.08
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 164727 0 0 0 10041 67 0 0 20 0 11 0 120804048 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 156900 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=83.44
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 8318 26 0 0 20 0 11 0 120804048 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460986176288 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=12.72
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 95382 0 0 0 1243 29 0 0 20 0 11 0 120804050 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.42
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 240 2 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.41
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 234 7 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.04
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 7 0 0 0 0 4 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 101.08
Current children cumulated vsize (KiB) 1055560

[startup+162.301 s]
/proc/loadavg: 1.02 1.07 1.03 2/76 21844
/proc/meminfo: memFree=15084/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=160.47
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 164735 0 0 0 15980 67 0 0 20 0 11 0 120804048 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 156900 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=138.45
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 13819 26 0 0 20 0 11 0 120804048 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460986017919 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=17.06
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 95384 0 0 0 1677 29 0 0 20 0 11 0 120804050 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.43
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 241 2 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.42
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 235 7 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.07
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 13 0 0 0 0 7 0 0 20 0 11 0 120804053 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 156900 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.47
Current children cumulated vsize (KiB) 1055560

[startup+222.304 s]
/proc/loadavg: 1.07 1.07 1.03 2/76 21844
/proc/meminfo: memFree=10348/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=219.84
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 167545 0 0 0 21917 67 0 0 20 0 11 0 120804048 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 159702 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=194.85
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 19459 26 0 0 20 0 11 0 120804048 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460985386793 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=20.01
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 98188 0 0 0 1971 30 0 0 20 0 11 0 120804050 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.43
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 241 2 0 0 20 0 11 0 120804053 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.42
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 235 7 0 0 20 0 11 0 120804053 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.1
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 19 0 0 0 0 10 0 0 20 0 11 0 120804053 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 159702 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.84
Current children cumulated vsize (KiB) 1055560

[startup+282.301 s]
/proc/loadavg: 1.02 1.06 1.02 2/76 21844
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=279.22
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 168486 0 0 0 27854 68 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 160635 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=251.24
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 25098 26 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460985386503 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=22.94
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 99123 0 0 0 2264 30 0 0 20 0 11 0 120804050 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.43
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 241 2 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.44
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 237 7 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.12
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 25 0 0 0 0 12 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 279.22
Current children cumulated vsize (KiB) 1055560

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

[startup+288.001 s]
/proc/loadavg: 1.02 1.05 1.02 2/76 21844
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=284.86
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 168486 0 0 0 28418 68 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 160635 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=256.88
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 25662 26 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460986330205 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=22.94
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 99123 0 0 0 2264 30 0 0 20 0 11 0 120804050 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.43
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 241 2 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.44
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 237 7 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.12
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 25 0 0 0 0 12 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.86
Current children cumulated vsize (KiB) 1055560

[startup+294.401 s]
/proc/loadavg: 1.02 1.05 1.02 2/76 21844
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=291.2
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 168489 0 0 0 29052 68 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 160635 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=263.22
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 26296 26 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460986328805 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=22.94
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 99123 0 0 0 2264 30 0 0 20 0 11 0 120804050 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.43
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 241 2 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.44
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 237 7 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.13
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 28 0 0 0 0 13 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.2
Current children cumulated vsize (KiB) 1055560

[startup+296.004 s]
/proc/loadavg: 1.02 1.05 1.02 2/76 21844
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=292.78
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 168489 0 0 0 29210 68 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 160635 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=264.8
/proc/21834/task/21835/stat : 21835 (java) R 21831 21831 1745 0 -1 4202560 47800 0 0 0 26454 26 0 0 20 0 11 0 120804048 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140461087756248 140460985863004 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=22.94
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 99123 0 0 0 2264 30 0 0 20 0 11 0 120804050 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.43
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 241 2 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.44
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 237 7 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.13
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 28 0 0 0 0 13 0 0 20 0 11 0 120804053 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 160635 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.78
Current children cumulated vsize (KiB) 1055560

[startup+296.801 s]
/proc/loadavg: 1.02 1.05 1.02 2/76 21844
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1046392 CPUtime=293.57
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 168491 0 0 0 29289 68 0 0 20 0 11 0 120804048 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261598 160636 2393 9 0 248948 0
[pid=21834/tid=21835] ppid=21831 vsize=1046392 CPUtime=265.06
/proc/21834/task/21835/stat : 21835 (java) S 21831 21831 1745 0 -1 4202560 47800 0 0 0 26480 26 0 0 20 0 11 0 120804048 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140461087756048 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1046392 CPUtime=23.47
/proc/21834/task/21836/stat : 21836 (java) R 21831 21831 1745 0 -1 4202560 99125 0 0 0 2317 30 0 0 20 0 11 0 120804050 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460980394096 140461065531260 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1046392 CPUtime=2.43
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 241 2 0 0 20 0 11 0 120804053 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1046392 CPUtime=2.44
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 237 7 0 0 20 0 11 0 120804053 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1046392 CPUtime=0.13
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 28 0 0 0 0 13 0 0 20 0 11 0 120804053 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21844] ppid=21831 vsize=1046392 CPUtime=0
/proc/21834/task/21844/stat : 21844 (java) S 21831 21831 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120805272 1071505408 160636 18446744073709551615 4194304 4228012 140735220587744 140460971833184 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.57
Current children cumulated vsize (KiB) 1055560

[startup+297.601 s]
/proc/loadavg: 1.02 1.05 1.02 2/76 21844
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=9168 CPUtime=0
/proc/21831/stat : 21831 (p2cudf-full-1.1) S 21830 21831 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 120804047 9388032 331 18446744073709551615 4194304 5129932 140735948879392 140735948877536 139735207863390 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 2292 331 280 229 0 52 0
[pid=21834] ppid=21831 vsize=1047420 CPUtime=294.07
/proc/21834/stat : 21834 (java) S 21831 21831 1745 0 -1 4202496 168899 0 0 0 29339 68 0 0 20 0 11 0 120804048 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140735220578768 140461081222773 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21834/statm: 261855 161042 2393 9 0 249205 0
[pid=21834/tid=21835] ppid=21831 vsize=1047420 CPUtime=265.35
/proc/21834/task/21835/stat : 21835 (java) S 21831 21831 1745 0 -1 4202560 47808 0 0 0 26509 26 0 0 20 0 11 0 120804048 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140461087757232 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21834/tid=21836] ppid=21831 vsize=1047420 CPUtime=23.66
/proc/21834/task/21836/stat : 21836 (java) S 21831 21831 1745 0 -1 4202560 99519 0 0 0 2336 30 0 0 20 0 11 0 120804050 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460980395136 140461081237067 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21837] ppid=21831 vsize=1047420 CPUtime=0
/proc/21834/task/21837/stat : 21837 (java) S 21831 21831 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120804051 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460979341328 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21838] ppid=21831 vsize=1047420 CPUtime=0
/proc/21834/task/21838/stat : 21838 (java) S 21831 21831 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 120804051 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460978288544 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21839] ppid=21831 vsize=1047420 CPUtime=0
/proc/21834/task/21839/stat : 21839 (java) S 21831 21831 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120804053 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460977236976 140461081244704 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21840] ppid=21831 vsize=1047420 CPUtime=2.44
/proc/21834/task/21840/stat : 21840 (java) S 21831 21831 1745 0 -1 4202560 9040 0 0 0 242 2 0 0 20 0 11 0 120804053 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460976184336 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21841] ppid=21831 vsize=1047420 CPUtime=2.45
/proc/21834/task/21841/stat : 21841 (java) S 21831 21831 1745 0 -1 4202560 11202 0 0 0 238 7 0 0 20 0 11 0 120804053 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460975131664 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21842] ppid=21831 vsize=1047420 CPUtime=0
/proc/21834/task/21842/stat : 21842 (java) S 21831 21831 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120804053 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460974079184 140461081236180 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21834/tid=21843] ppid=21831 vsize=1047420 CPUtime=0.13
/proc/21834/task/21843/stat : 21843 (java) S 21831 21831 1745 0 -1 4202560 28 0 0 0 0 13 0 0 20 0 11 0 120804053 1072558080 161042 18446744073709551615 4194304 4228012 140735220587744 140460973026688 140461081237067 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 294.07
Current children cumulated vsize (KiB) 1056588

[startup+298.001 s]
/proc/loadavg: 1.02 1.05 1.02 2/76 21844
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21831] ppid=21830 vsize=0 CPUtime=294.26
/proc/21831/stat : 21831 (p2cudf-full-1.1) Z 21830 21831 1745 0 -1 4202508 469 169204 0 0 0 0 29353 73 20 0 1 0 120804047 0 0 18446744073709551615 0 0 0 0 0 0 0 16781316 65536 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/21831/statm: 0 0 0 0 0 0 0
Current children cumulated CPU time (s) 294.26
Current children cumulated vsize (KiB) 0

Child status: 0
Real time (s): 298.001
CPU time (s): 294.274
CPU user time (s): 293.538
CPU system time (s): 0.736046
CPU usage (%): 98.7494
Max. virtual memory (cumulated for all children) (KiB): 1064596

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 293.538
system time used= 0.736046
maximum resident set size= 644212
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 169673
page faults= 0
swaps= 0
block input operations= 68424
block output operations= 552
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7308
involuntary context switches= 42013

runsolver used 1.00006 second user time and 1.6321 second system time

The end