runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: runsolver -s SIGUSR1 -M 1124 -C 300 -d 10 -w /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/upgrade/difficult/rand196.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/difficult/rand196.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/upgrade/difficult/rand196.cudf.result -count(removed),-sum(solution,installedsize),-notuptodate(solution),-unsat_recommends(solution),-count(new) 

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


[startup+0 s]
/proc/loadavg: 1.04 1.13 1.12 2/64 18764
/proc/meminfo: memFree=513500/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=3152 CPUtime=0
/proc/18764/stat : 18764 (runsolver) R 18763 1745 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 119905177 3227648 32 18446744073709551615 134512640 134586868 4292122800 4292120848 4151780400 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 788 32 0 19 0 73 0

[startup+0.111978 s]
/proc/loadavg: 1.04 1.13 1.12 2/64 18764
/proc/meminfo: memFree=513500/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200317 s]
/proc/loadavg: 1.04 1.13 1.12 2/64 18764
/proc/meminfo: memFree=513500/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300316 s]
/proc/loadavg: 1.04 1.13 1.12 2/64 18764
/proc/meminfo: memFree=513500/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700236 s]
/proc/loadavg: 1.04 1.13 1.12 2/64 18764
/proc/meminfo: memFree=513500/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50058 s]
/proc/loadavg: 1.04 1.13 1.12 4/75 18776
/proc/meminfo: memFree=457976/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1048332 CPUtime=1.46
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 36966 0 0 0 132 14 0 0 20 0 10 0 119905177 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 262083 33198 2345 9 0 249433 0
[pid=18767/tid=18768] ppid=18764 vsize=1048332 CPUtime=0.66
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 16932 0 0 0 60 6 0 0 20 0 10 0 119905178 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692858344096 139692755863701 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1048332 CPUtime=0
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 119905180 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1048332 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 119905180 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1048332 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 119905180 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1048332 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119905183 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1048332 CPUtime=0.42
/proc/18767/task/18773/stat : 18773 (java) R 18764 18764 1745 0 -1 4202560 9835 0 0 0 38 4 0 0 20 0 10 0 119905183 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692746767240 139692837390761 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1048332 CPUtime=0.35
/proc/18767/task/18774/stat : 18774 (java) R 18764 18764 1745 0 -1 4202560 8907 0 0 0 32 3 0 0 20 0 10 0 119905183 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692745714792 139692834960646 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1048332 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119905183 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1048332 CPUtime=0
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119905183 1073491968 33198 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.46
Current children cumulated vsize (KiB) 1057500

[startup+3.10081 s]
/proc/loadavg: 1.19 1.16 1.13 2/75 18776
/proc/meminfo: memFree=243208/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1044496 CPUtime=3.02
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 90249 0 0 0 274 28 0 0 20 0 10 0 119905177 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261124 85061 2366 9 0 248474 0
[pid=18767/tid=18768] ppid=18764 vsize=1044496 CPUtime=1.5
/proc/18767/task/18768/stat : 18768 (java) S 18764 18764 1745 0 -1 4202560 47395 0 0 0 136 14 0 0 20 0 10 0 119905178 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692858352144 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1044496 CPUtime=0.43
/proc/18767/task/18769/stat : 18769 (java) R 18764 18764 1745 0 -1 4202560 21972 0 0 0 37 6 0 0 20 0 10 0 119905180 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692750989840 139692836128889 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119905180 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119905180 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119905183 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1044496 CPUtime=0.59
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10266 0 0 0 55 4 0 0 20 0 10 0 119905183 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1044496 CPUtime=0.47
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9323 0 0 0 44 3 0 0 20 0 10 0 119905183 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119905183 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119905183 1069563904 85061 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.02
Current children cumulated vsize (KiB) 1053664

[startup+6.30076 s]
/proc/loadavg: 1.19 1.16 1.13 2/75 18776
/proc/meminfo: memFree=9924/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1044496 CPUtime=5.95
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 138068 0 0 0 552 43 0 0 20 0 10 0 119905177 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261124 132390 2367 9 0 248474 0
[pid=18767/tid=18768] ppid=18764 vsize=1044496 CPUtime=3.27
/proc/18767/task/18768/stat : 18768 (java) S 18764 18764 1745 0 -1 4202560 47398 0 0 0 311 16 0 0 20 0 10 0 119905178 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692858352144 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1044496 CPUtime=1.54
/proc/18767/task/18769/stat : 18769 (java) R 18764 18764 1745 0 -1 4202560 69758 0 0 0 136 18 0 0 20 0 10 0 119905180 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692750989840 139692836128840 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119905180 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119905180 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119905183 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1044496 CPUtime=0.61
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10266 0 0 0 57 4 0 0 20 0 10 0 119905183 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1044496 CPUtime=0.5
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9352 0 0 0 47 3 0 0 20 0 10 0 119905183 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119905183 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1044496 CPUtime=0
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119905183 1069563904 132390 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 5.95
Current children cumulated vsize (KiB) 1053664

