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/rand347.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/difficult/rand347.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/upgrade/difficult/rand347.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

[startup+0 s]
/proc/loadavg: 1.00 1.02 1.04 2/59 22906
/proc/meminfo: memFree=630520/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=3152 CPUtime=0
/proc/22906/stat : 22906 (runsolver) R 22905 1750 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 121017115 3227648 32 18446744073709551615 134512640 134586868 4294346624 4294344672 4151989296 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.193764 s]
/proc/loadavg: 1.00 1.02 1.04 2/59 22906
/proc/meminfo: memFree=630520/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200312 s]
/proc/loadavg: 1.00 1.02 1.04 2/59 22906
/proc/meminfo: memFree=630520/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300293 s]
/proc/loadavg: 1.00 1.02 1.04 2/59 22906
/proc/meminfo: memFree=630520/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700215 s]
/proc/loadavg: 1.00 1.02 1.04 2/59 22906
/proc/meminfo: memFree=630520/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50056 s]
/proc/loadavg: 1.00 1.02 1.04 4/70 22918
/proc/meminfo: memFree=558264/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1048348 CPUtime=1.37
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 38654 0 15 0 123 14 0 0 20 0 10 0 121017116 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 262087 34301 2349 9 0 249437 0
[pid=22909/tid=22910] ppid=22906 vsize=1048348 CPUtime=0.62
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 18176 0 3 0 56 6 0 0 20 0 10 0 121017121 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969492808352 139969390709522 0 4 0 16800975 0 0 0 -1 0 0 0 6 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1048348 CPUtime=0
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 121017128 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1048348 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 121017129 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1048348 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 121017129 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1048348 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 121017132 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1048348 CPUtime=0.36
/proc/22909/task/22915/stat : 22915 (java) R 22906 22906 1750 0 -1 4202560 9965 0 0 0 32 4 0 0 20 0 10 0 121017132 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969381231640 139969470586002 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1048348 CPUtime=0.35
/proc/22909/task/22916/stat : 22916 (java) R 22906 22906 1750 0 -1 4202560 9232 0 0 0 32 3 0 0 20 0 10 0 121017132 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969380179048 139969467897248 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1048348 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121017133 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1048348 CPUtime=0
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121017133 1073508352 34301 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.37
Current children cumulated vsize (KiB) 1057516

[startup+3.1007 s]
/proc/loadavg: 1.16 1.06 1.05 2/70 22918
/proc/meminfo: memFree=350688/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1043240 CPUtime=2.94
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 89702 0 15 0 266 28 0 0 20 0 10 0 121017116 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 260810 84040 2370 9 0 248160 0
[pid=22909/tid=22910] ppid=22906 vsize=1043240 CPUtime=1.6
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47423 0 3 0 145 15 0 0 20 0 10 0 121017121 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969492808384 139969390603680 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1043240 CPUtime=0.34
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 21300 0 0 0 30 4 0 0 20 0 10 0 121017128 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 121017129 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 121017129 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 121017132 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1043240 CPUtime=0.48
/proc/22909/task/22915/stat : 22915 (java) R 22906 22906 1750 0 -1 4202560 10205 0 0 0 44 4 0 0 20 0 10 0 121017132 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969381243664 139969469401440 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1043240 CPUtime=0.48
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9492 0 0 0 44 4 0 0 20 0 10 0 121017132 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121017133 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121017133 1068277760 84040 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 2.94
Current children cumulated vsize (KiB) 1052408

[startup+6.30071 s]
/proc/loadavg: 1.16 1.06 1.05 2/70 22918
/proc/meminfo: memFree=148320/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1043240 CPUtime=6.07
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 135245 0 15 0 566 41 0 0 20 0 10 0 121017116 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 260810 129456 2370 9 0 248160 0
[pid=22909/tid=22910] ppid=22906 vsize=1043240 CPUtime=3.5
/proc/22909/task/22910/stat : 22910 (java) S 22906 22906 1750 0 -1 4202560 47426 0 3 0 333 17 0 0 20 0 10 0 121017121 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969492816384 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1043240 CPUtime=1.54
/proc/22909/task/22911/stat : 22911 (java) R 22906 22906 1750 0 -1 4202560 66835 0 0 0 138 16 0 0 20 0 10 0 121017128 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969385454096 139969470593109 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 121017129 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 121017129 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 121017132 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1043240 CPUtime=0.51
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10205 0 0 0 47 4 0 0 20 0 10 0 121017132 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1043240 CPUtime=0.51
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9497 0 0 0 47 4 0 0 20 0 10 0 121017132 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121017133 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1043240 CPUtime=0
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 121017133 1068277760 129456 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.07
Current children cumulated vsize (KiB) 1052408

