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: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201103021629/p2cudf-trendy-1.12/rand4.cudf.heavy.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/heavy//rand4.cudf /home/misc2010/tmp/201103021629/p2cudf-trendy-1.12/rand4.cudf.heavy.result 

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

[startup+0 s]
/proc/loadavg: 1.18 1.12 0.97 5/38 11610
/proc/meminfo: memFree=296508/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=3016 CPUtime=0
/proc/11610/stat : 11610 (runsolver) R 11609 1684 1684 34817 1684 4202560 54 0 0 0 0 0 0 0 25 0 1 0 68494408 3088384 75 1283457024 134512640 134586868 4290276608 18446744073709551615 4158880480 0 0 0 24578 0 0 0 17 0 0 0 0
/proc/11610/statm: 754 75 44 19 0 53 0
Current StackSize limit: 8192 KiB


[startup+0.150282 s]
/proc/loadavg: 1.18 1.12 0.97 5/38 11610
/proc/meminfo: memFree=296508/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.210296 s]
/proc/loadavg: 1.18 1.12 0.97 5/38 11610
/proc/meminfo: memFree=296508/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.310308 s]
/proc/loadavg: 1.18 1.12 0.97 5/38 11610
/proc/meminfo: memFree=296508/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.710426 s]
/proc/loadavg: 1.18 1.12 0.97 5/38 11610
/proc/meminfo: memFree=296508/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+1.51061 s]
/proc/loadavg: 1.18 1.12 0.97 4/50 11633
/proc/meminfo: memFree=249032/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=881504 CPUtime=1.47
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 22483 0 1 0 139 8 0 0 25 0 10 0 68494412 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 220376 19236 1724 10 0 217467 0
[pid=11624/tid=11625] ppid=11612 vsize=881504 CPUtime=0.77
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 12014 0 1 0 74 3 0 0 25 0 10 0 68494412 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4095918447 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=881504 CPUtime=0
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 15 0 10 0 68494414 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=881504 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 25 0 10 0 68494414 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=881504 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 10 0 68494414 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=881504 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68494417 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=881504 CPUtime=0.28
/proc/11624/task/11630/stat : 11630 (java) R 11612 11610 1684 34817 1684 4202560 2389 0 0 0 28 0 0 0 25 0 10 0 68494417 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=881504 CPUtime=0.4
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7000 0 0 0 37 3 0 0 25 0 10 0 68494417 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=881504 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68494417 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=881504 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 10 0 68494417 902660096 19236 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 886644

[startup+3.11687 s]
/proc/loadavg: 1.18 1.12 0.97 2/50 11633
/proc/meminfo: memFree=124288/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=879476 CPUtime=3.06
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 73143 0 1 0 272 34 0 0 25 0 10 0 68494412 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219869 69772 1743 10 0 216960 0
[pid=11624/tid=11625] ppid=11612 vsize=879476 CPUtime=1.66
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50069 0 1 0 146 20 0 0 25 0 10 0 68494412 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=879476 CPUtime=0.28
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 12533 0 0 0 19 9 0 0 16 0 10 0 68494414 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=879476 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 10 0 68494414 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=879476 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 10 0 68494414 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=879476 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68494417 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=879476 CPUtime=0.66
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 2441 0 0 0 66 0 0 0 15 0 10 0 68494417 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=879476 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 10 0 68494417 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=879476 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68494417 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=879476 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 10 0 68494417 900583424 69772 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 884616

[startup+6.31181 s]
/proc/loadavg: 1.25 1.13 0.97 2/51 11634
/proc/meminfo: memFree=22476/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=6.26
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 74582 0 1 0 590 36 0 0 25 0 11 0 68494412 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 70006 1779 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=3.36
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50262 0 1 0 315 21 0 0 25 0 11 0 68494412 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4096591030 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=0.28
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 12533 0 0 0 19 9 0 0 16 0 11 0 68494414 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.15
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3683 0 0 0 214 1 0 0 15 0 11 0 68494417 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 70006 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.29
Current children cumulated vsize (KiB) 884128