[startup+12.7006 s]
/proc/loadavg: 1.16 1.16 1.13 2/76 18777
/proc/meminfo: memFree=10104/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1045524 CPUtime=12.17
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 152855 0 0 0 1166 51 0 0 20 0 11 0 119905177 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261381 147177 2379 9 0 248731 0
[pid=18767/tid=18768] ppid=18764 vsize=1045524 CPUtime=6.65
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47778 0 0 0 648 17 0 0 20 0 11 0 119905178 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692756922324 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1045524 CPUtime=1.85
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 83694 0 0 0 161 24 0 0 20 0 11 0 119905180 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1045524 CPUtime=1.95
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10585 0 0 0 190 5 0 0 20 0 11 0 119905183 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1045524 CPUtime=1.69
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9498 0 0 0 165 4 0 0 20 0 11 0 119905183 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1070616576 147177 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.17
Current children cumulated vsize (KiB) 1054692

[startup+25.5007 s]
/proc/loadavg: 1.14 1.15 1.13 2/76 18777
/proc/meminfo: memFree=9856/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1045524 CPUtime=24.82
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 152874 0 0 0 2430 52 0 0 20 0 11 0 119905177 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261381 147196 2381 9 0 248731 0
[pid=18767/tid=18768] ppid=18764 vsize=1045524 CPUtime=19
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47785 0 0 0 1882 18 0 0 20 0 11 0 119905178 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692858351640 139692757195260 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1045524 CPUtime=1.86
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 83694 0 0 0 162 24 0 0 20 0 11 0 119905180 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1045524 CPUtime=2.09
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10591 0 0 0 204 5 0 0 20 0 11 0 119905183 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1045524 CPUtime=1.83
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9504 0 0 0 179 4 0 0 20 0 11 0 119905183 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1045524 CPUtime=0.01
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 1 0 0 0 20 0 11 0 119905183 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1045524 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1070616576 147196 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.82
Current children cumulated vsize (KiB) 1054692

[startup+51.1007 s]
/proc/loadavg: 1.09 1.14 1.12 2/76 18777
/proc/meminfo: memFree=9944/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=50.11
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 165096 0 0 0 4955 56 0 0 20 0 11 0 119905177 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 159414 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=42.39
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 4221 18 0 0 20 0 11 0 119905178 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692858351736 139692755981831 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=3.68
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 95903 0 0 0 340 28 0 0 20 0 11 0 119905180 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.13
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10595 0 0 0 208 5 0 0 20 0 11 0 119905183 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.86
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9508 0 0 0 182 4 0 0 20 0 11 0 119905183 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.02
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 4 0 0 0 2 0 0 0 20 0 11 0 119905183 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 159414 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.11
Current children cumulated vsize (KiB) 1055748

[startup+102.301 s]
/proc/loadavg: 1.03 1.11 1.11 2/76 18777
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=100.76
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 165985 0 0 0 10019 57 0 0 20 0 11 0 119905177 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 160296 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=91.1
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 9092 18 0 0 20 0 11 0 119905178 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692858351592 139692757228088 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=5.54
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 96784 0 0 0 526 28 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.15
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10595 0 0 0 210 5 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.05
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 10 0 0 0 5 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.76
Current children cumulated vsize (KiB) 1055748

[startup+162.301 s]
/proc/loadavg: 1.10 1.12 1.11 2/76 18777
/proc/meminfo: memFree=9200/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=160.09
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 165990 0 0 0 15952 57 0 0 20 0 11 0 119905177 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 160296 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=147.89
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 14771 18 0 0 20 0 11 0 119905178 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692756922413 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=8.06
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 96786 0 0 0 778 28 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.15
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10595 0 0 0 210 5 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.08
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 13 0 0 0 8 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.09
Current children cumulated vsize (KiB) 1055748