[startup+12.7008 s]
/proc/loadavg: 1.29 1.09 1.06 2/71 22919
/proc/meminfo: memFree=13772/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=12.39
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 153048 0 15 0 1193 46 0 0 20 0 11 0 121017116 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 147259 2381 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=6.59
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47805 0 3 0 642 17 0 0 20 0 11 0 121017121 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969492816232 139969486297781 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=2.06
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 83889 0 0 0 186 20 0 0 20 0 11 0 121017128 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=1.86
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10389 0 0 0 182 4 0 0 20 0 11 0 121017132 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.84
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9677 0 0 0 180 4 0 0 20 0 11 0 121017132 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969380191080 139969486306540 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 147259 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.39
Current children cumulated vsize (KiB) 1053436

[startup+25.5008 s]
/proc/loadavg: 1.25 1.09 1.05 2/71 22919
/proc/meminfo: memFree=9960/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=24.99
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159645 0 15 0 2447 52 0 0 20 0 11 0 121017116 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 153856 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=16.65
/proc/22909/task/22910/stat : 22910 (java) S 22906 22906 1750 0 -1 4202560 47808 0 3 0 1648 17 0 0 20 0 11 0 121017121 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969492815648 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=4.4
/proc/22909/task/22911/stat : 22911 (java) R 22906 22906 1750 0 -1 4202560 90481 0 0 0 415 25 0 0 20 0 11 0 121017128 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969385454128 139969472097907 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=1.96
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10389 0 0 0 192 4 0 0 20 0 11 0 121017132 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.93
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9679 0 0 0 189 4 0 0 20 0 11 0 121017132 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.01
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 121017133 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 153856 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.99
Current children cumulated vsize (KiB) 1053436

[startup+51.1008 s]
/proc/loadavg: 1.16 1.08 1.05 2/71 22919
/proc/meminfo: memFree=9092/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=50.27
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159852 0 15 0 4975 52 0 0 20 0 11 0 121017116 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154059 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=37.49
/proc/22909/task/22910/stat : 22910 (java) S 22906 22906 1750 0 -1 4202560 47808 0 3 0 3732 17 0 0 20 0 11 0 121017121 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969492815648 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=8.79
/proc/22909/task/22911/stat : 22911 (java) R 22906 22906 1750 0 -1 4202560 90685 0 0 0 853 26 0 0 20 0 11 0 121017128 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969385453680 139969473145933 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10389 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.96
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9679 0 0 0 192 4 0 0 20 0 11 0 121017132 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.02
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 121017133 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.27
Current children cumulated vsize (KiB) 1053436

[startup+102.301 s]
/proc/loadavg: 1.06 1.06 1.05 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=100.81
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159856 0 15 0 10029 52 0 0 20 0 11 0 121017116 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154059 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=84.32
/proc/22909/task/22910/stat : 22910 (java) S 22906 22906 1750 0 -1 4202560 47808 0 3 0 8415 17 0 0 20 0 11 0 121017121 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969492815648 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=12.48
/proc/22909/task/22911/stat : 22911 (java) R 22906 22906 1750 0 -1 4202560 90686 0 0 0 1222 26 0 0 20 0 11 0 121017128 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969385454128 139969472097118 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10389 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.96
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9679 0 0 0 192 4 0 0 20 0 11 0 121017132 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.05
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 5 0 0 0 20 0 11 0 121017133 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154059 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.81
Current children cumulated vsize (KiB) 1053436

[startup+162.301 s]
/proc/loadavg: 1.02 1.05 1.04 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=160.04
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159867 0 15 0 15952 52 0 0 20 0 11 0 121017116 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154062 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=140.34
/proc/22909/task/22910/stat : 22910 (java) S 22906 22906 1750 0 -1 4202560 47808 0 3 0 14017 17 0 0 20 0 11 0 121017121 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969492815648 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=15.62
/proc/22909/task/22911/stat : 22911 (java) R 22906 22906 1750 0 -1 4202560 90688 0 0 0 1536 26 0 0 20 0 11 0 121017128 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969385454128 139969470644597 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=2
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10391 0 0 0 196 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.08
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.04
Current children cumulated vsize (KiB) 1053436