[startup+12.7138 s]
/proc/loadavg: 1.23 1.13 0.97 2/50 11634
/proc/meminfo: memFree=22236/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=12.66
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85177 0 1 0 1226 40 0 0 25 0 11 0 68494412 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 80601 1788 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=9.01
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50263 0 1 0 880 21 0 0 25 0 11 0 68494412 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4096695135 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=0.71
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23091 0 0 0 59 12 0 0 16 0 11 0 68494414 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.46
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3719 0 0 0 245 1 0 0 16 0 11 0 68494417 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 80601 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.69
Current children cumulated vsize (KiB) 884128

[startup+25.507 s]
/proc/loadavg: 1.17 1.12 0.97 2/50 11634
/proc/meminfo: memFree=5360/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=25.44
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85416 0 1 0 2504 40 0 0 25 0 11 0 68494412 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 80840 1788 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=21.45
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50263 0 1 0 2124 21 0 0 25 0 11 0 68494412 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4096565281 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=1
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23326 0 0 0 88 12 0 0 15 0 11 0 68494414 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.51
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3723 0 0 0 250 1 0 0 15 0 11 0 68494417 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 80840 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.47
Current children cumulated vsize (KiB) 884128

[startup+51.1129 s]
/proc/loadavg: 1.11 1.11 0.97 2/52 11636
/proc/meminfo: memFree=5608/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=51.01
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85592 0 1 0 5061 40 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 81016 1788 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=45.9
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50263 0 1 0 4569 21 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4096577018 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=1.92
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23494 0 0 0 180 12 0 0 15 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.7
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3731 0 0 0 269 1 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.04
Current children cumulated vsize (KiB) 884128

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

[startup+63.9059 s]
/proc/loadavg: 1.10 1.11 0.97 2/52 11637
/proc/meminfo: memFree=5608/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=63.79
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85592 0 1 0 6339 40 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 81016 1788 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=58.11
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50263 0 1 0 5789 22 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4096757327 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=2.5
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23494 0 0 0 238 12 0 0 15 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.7
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3731 0 0 0 269 1 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 63.82
Current children cumulated vsize (KiB) 884128

[startup+70.3074 s]
/proc/loadavg: 1.08 1.11 0.97 2/52 11638
/proc/meminfo: memFree=5484/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=70.17
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85592 0 1 0 6977 40 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 81016 1788 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=64.49
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50263 0 1 0 6427 22 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4096757384 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=2.5
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23494 0 0 0 238 12 0 0 15 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.7
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3731 0 0 0 269 1 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 70.2
Current children cumulated vsize (KiB) 884128

[startup+71.9078 s]
/proc/loadavg: 1.08 1.11 0.97 2/52 11638
/proc/meminfo: memFree=5484/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=71.77
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85592 0 1 0 7137 40 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 81016 1788 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=65.77
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50263 0 1 0 6555 22 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4095879961 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=2.82
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23494 0 0 0 270 12 0 0 16 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.7
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3731 0 0 0 269 1 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 71.8
Current children cumulated vsize (KiB) 884128

[startup+72.708 s]
/proc/loadavg: 1.08 1.11 0.97 2/52 11638
/proc/meminfo: memFree=5484/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=878988 CPUtime=72.57
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85592 0 1 0 7217 40 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219747 81016 1788 10 0 216838 0
[pid=11624/tid=11625] ppid=11612 vsize=878988 CPUtime=66.57
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4202560 50263 0 1 0 6635 22 0 0 25 0 11 0 68494412 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4096577198 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=878988 CPUtime=2.82
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23494 0 0 0 270 12 0 0 15 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68494414 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=878988 CPUtime=2.7
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3731 0 0 0 269 1 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=878988 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 11 0 68494417 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=878988 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68494882 900083712 81016 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 72.6
Current children cumulated vsize (KiB) 884128