[startup+222.304 s]
/proc/loadavg: 1.03 1.10 1.10 2/76 18777
/proc/meminfo: memFree=9200/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=219.43
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 165998 0 0 0 21886 57 0 0 20 0 11 0 119905177 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 160296 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=203.82
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 20364 18 0 0 20 0 11 0 119905178 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692757189355 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=11.44
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 96788 0 0 0 1116 28 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.15
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10595 0 0 0 210 5 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.11
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 19 0 0 0 11 0 0 0 20 0 11 0 119905183 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 160296 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.43
Current children cumulated vsize (KiB) 1055748

[startup+282.301 s]
/proc/loadavg: 1.06 1.09 1.10 2/76 18777
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=278.76
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 166720 0 0 0 27819 57 0 0 20 0 11 0 119905177 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 161010 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=260.54
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 26036 18 0 0 20 0 11 0 119905178 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692756669687 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=14
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 97503 0 0 0 1372 28 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.17
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10596 0 0 0 212 5 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.14
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.76
Current children cumulated vsize (KiB) 1055748

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

[startup+288.001 s]
/proc/loadavg: 1.05 1.09 1.09 2/76 18777
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=284.4
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 166720 0 0 0 28383 57 0 0 20 0 11 0 119905177 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 161010 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=266.18
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 26600 18 0 0 20 0 11 0 119905178 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692757260826 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=14
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 97503 0 0 0 1372 28 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.17
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10596 0 0 0 212 5 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.14
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.4
Current children cumulated vsize (KiB) 1055748

[startup+291.201 s]
/proc/loadavg: 1.05 1.09 1.09 2/76 18777
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=287.57
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 166720 0 0 0 28700 57 0 0 20 0 11 0 119905177 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 161010 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=269.34
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 26916 18 0 0 20 0 11 0 119905178 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692756922302 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=14
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 97503 0 0 0 1372 28 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.17
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10596 0 0 0 212 5 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.14
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.57
Current children cumulated vsize (KiB) 1055748

[startup+294.401 s]
/proc/loadavg: 1.05 1.09 1.09 2/76 18777
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=290.73
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 166720 0 0 0 29016 57 0 0 20 0 11 0 119905177 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 161010 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=272.5
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 27232 18 0 0 20 0 11 0 119905178 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692756669435 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=14
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 97503 0 0 0 1372 28 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.17
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10596 0 0 0 212 5 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.14
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.73
Current children cumulated vsize (KiB) 1055748

[startup+295.201 s]
/proc/loadavg: 1.05 1.09 1.09 2/76 18777
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1046580 CPUtime=291.52
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 166720 0 0 0 29095 57 0 0 20 0 11 0 119905177 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261645 161010 2393 9 0 248995 0
[pid=18767/tid=18768] ppid=18764 vsize=1046580 CPUtime=273.3
/proc/18767/task/18768/stat : 18768 (java) R 18764 18764 1745 0 -1 4202560 47787 0 0 0 27312 18 0 0 20 0 11 0 119905178 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692858351688 139692756922320 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1046580 CPUtime=14
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 97503 0 0 0 1372 28 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1046580 CPUtime=2.17
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10596 0 0 0 212 5 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1046580 CPUtime=1.88
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 184 4 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1046580 CPUtime=0.14
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 119905183 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18767/tid=18777] ppid=18764 vsize=1046580 CPUtime=0
/proc/18767/task/18777/stat : 18777 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119906256 1071697920 161010 18446744073709551615 4194304 4228012 140733475646272 139692742428512 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.52
Current children cumulated vsize (KiB) 1055748

[startup+296.005 s]
/proc/loadavg: 1.05 1.09 1.09 1/76 18778
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1047608 CPUtime=292.16
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 166737 0 0 0 29159 57 0 0 20 0 11 0 119905177 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261902 161025 2393 9 0 249252 0
[pid=18767/tid=18768] ppid=18764 vsize=1047608 CPUtime=273.91
/proc/18767/task/18768/stat : 18768 (java) S 18764 18764 1745 0 -1 4202560 47795 0 0 0 27373 18 0 0 20 0 11 0 119905178 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692858352560 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1047608 CPUtime=14
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 97503 0 0 0 1372 28 0 0 20 0 11 0 119905180 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1047608 CPUtime=2.18
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10599 0 0 0 213 5 0 0 20 0 11 0 119905183 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1047608 CPUtime=1.89
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 185 4 0 0 20 0 11 0 119905183 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1047608 CPUtime=0.14
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 119905183 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18767/tid=18778] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18778/stat : 18778 (java) S 18764 18764 1745 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119934761 1072750592 161025 18446744073709551615 4194304 4228012 140733475646272 139692741376176 139692851832395 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) 1056776

