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

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


[startup+0 s]
/proc/loadavg: 1.45 1.19 1.05 2/60 24980
/proc/meminfo: memFree=596560/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 425 0 0 0 0 0 0 0 20 0 1 0 41682668 9388032 325 18446744073709551615 4194304 5129932 140734480612480 140734480610152 139976470406944 0 0 16781316 65536 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 325 275 229 0 52 0

[startup+0.179998 s]
/proc/loadavg: 1.45 1.19 1.05 2/60 24980
/proc/meminfo: memFree=596560/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200318 s]
/proc/loadavg: 1.45 1.19 1.05 2/60 24980
/proc/meminfo: memFree=596560/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300292 s]
/proc/loadavg: 1.45 1.19 1.05 2/60 24980
/proc/meminfo: memFree=596560/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700234 s]
/proc/loadavg: 1.45 1.19 1.05 2/60 24980
/proc/meminfo: memFree=596560/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50075 s]
/proc/loadavg: 1.45 1.19 1.05 4/71 24992
/proc/meminfo: memFree=553312/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1052732 CPUtime=1.44
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 31964 0 4 0 128 16 0 0 20 0 10 0 41682669 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 263183 28725 2348 9 0 250533 0
[pid=24983/tid=24984] ppid=24980 vsize=1052732 CPUtime=0.66
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 13598 0 4 0 57 9 0 0 20 0 10 0 41682670 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610570453664 140610468304506 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1052732 CPUtime=0
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 41682675 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1052732 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 41682676 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1052732 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 41682676 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1052732 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 41682679 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1052732 CPUtime=0.38
/proc/24983/task/24989/stat : 24989 (java) R 24980 24980 32685 0 -1 4202560 9194 0 0 0 35 3 0 0 20 0 10 0 41682679 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610458874008 140610549619210 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1052732 CPUtime=0.36
/proc/24983/task/24990/stat : 24990 (java) R 24980 24980 32685 0 -1 4202560 7880 0 0 0 34 2 0 0 20 0 10 0 41682679 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610457822264 140610549763648 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1052732 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 41682679 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1052732 CPUtime=0
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 41682679 1077997568 28725 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.44
Current children cumulated vsize (KiB) 1061900

[startup+3.10078 s]
/proc/loadavg: 1.57 1.22 1.06 4/71 24992
/proc/meminfo: memFree=449400/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1054712 CPUtime=3
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 76094 0 4 0 270 30 0 0 20 0 10 0 41682669 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 263678 71091 2369 9 0 251028 0
[pid=24983/tid=24984] ppid=24980 vsize=1054712 CPUtime=1.5
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47397 0 4 0 131 19 0 0 20 0 10 0 41682670 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610570453696 140610468344247 0 4 0 16800975 0 0 0 -1 0 0 0 5 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1054712 CPUtime=0.16
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 7976 0 0 0 12 4 0 0 20 0 10 0 41682675 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1054712 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 41682676 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1054712 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 41682676 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1054712 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 41682679 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1054712 CPUtime=0.64
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 11076 0 0 0 60 4 0 0 20 0 10 0 41682679 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1054712 CPUtime=0.67
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8353 0 0 0 65 2 0 0 20 0 10 0 41682679 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1054712 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 41682679 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1054712 CPUtime=0
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 41682679 1080025088 71091 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3
Current children cumulated vsize (KiB) 1063880

[startup+6.30078 s]
/proc/loadavg: 1.57 1.22 1.06 2/71 24992
/proc/meminfo: memFree=144484/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1040136 CPUtime=6.13
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 129614 0 4 0 563 50 0 0 20 0 10 0 41682669 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 260034 121197 2370 9 0 247384 0
[pid=24983/tid=24984] ppid=24980 vsize=1040136 CPUtime=3.33
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47400 0 4 0 312 21 0 0 20 0 10 0 41682670 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610570453696 140610468247202 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1040136 CPUtime=1.4
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 61462 0 0 0 120 20 0 0 20 0 10 0 41682675 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1040136 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 41682676 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1040136 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 41682676 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1040136 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 41682679 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1040136 CPUtime=0.66
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 11107 0 0 0 62 4 0 0 20 0 10 0 41682679 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1040136 CPUtime=0.7
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8353 0 0 0 68 2 0 0 20 0 10 0 41682679 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1040136 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 41682679 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1040136 CPUtime=0
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 41682679 1065099264 121197 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.13
Current children cumulated vsize (KiB) 1049304