[startup+222.304 s]
/proc/loadavg: 1.01 1.04 1.03 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=219.32
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159875 0 15 0 21877 55 0 0 20 0 11 0 121017116 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154062 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=195.33
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47808 0 3 0 19513 20 0 0 20 0 11 0 121017121 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969492815848 139969391210837 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=19.88
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90690 0 0 0 1962 26 0 0 20 0 11 0 121017128 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=2
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10391 0 0 0 196 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.11
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 18 0 0 0 11 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.32
Current children cumulated vsize (KiB) 1053436

[startup+282.301 s]
/proc/loadavg: 1.00 1.03 1.03 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=278.71
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159882 0 15 0 27816 55 0 0 20 0 11 0 121017116 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154062 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=251.69
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47808 0 3 0 25149 20 0 0 20 0 11 0 121017121 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969492815848 139969391479412 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=22.88
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90691 0 0 0 2262 26 0 0 20 0 11 0 121017128 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=2
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10391 0 0 0 196 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.14
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.71
Current children cumulated vsize (KiB) 1053436

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

[startup+288.001 s]
/proc/loadavg: 1.00 1.03 1.03 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=284.35
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159883 0 15 0 28380 55 0 0 20 0 11 0 121017116 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154062 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=256.58
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47808 0 3 0 25638 20 0 0 20 0 11 0 121017121 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969492815848 139969390638010 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=23.63
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90692 0 0 0 2337 26 0 0 20 0 11 0 121017128 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=2
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10391 0 0 0 196 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.14
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.35
Current children cumulated vsize (KiB) 1053436

[startup+291.201 s]
/proc/loadavg: 1.00 1.03 1.03 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=287.52
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159883 0 15 0 28697 55 0 0 20 0 11 0 121017116 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154062 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=259.74
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47808 0 3 0 25954 20 0 0 20 0 11 0 121017121 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969492815848 139969391520760 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=23.63
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90692 0 0 0 2337 26 0 0 20 0 11 0 121017128 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=2
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10391 0 0 0 196 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.14
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.52
Current children cumulated vsize (KiB) 1053436

[startup+294.401 s]
/proc/loadavg: 1.00 1.03 1.03 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=290.69
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159883 0 15 0 29014 55 0 0 20 0 11 0 121017116 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154062 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=262.9
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47808 0 3 0 26270 20 0 0 20 0 11 0 121017121 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969492815848 139969390637604 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=23.63
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90692 0 0 0 2337 26 0 0 20 0 11 0 121017128 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=2
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10391 0 0 0 196 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.14
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.69
Current children cumulated vsize (KiB) 1053436

[startup+295.201 s]
/proc/loadavg: 1.00 1.03 1.03 2/71 22919
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1044268 CPUtime=291.48
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159883 0 15 0 29093 55 0 0 20 0 11 0 121017116 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261067 154062 2393 9 0 248417 0
[pid=22909/tid=22910] ppid=22906 vsize=1044268 CPUtime=263.7
/proc/22909/task/22910/stat : 22910 (java) R 22906 22906 1750 0 -1 4202560 47808 0 3 0 26350 20 0 0 20 0 11 0 121017121 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969492815848 139969391510020 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1044268 CPUtime=23.63
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90692 0 0 0 2337 26 0 0 20 0 11 0 121017128 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1044268 CPUtime=2
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10391 0 0 0 196 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1044268 CPUtime=1.98
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 194 4 0 0 20 0 11 0 121017132 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1044268 CPUtime=0.14
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 121017133 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22919] ppid=22906 vsize=1044268 CPUtime=0
/proc/22909/task/22919/stat : 22919 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121018197 1069330432 154062 18446744073709551615 4194304 4228012 140734107629520 139969376888672 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.48
Current children cumulated vsize (KiB) 1053436

[startup+296.004 s]
/proc/loadavg: 1.00 1.03 1.03 1/71 22920
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1045296 CPUtime=292.16
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159900 0 15 0 29161 55 0 0 20 0 11 0 121017116 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261324 154077 2393 9 0 248674 0
[pid=22909/tid=22910] ppid=22906 vsize=1045296 CPUtime=264.34
/proc/22909/task/22910/stat : 22910 (java) S 22906 22906 1750 0 -1 4202560 47817 0 3 0 26414 20 0 0 20 0 11 0 121017121 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969492816816 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1045296 CPUtime=23.63
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90692 0 0 0 2337 26 0 0 20 0 11 0 121017128 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1045296 CPUtime=2.01
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10393 0 0 0 197 4 0 0 20 0 11 0 121017132 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1045296 CPUtime=2
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 196 4 0 0 20 0 11 0 121017132 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1045296 CPUtime=0.14
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 121017133 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22920] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22920/stat : 22920 (java) S 22906 22906 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 121046703 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969375836336 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.16
Current children cumulated vsize (KiB) 1054464