[startup+296.401 s]
/proc/loadavg: 1.05 1.09 1.09 1/76 18778
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=1047608 CPUtime=292.22
/proc/18767/stat : 18767 (java) S 18764 18764 1745 0 -1 4202496 166739 0 0 0 29164 58 0 0 20 0 11 0 119905177 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 140733475637296 139692851818101 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 261902 161027 2393 9 0 249252 0
[pid=18767/tid=18768] ppid=18764 vsize=1047608 CPUtime=273.91
/proc/18767/task/18768/stat : 18768 (java) S 18764 18764 1745 0 -1 4202560 47795 0 0 0 27373 18 0 0 20 0 11 0 119905178 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692858352560 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=18767/tid=18769] ppid=18764 vsize=1047608 CPUtime=14
/proc/18767/task/18769/stat : 18769 (java) S 18764 18764 1745 0 -1 4202560 97503 0 0 0 1372 28 0 0 20 0 11 0 119905180 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692750990464 139692851832395 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18770] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18770/stat : 18770 (java) S 18764 18764 1745 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119905180 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692749936656 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18771] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18771/stat : 18771 (java) S 18764 18764 1745 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119905180 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692748883872 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18772] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18772/stat : 18772 (java) S 18764 18764 1745 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119905183 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692747832304 139692851840032 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18773] ppid=18764 vsize=1047608 CPUtime=2.18
/proc/18767/task/18773/stat : 18773 (java) S 18764 18764 1745 0 -1 4202560 10599 0 0 0 213 5 0 0 20 0 11 0 119905183 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692746779664 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18774] ppid=18764 vsize=1047608 CPUtime=1.89
/proc/18767/task/18774/stat : 18774 (java) S 18764 18764 1745 0 -1 4202560 9510 0 0 0 185 4 0 0 20 0 11 0 119905183 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692745726992 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18775] ppid=18764 vsize=1047608 CPUtime=0
/proc/18767/task/18775/stat : 18775 (java) S 18764 18764 1745 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119905183 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692744674512 139692851831508 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=18767/tid=18776] ppid=18764 vsize=1047608 CPUtime=0.14
/proc/18767/task/18776/stat : 18776 (java) S 18764 18764 1745 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 119905183 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692743622016 139692851832395 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=18767/tid=18778] ppid=18764 vsize=1047608 CPUtime=0.04
/proc/18767/task/18778/stat : 18778 (java) R 18764 18764 1745 0 -1 4202560 8 0 0 0 4 0 0 0 20 0 11 0 119934761 1072750592 161027 18446744073709551615 4194304 4228012 140733475646272 139692741366976 139692755842151 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.22
Current children cumulated vsize (KiB) 1056776

[startup+296.505 s]
/proc/loadavg: 1.05 1.09 1.09 1/76 18778
/proc/meminfo: memFree=9448/1022884 swapFree=0/0
[pid=18764] ppid=18763 vsize=9168 CPUtime=0
/proc/18764/stat : 18764 (p2cudf-full-1.1) S 18763 18764 1745 0 -1 4202496 457 292 0 0 0 0 0 0 20 0 1 0 119905177 9388032 331 18446744073709551615 4194304 5129932 140737091765184 140737091763328 140504031958110 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/18764/statm: 2292 331 280 229 0 52 0
[pid=18767] ppid=18764 vsize=0 CPUtime=292.32
/proc/18767/stat : 18767 (java) Z 18764 18764 1745 0 -1 4203532 166750 0 0 0 29171 61 0 0 20 0 2 0 119905177 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/18767/statm: 0 0 0 0 0 0 0
[pid=18767/tid=18778] ppid=18764 vsize=0 CPUtime=0.13
/proc/18767/task/18778/stat : 18778 (java) R 18764 18764 1745 0 -1 4203588 8 0 0 0 9 4 0 0 20 0 2 0 119934761 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.32
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 296.527
CPU time (s): 292.354
CPU user time (s): 291.722
CPU system time (s): 0.632039
CPU usage (%): 98.593
Max. virtual memory (cumulated for all children) (KiB): 1058844

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 291.722
system time used= 0.632039
maximum resident set size= 644144
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 167512
page faults= 0
swaps= 0
block input operations= 68432
block output operations= 536
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7373
involuntary context switches= 41442

runsolver used 0.872054 second user time and 1.73211 second system time

The end