[startup+12.7006 s]
/proc/loadavg: 1.56 1.22 1.07 3/71 24992
/proc/meminfo: memFree=10508/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1034104 CPUtime=12.44
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 154456 0 4 0 1183 61 0 0 20 0 11 0 41682669 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 258526 142614 2378 9 0 245876 0
[pid=24983/tid=24984] ppid=24980 vsize=1034104 CPUtime=6.2
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47762 0 4 0 597 23 0 0 20 0 11 0 41682670 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610570461336 140610469287806 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1034104 CPUtime=2.05
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 83711 0 0 0 176 29 0 0 20 0 11 0 41682675 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1034104 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1034104 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1034104 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1034104 CPUtime=2.04
/proc/24983/task/24989/stat : 24989 (java) R 24980 24980 32685 0 -1 4202560 13227 0 0 0 199 5 0 0 20 0 11 0 41682679 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610458888976 140610550438601 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1034104 CPUtime=2.11
/proc/24983/task/24990/stat : 24990 (java) R 24980 24980 32685 0 -1 4202560 8458 0 0 0 208 3 0 0 20 0 11 0 41682679 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610457836304 140610545662625 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1034104 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1034104 CPUtime=0.01
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 1 0 0 20 0 11 0 41682679 1058922496 142614 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.44
Current children cumulated vsize (KiB) 1043272

[startup+25.5007 s]
/proc/loadavg: 1.48 1.22 1.06 2/72 24993
/proc/meminfo: memFree=14220/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1030968 CPUtime=25.13
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 154496 0 4 0 2449 64 0 0 20 0 11 0 41682669 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 257742 141647 2381 9 0 245092 0
[pid=24983/tid=24984] ppid=24980 vsize=1030968 CPUtime=18.53
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47786 0 4 0 1830 23 0 0 20 0 11 0 41682670 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610570461192 140610469268821 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1030968 CPUtime=2.06
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 83711 0 0 0 176 30 0 0 20 0 11 0 41682675 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1030968 CPUtime=2.17
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13233 0 0 0 212 5 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1030968 CPUtime=2.32
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 229 3 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1030968 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 2 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24993] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24993/stat : 24993 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 41683916 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610454529888 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.13
Current children cumulated vsize (KiB) 1040136

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

[startup+25.6008 s]
/proc/loadavg: 1.48 1.22 1.06 2/72 24993
/proc/meminfo: memFree=14220/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1030968 CPUtime=25.23
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 154496 0 4 0 2459 64 0 0 20 0 11 0 41682669 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 257742 141647 2381 9 0 245092 0
[pid=24983/tid=24984] ppid=24980 vsize=1030968 CPUtime=18.63
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47786 0 4 0 1840 23 0 0 20 0 11 0 41682670 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610570461192 140610469318617 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1030968 CPUtime=2.06
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 83711 0 0 0 176 30 0 0 20 0 11 0 41682675 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1030968 CPUtime=2.17
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13233 0 0 0 212 5 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1030968 CPUtime=2.32
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 229 3 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1030968 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 2 0 0 20 0 11 0 41682679 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24993] ppid=24980 vsize=1030968 CPUtime=0
/proc/24983/task/24993/stat : 24993 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 41683916 1055711232 141647 18446744073709551615 4194304 4228012 140735856404800 140610454529888 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.23
Current children cumulated vsize (KiB) 1040136

[startup+32.0038 s]
/proc/loadavg: 1.40 1.21 1.06 2/72 24993
/proc/meminfo: memFree=10220/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1033212 CPUtime=31.51
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 165871 0 4 0 3084 67 0 0 20 0 11 0 41682669 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 258303 153018 2393 9 0 245653 0
[pid=24983/tid=24984] ppid=24980 vsize=1033212 CPUtime=23.94
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47787 0 4 0 2371 23 0 0 20 0 11 0 41682670 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610570461368 140610469268957 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1033212 CPUtime=3
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 95082 0 0 0 266 34 0 0 20 0 11 0 41682675 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1033212 CPUtime=2.19
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13233 0 0 0 214 5 0 0 20 0 11 0 41682679 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1033212 CPUtime=2.33
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 230 3 0 0 20 0 11 0 41682679 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1033212 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 41682679 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24993] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24993/stat : 24993 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 41683916 1058009088 153018 18446744073709551615 4194304 4228012 140735856404800 140610454529888 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 31.51
Current children cumulated vsize (KiB) 1042380