[startup+296.401 s]
/proc/loadavg: 1.00 1.03 1.03 1/71 22920
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=1045296 CPUtime=292.18
/proc/22909/stat : 22909 (java) S 22906 22906 1750 0 -1 4202496 159900 0 15 0 29163 55 0 0 20 0 11 0 121017116 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 140734107620544 139969486282357 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 261324 154077 2393 9 0 248674 0
[pid=22909/tid=22910] ppid=22906 vsize=1045296 CPUtime=264.34
/proc/22909/task/22910/stat : 22910 (java) S 22906 22906 1750 0 -1 4202560 47817 0 3 0 26414 20 0 0 20 0 11 0 121017121 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969492816816 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0
[pid=22909/tid=22911] ppid=22906 vsize=1045296 CPUtime=23.63
/proc/22909/task/22911/stat : 22911 (java) S 22906 22906 1750 0 -1 4202560 90692 0 0 0 2337 26 0 0 20 0 11 0 121017128 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969385454720 139969486296651 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22912] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22912/stat : 22912 (java) S 22906 22906 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 121017129 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969384400912 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22913] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22913/stat : 22913 (java) S 22906 22906 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 121017129 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969383348128 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22914] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22914/stat : 22914 (java) S 22906 22906 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 121017132 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969382296560 139969486304288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22915] ppid=22906 vsize=1045296 CPUtime=2.01
/proc/22909/task/22915/stat : 22915 (java) S 22906 22906 1750 0 -1 4202560 10393 0 0 0 197 4 0 0 20 0 11 0 121017132 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969381243920 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22916] ppid=22906 vsize=1045296 CPUtime=2
/proc/22909/task/22916/stat : 22916 (java) S 22906 22906 1750 0 -1 4202560 9680 0 0 0 196 4 0 0 20 0 11 0 121017132 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969380191248 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22917] ppid=22906 vsize=1045296 CPUtime=0
/proc/22909/task/22917/stat : 22917 (java) S 22906 22906 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 121017133 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969379138768 139969486295764 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22918] ppid=22906 vsize=1045296 CPUtime=0.14
/proc/22909/task/22918/stat : 22918 (java) S 22906 22906 1750 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 121017133 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969378086272 139969486296651 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=22909/tid=22920] ppid=22906 vsize=1045296 CPUtime=0.01
/proc/22909/task/22920/stat : 22920 (java) R 22906 22906 1750 0 -1 4202560 6 0 0 0 1 0 0 0 20 0 11 0 121046703 1070383104 154077 18446744073709551615 4194304 4228012 140734107629520 139969375827248 139969486307629 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.18
Current children cumulated vsize (KiB) 1054464

[startup+296.51 s]
/proc/loadavg: 1.00 1.03 1.03 1/71 22920
/proc/meminfo: memFree=10084/1022884 swapFree=0/0
[pid=22906] ppid=22905 vsize=9168 CPUtime=0
/proc/22906/stat : 22906 (p2cudf-full-1.1) S 22905 22906 1750 0 -1 4202496 459 292 0 0 0 0 0 0 20 0 1 0 121017115 9388032 331 18446744073709551615 4194304 5129932 140733694380352 140733694378496 139844034212958 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/22906/statm: 2292 331 280 229 0 52 0
[pid=22909] ppid=22906 vsize=0 CPUtime=292.28
/proc/22909/stat : 22909 (java) Z 22906 22906 1750 0 -1 4203532 159913 0 15 0 29170 58 0 0 20 0 2 0 121017116 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/22909/statm: 0 0 0 0 0 0 0
[pid=22909/tid=22920] ppid=22906 vsize=0 CPUtime=0.09
/proc/22909/task/22920/stat : 22920 (java) R 22906 22906 1750 0 -1 4203588 8 0 0 0 6 3 0 0 20 0 2 0 121046703 0 0 18446744073709551615 0 0 0 0 0 0 4 0 16800975 18446744073709551615 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.28
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 296.548
CPU time (s): 292.33
CPU user time (s): 291.714
CPU system time (s): 0.616038
CPU usage (%): 98.5776
Max. virtual memory (cumulated for all children) (KiB): 1058384

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 291.714
system time used= 0.616038
maximum resident set size= 616352
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 160676
page faults= 15
swaps= 0
block input operations= 70232
block output operations= 496
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7310
involuntary context switches= 41356

runsolver used 0.976061 second user time and 1.90812 second system time

The end