[startup+73.5057 s]
/proc/loadavg: 1.08 1.11 0.97 2/52 11638
/proc/meminfo: memFree=5484/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=879312 CPUtime=73.1
/proc/11624/stat : 11624 (java) S 11612 11610 1684 34817 1684 4202496 85604 0 1 0 7270 40 0 0 25 0 12 0 68494412 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 219828 81028 1788 10 0 216919 0
[pid=11624/tid=11625] ppid=11612 vsize=879312 CPUtime=67.08
/proc/11624/task/11625/stat : 11625 (java) S 11612 11610 1684 34817 1684 4202560 50271 0 1 0 6686 22 0 0 25 0 12 0 68494412 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11626] ppid=11612 vsize=879312 CPUtime=2.82
/proc/11624/task/11626/stat : 11626 (java) S 11612 11610 1684 34817 1684 4202560 23494 0 0 0 270 12 0 0 16 0 12 0 68494414 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 0 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11627] ppid=11612 vsize=879312 CPUtime=0
/proc/11624/task/11627/stat : 11627 (java) S 11612 11610 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 12 0 68494414 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11628] ppid=11612 vsize=879312 CPUtime=0
/proc/11624/task/11628/stat : 11628 (java) S 11612 11610 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 12 0 68494414 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11629] ppid=11612 vsize=879312 CPUtime=0
/proc/11624/task/11629/stat : 11629 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 12 0 68494417 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11630] ppid=11612 vsize=879312 CPUtime=2.73
/proc/11624/task/11630/stat : 11630 (java) S 11612 11610 1684 34817 1684 4202560 3731 0 0 0 272 1 0 0 16 0 12 0 68494417 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11631] ppid=11612 vsize=879312 CPUtime=0.44
/proc/11624/task/11631/stat : 11631 (java) S 11612 11610 1684 34817 1684 4202560 7024 0 0 0 41 3 0 0 23 0 12 0 68494417 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11632] ppid=11612 vsize=879312 CPUtime=0
/proc/11624/task/11632/stat : 11632 (java) S 11612 11610 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 12 0 68494417 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11633] ppid=11612 vsize=879312 CPUtime=0
/proc/11624/task/11633/stat : 11633 (java) S 11612 11610 1684 34817 1684 4202560 2 0 0 0 0 0 0 0 15 0 12 0 68494417 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
[pid=11624/tid=11634] ppid=11612 vsize=879312 CPUtime=0
/proc/11624/task/11634/stat : 11634 (java) S 11612 11610 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 12 0 68494882 900415488 81028 1283457024 134512640 134550932 4294604912 18446744073709551615 4294960130 0 4 0 16800975 18446612133131620608 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 73.13
Current children cumulated vsize (KiB) 884452

[startup+73.9075 s]
/proc/loadavg: 1.08 1.11 0.97 1/53 11639
/proc/meminfo: memFree=5476/1048576 swapFree=0/0
[pid=11610] ppid=11609 vsize=2568 CPUtime=0
/proc/11610/stat : 11610 (p2cudf-trendy-1) S 11609 11610 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 25 0 1 0 68494408 2629632 270 1283457024 134512640 135304128 4287253216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11610/statm: 642 270 230 194 0 29 0
[pid=11612] ppid=11610 vsize=2572 CPUtime=0.03
/proc/11612/stat : 11612 (p2cudf) S 11610 11610 1684 34817 1684 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 68494408 2633728 280 1283457024 134512640 135304128 4294709744 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11612/statm: 643 280 239 194 0 30 0
[pid=11624] ppid=11612 vsize=0 CPUtime=73.22
/proc/11624/stat : 11624 (java) Z 11612 11610 1684 34817 1684 4203532 85611 0 1 0 7278 44 0 0 18 0 2 0 68494412 0 0 1283457024 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11624/statm: 0 0 0 0 0 0 0
[pid=11624/tid=11625] ppid=11612 vsize=0 CPUtime=67.1
/proc/11624/task/11625/stat : 11625 (java) R 11612 11610 1684 34817 1684 4203588 50271 0 1 0 6686 24 0 0 19 0 2 0 68494412 0 0 1283457024 0 0 0 0 0 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 73.25
Current children cumulated vsize (KiB) 5140

Child status: 0
Real time (s): 73.9241
CPU time (s): 73.2726
CPU user time (s): 72.8005
CPU system time (s): 0.472029
CPU usage (%): 99.1186
Max. virtual memory (cumulated for all children) (KiB): 886644

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 72.8005
system time used= 0.472029
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 89409
page faults= 2
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 2201
involuntary context switches= 2936

runsolver used 0 second user time and 0 second system time

The end