[startup+38.4006 s]
/proc/loadavg: 1.37 1.21 1.06 2/72 24993
/proc/meminfo: memFree=10220/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1033212 CPUtime=37.86
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 165885 0 4 0 3719 67 0 0 20 0 11 0 41682669 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 258303 153032 2393 9 0 245653 0
[pid=24983/tid=24984] ppid=24980 vsize=1033212 CPUtime=30.17
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47787 0 4 0 2994 23 0 0 20 0 11 0 41682670 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610570461544 140610469268821 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1033212 CPUtime=3
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 95082 0 0 0 266 34 0 0 20 0 11 0 41682675 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1033212 CPUtime=2.27
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13247 0 0 0 222 5 0 0 20 0 11 0 41682679 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1033212 CPUtime=2.36
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 233 3 0 0 20 0 11 0 41682679 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1033212 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 41682679 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24993] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24993/stat : 24993 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 41683916 1058009088 153032 18446744073709551615 4194304 4228012 140735856404800 140610454529888 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 37.86
Current children cumulated vsize (KiB) 1042380

[startup+41.6006 s]
/proc/loadavg: 1.37 1.21 1.06 2/72 24993
/proc/meminfo: memFree=9476/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1033212 CPUtime=41.03
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 166618 0 4 0 4035 68 0 0 20 0 11 0 41682669 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 258303 153765 2393 9 0 245653 0
[pid=24983/tid=24984] ppid=24980 vsize=1033212 CPUtime=32.52
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47787 0 4 0 3229 23 0 0 20 0 11 0 41682670 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610570461256 140610469066416 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1033212 CPUtime=3.81
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 95815 0 0 0 347 34 0 0 20 0 11 0 41682675 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1033212 CPUtime=2.27
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13247 0 0 0 222 5 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1033212 CPUtime=2.36
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 233 3 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1033212 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24993] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24993/stat : 24993 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 41683916 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610454529888 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 41.03
Current children cumulated vsize (KiB) 1042380

[startup+43.2006 s]
/proc/loadavg: 1.34 1.20 1.06 2/72 24993
/proc/meminfo: memFree=9476/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1033212 CPUtime=42.61
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 166618 0 4 0 4193 68 0 0 20 0 11 0 41682669 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 258303 153765 2393 9 0 245653 0
[pid=24983/tid=24984] ppid=24980 vsize=1033212 CPUtime=34.1
/proc/24983/task/24984/stat : 24984 (java) R 24980 24980 32685 0 -1 4202560 47787 0 4 0 3387 23 0 0 20 0 11 0 41682670 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610570461256 140610468091399 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1033212 CPUtime=3.81
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 95815 0 0 0 347 34 0 0 20 0 11 0 41682675 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1033212 CPUtime=2.27
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13247 0 0 0 222 5 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1033212 CPUtime=2.36
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 233 3 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1033212 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 41682679 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24993] ppid=24980 vsize=1033212 CPUtime=0
/proc/24983/task/24993/stat : 24993 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 41683916 1058009088 153765 18446744073709551615 4194304 4228012 140735856404800 140610454529888 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 42.61
Current children cumulated vsize (KiB) 1042380

[startup+44.0008 s]
/proc/loadavg: 1.34 1.20 1.06 1/72 24994
/proc/meminfo: memFree=9352/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1034240 CPUtime=43.07
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 166633 0 4 0 4239 68 0 0 20 0 11 0 41682669 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 258560 153778 2393 9 0 245910 0
[pid=24983/tid=24984] ppid=24980 vsize=1034240 CPUtime=34.52
/proc/24983/task/24984/stat : 24984 (java) S 24980 24980 32685 0 -1 4202560 47796 0 4 0 3429 23 0 0 20 0 11 0 41682670 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610570462128 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1034240 CPUtime=3.81
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 95815 0 0 0 347 34 0 0 20 0 11 0 41682675 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1034240 CPUtime=2.29
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13247 0 0 0 224 5 0 0 20 0 11 0 41682679 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1034240 CPUtime=2.39
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 236 3 0 0 20 0 11 0 41682679 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1034240 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 41682679 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24994] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24994/stat : 24994 (java) S 24980 24980 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 41687032 1059061760 153778 18446744073709551615 4194304 4228012 140735856404800 140610453477552 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 43.07
Current children cumulated vsize (KiB) 1043408

[startup+44.2008 s]
/proc/loadavg: 1.34 1.20 1.06 1/72 24994
/proc/meminfo: memFree=9352/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=1034240 CPUtime=43.12
/proc/24983/stat : 24983 (java) S 24980 24980 32685 0 -1 4202496 166635 0 4 0 4242 70 0 0 20 0 11 0 41682669 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140735856395824 140610563927669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 258560 153780 2393 9 0 245910 0
[pid=24983/tid=24984] ppid=24980 vsize=1034240 CPUtime=34.52
/proc/24983/task/24984/stat : 24984 (java) S 24980 24980 32685 0 -1 4202560 47796 0 4 0 3429 23 0 0 20 0 11 0 41682670 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610570462128 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=24983/tid=24985] ppid=24980 vsize=1034240 CPUtime=3.81
/proc/24983/task/24985/stat : 24985 (java) S 24980 24980 32685 0 -1 4202560 95815 0 0 0 347 34 0 0 20 0 11 0 41682675 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610463100032 140610563941963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24986] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24986/stat : 24986 (java) S 24980 24980 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 41682676 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610462046224 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24987] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24987/stat : 24987 (java) S 24980 24980 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 41682676 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610460993440 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24988] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24988/stat : 24988 (java) S 24980 24980 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 41682679 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610459941872 140610563949600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24989] ppid=24980 vsize=1034240 CPUtime=2.29
/proc/24983/task/24989/stat : 24989 (java) S 24980 24980 32685 0 -1 4202560 13247 0 0 0 224 5 0 0 20 0 11 0 41682679 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610458889232 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24990] ppid=24980 vsize=1034240 CPUtime=2.39
/proc/24983/task/24990/stat : 24990 (java) S 24980 24980 32685 0 -1 4202560 8468 0 0 0 236 3 0 0 20 0 11 0 41682679 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610457836560 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24991] ppid=24980 vsize=1034240 CPUtime=0
/proc/24983/task/24991/stat : 24991 (java) S 24980 24980 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 41682679 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610456784080 140610563941076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24992] ppid=24980 vsize=1034240 CPUtime=0.02
/proc/24983/task/24992/stat : 24992 (java) S 24980 24980 32685 0 -1 4202560 3 0 0 0 0 2 0 0 20 0 11 0 41682679 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610455731584 140610563941963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=24983/tid=24994] ppid=24980 vsize=1034240 CPUtime=0.05
/proc/24983/task/24994/stat : 24994 (java) R 24980 24980 32685 0 -1 4202560 8 0 0 0 3 2 0 0 20 0 11 0 41687032 1059061760 153780 18446744073709551615 4194304 4228012 140735856404800 140610453468352 140610563952941 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 43.12
Current children cumulated vsize (KiB) 1043408

[startup+44.3011 s]
/proc/loadavg: 1.34 1.20 1.06 1/72 24994
/proc/meminfo: memFree=9352/1022884 swapFree=0/0
[pid=24980] ppid=24979 vsize=9168 CPUtime=0
/proc/24980/stat : 24980 (p2cudf-full-1.1) S 24979 24980 32685 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 41682668 9388032 330 18446744073709551615 4194304 5129932 140734480612480 140734480610624 139976470262878 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/24980/statm: 2292 330 280 229 0 52 0
[pid=24983] ppid=24980 vsize=0 CPUtime=43.21
/proc/24983/stat : 24983 (java) Z 24980 24980 32685 0 -1 4203532 166646 0 4 0 4249 72 0 0 20 0 2 0 41682669 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/24983/statm: 0 0 0 0 0 0 0
[pid=24983/tid=24994] ppid=24980 vsize=0 CPUtime=0.14
/proc/24983/task/24994/stat : 24994 (java) R 24980 24980 32685 0 -1 4203588 9 0 0 0 8 6 0 0 20 0 2 0 41687032 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) 43.21
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 44.3272
CPU time (s): 43.2587
CPU user time (s): 42.5107
CPU system time (s): 0.748046
CPU usage (%): 97.5895
Max. virtual memory (cumulated for all children) (KiB): 1064352

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 42.5107
system time used= 0.748046
maximum resident set size= 615156
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 167407
page faults= 4
swaps= 0
block input operations= 68648
block output operations= 256
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 1994
involuntary context switches= 3900

runsolver used 0.176011 second user time and 0.240015 second system time

The end