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/201012070034/p2cudf-trendy-1.9/218091ce-4bcc-11df-bc4e-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/debian-dudf//218091ce-4bcc-11df-bc4e-00163e7a6f5e.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/218091ce-4bcc-11df-bc4e-00163e7a6f5e.cudf.debian-dudf.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
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 1.00 0.98 0.99 4/43 9685
/proc/meminfo: memFree=621312/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2576 CPUtime=0
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 358 0 0 0 0 0 0 0 25 0 1 0 878996001 2637824 275 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0
/proc/9683/statm: 644 275 234 194 0 30 0
[pid=9684] ppid=9683 vsize=2576 CPUtime=0
/proc/9684/stat : 9684 (p2cudf) R 9683 9682 4159 34819 4229 4202560 92 0 0 0 0 0 0 0 25 0 1 0 878996001 2637824 117 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/9684/statm: 644 117 75 194 0 30 0
[pid=9685] ppid=9684 vsize=2576 CPUtime=0
/proc/9685/stat : 9685 (p2cudf) R 9684 9682 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 1 0 878996001 2637824 42 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/9685/statm: 644 42 0 194 0 30 0

[startup+0.124404 s]
/proc/loadavg: 1.00 0.98 0.99 4/43 9685
/proc/meminfo: memFree=621312/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+0.204428 s]
/proc/loadavg: 1.00 0.98 0.99 4/43 9685
/proc/meminfo: memFree=621312/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+0.304455 s]
/proc/loadavg: 1.00 0.98 0.99 4/43 9685
/proc/meminfo: memFree=621312/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+0.704598 s]
/proc/loadavg: 1.00 0.98 0.99 4/43 9685
/proc/meminfo: memFree=621312/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+1.50483 s]
/proc/loadavg: 1.00 0.98 0.99 4/52 9705
/proc/meminfo: memFree=579608/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=878144 CPUtime=1.45
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 19198 0 1 0 138 7 0 0 25 0 10 0 878996004 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219536 16312 1729 10 0 216626 0
[pid=9696/tid=9697] ppid=9683 vsize=878144 CPUtime=0.76
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 9906 0 1 0 72 4 0 0 25 0 10 0 878996005 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4149439901 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=878144 CPUtime=0
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 18 0 10 0 878996007 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=878144 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 878996008 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=878144 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 878996008 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=878144 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 878996011 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=878144 CPUtime=0.48
/proc/9696/task/9702/stat : 9702 (java) R 9683 9682 4159 34819 4229 4202560 6505 0 0 0 47 1 0 0 25 0 10 0 878996011 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=878144 CPUtime=0.18
/proc/9696/task/9703/stat : 9703 (java) R 9683 9682 4159 34819 4229 4202560 1674 0 0 0 18 0 0 0 21 0 10 0 878996011 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4147795120 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=878144 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 878996011 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=878144 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 878996011 899219456 16312 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.47
Current children cumulated vsize (KiB) 883296

[startup+3.10535 s]
/proc/loadavg: 1.00 0.98 0.99 3/52 9705
/proc/meminfo: memFree=515872/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=878580 CPUtime=3.05
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 54376 0 1 0 286 19 0 0 25 0 10 0 878996004 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219645 48525 1732 10 0 216735 0
[pid=9696/tid=9697] ppid=9683 vsize=878580 CPUtime=1.62
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 41459 0 1 0 148 14 0 0 25 0 10 0 878996005 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4095744926 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=878580 CPUtime=0
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 18 0 10 0 878996007 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=878580 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 878996008 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=878580 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 878996008 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=878580 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 878996011 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=878580 CPUtime=0.91
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 8832 0 0 0 88 3 0 0 25 0 10 0 878996011 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=878580 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 10 0 878996011 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=878580 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 878996011 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=878580 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 878996011 899665920 48525 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.07
Current children cumulated vsize (KiB) 883732

[startup+6.30631 s]
/proc/loadavg: 1.08 1.00 0.99 3/52 9705
/proc/meminfo: memFree=319892/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=878524 CPUtime=6.25
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 91365 0 1 0 593 32 0 0 25 0 10 0 878996004 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219631 83368 1766 10 0 216721 0
[pid=9696/tid=9697] ppid=9683 vsize=878524 CPUtime=2.79
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 50412 0 1 0 262 17 0 0 25 0 10 0 878996005 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4150008052 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=878524 CPUtime=0.58
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 25785 0 0 0 48 10 0 0 16 0 10 0 878996007 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=878524 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 10 0 878996008 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=878524 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 10 0 878996008 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=878524 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 878996011 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=878524 CPUtime=2.35
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11085 0 0 0 232 3 0 0 15 0 10 0 878996011 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=878524 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 10 0 878996011 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=878524 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 878996011 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=878524 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 878996011 899608576 83368 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.27
Current children cumulated vsize (KiB) 883676

[startup+12.7085 s]
/proc/loadavg: 1.07 1.00 0.99 3/53 9708
/proc/meminfo: memFree=269052/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=878848 CPUtime=12.64
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 97553 0 1 0 1230 34 0 0 25 0 11 0 878996004 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219712 89556 1792 10 0 216802 0
[pid=9696/tid=9697] ppid=9683 vsize=878848 CPUtime=7.9
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 50473 0 1 0 773 17 0 0 25 0 11 0 878996005 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4096917368 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=878848 CPUtime=0.82
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 31763 0 0 0 71 11 0 0 16 0 11 0 878996007 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=878848 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 878996008 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=878848 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 878996008 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=878848 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=878848 CPUtime=3.4
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11230 0 0 0 336 4 0 0 15 0 11 0 878996011 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=878848 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=878848 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=878848 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9708] ppid=9683 vsize=878848 CPUtime=0
/proc/9696/task/9708/stat : 9708 (java) S 9683 9682 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 878997171 899940352 89556 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.66
Current children cumulated vsize (KiB) 884000

[startup+25.5225 s]
/proc/loadavg: 1.06 1.00 0.99 3/53 9719
/proc/meminfo: memFree=234332/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=879496 CPUtime=25.45
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 106191 0 1 0 2509 36 0 0 25 0 11 0 878996004 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219874 98194 1793 10 0 216964 0
[pid=9696/tid=9697] ppid=9683 vsize=879496 CPUtime=19.93
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 50477 0 1 0 1976 17 0 0 25 0 11 0 878996005 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=879496 CPUtime=1.35
/proc/9696/task/9698/stat : 9698 (java) R 9683 9682 4159 34819 4229 4202560 40388 0 0 0 122 13 0 0 15 0 11 0 878996007 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4150232472 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 878996008 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 878996008 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=879496 CPUtime=3.64
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11231 0 0 0 360 4 0 0 16 0 11 0 878996011 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=879496 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) R 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9719] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9719/stat : 9719 (java) S 9683 9682 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 11 0 878998282 900603904 98194 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.47
Current children cumulated vsize (KiB) 884648

[startup+51.1118 s]
/proc/loadavg: 1.04 1.00 0.99 2/53 9744
/proc/meminfo: memFree=232100/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=879496 CPUtime=50.99
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 106736 0 1 0 5062 37 0 0 25 0 11 0 878996004 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219874 98739 1793 10 0 216964 0
[pid=9696/tid=9697] ppid=9683 vsize=879496 CPUtime=43.74
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 50477 0 1 0 4357 17 0 0 25 0 11 0 878996005 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4096765017 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=879496 CPUtime=2.78
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 40928 0 0 0 264 14 0 0 15 0 11 0 878996007 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=879496 CPUtime=3.95
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11236 0 0 0 391 4 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=879496 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9744] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9744/stat : 9744 (java) S 9683 9682 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 879000295 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.01
Current children cumulated vsize (KiB) 884648

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

[startup+57.506 s]
/proc/loadavg: 1.03 1.00 0.99 2/53 9744
/proc/meminfo: memFree=232100/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=879496 CPUtime=57.36
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 106736 0 1 0 5699 37 0 0 25 0 11 0 878996004 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219874 98739 1793 10 0 216964 0
[pid=9696/tid=9697] ppid=9683 vsize=879496 CPUtime=50.1
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 50477 0 1 0 4993 17 0 0 25 0 11 0 878996005 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4095716047 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=879496 CPUtime=2.78
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 40928 0 0 0 264 14 0 0 15 0 11 0 878996007 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=879496 CPUtime=3.95
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11236 0 0 0 391 4 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=879496 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9744] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9744/stat : 9744 (java) S 9683 9682 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 879000295 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 57.38
Current children cumulated vsize (KiB) 884648

[startup+59.1065 s]
/proc/loadavg: 1.03 1.00 0.99 2/53 9744
/proc/meminfo: memFree=232100/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=879496 CPUtime=58.95
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 106736 0 1 0 5858 37 0 0 25 0 11 0 878996004 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219874 98739 1793 10 0 216964 0
[pid=9696/tid=9697] ppid=9683 vsize=879496 CPUtime=51.7
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 50477 0 1 0 5153 17 0 0 25 0 11 0 878996005 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4096911209 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=879496 CPUtime=2.78
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 40928 0 0 0 264 14 0 0 15 0 11 0 878996007 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=879496 CPUtime=3.95
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11236 0 0 0 391 4 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=879496 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9744] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9744/stat : 9744 (java) S 9683 9682 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 879000295 900603904 98739 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 58.97
Current children cumulated vsize (KiB) 884648

[startup+59.9068 s]
/proc/loadavg: 1.03 1.00 0.99 2/53 9745
/proc/meminfo: memFree=232100/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=879496 CPUtime=59.76
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 106738 0 1 0 5939 37 0 0 25 0 11 0 878996004 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219874 98741 1793 10 0 216964 0
[pid=9696/tid=9697] ppid=9683 vsize=879496 CPUtime=52.46
/proc/9696/task/9697/stat : 9697 (java) R 9683 9682 4159 34819 4229 4202560 50477 0 1 0 5229 17 0 0 25 0 11 0 878996005 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4095975719 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=879496 CPUtime=2.79
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 40928 0 0 0 265 14 0 0 16 0 11 0 878996007 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=879496 CPUtime=3.98
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11238 0 0 0 394 4 0 0 15 0 11 0 878996011 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=879496 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 900603904 98741 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 59.78
Current children cumulated vsize (KiB) 884648

[startup+60.6075 s]
/proc/loadavg: 1.03 1.00 0.99 1/53 9748
/proc/meminfo: memFree=231976/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=879496 CPUtime=59.98
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 106748 0 1 0 5961 37 0 0 25 0 11 0 878996004 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219874 98751 1793 10 0 216964 0
[pid=9696/tid=9697] ppid=9683 vsize=879496 CPUtime=52.63
/proc/9696/task/9697/stat : 9697 (java) S 9683 9682 4159 34819 4229 4202560 50483 0 1 0 5246 17 0 0 25 0 11 0 878996005 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=879496 CPUtime=2.79
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 40928 0 0 0 265 14 0 0 16 0 11 0 878996007 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=879496 CPUtime=4.04
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11242 0 0 0 400 4 0 0 16 0 11 0 878996011 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=879496 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9748] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9748/stat : 9748 (java) S 9683 9682 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 879002015 900603904 98751 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 60
Current children cumulated vsize (KiB) 884648

[startup+60.8116 s]
/proc/loadavg: 1.03 1.00 0.99 1/53 9748
/proc/meminfo: memFree=231976/1048576 swapFree=2080288/2097144
[pid=9682] ppid=9681 vsize=2572 CPUtime=0
/proc/9682/stat : 9682 (p2cudf-trendy-1) S 9681 9682 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 878996001 2633728 274 1283457024 134512640 135304128 4287927104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9682/statm: 643 274 233 194 0 29 0
[pid=9683] ppid=9682 vsize=2580 CPUtime=0.02
/proc/9683/stat : 9683 (p2cudf) S 9682 9682 4159 34819 4229 4202496 395 2997 0 1 0 0 2 0 25 0 1 0 878996001 2641920 283 1283457024 134512640 135304128 4291897072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9683/statm: 645 283 241 194 0 31 0
[pid=9696] ppid=9683 vsize=879496 CPUtime=60.14
/proc/9696/stat : 9696 (java) S 9683 9682 4159 34819 4229 4202496 106753 0 1 0 5973 41 0 0 25 0 11 0 878996004 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9696/statm: 219874 98756 1793 10 0 216964 0
[pid=9696/tid=9697] ppid=9683 vsize=879496 CPUtime=52.63
/proc/9696/task/9697/stat : 9697 (java) S 9683 9682 4159 34819 4229 4202560 50483 0 1 0 5246 17 0 0 25 0 11 0 878996005 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9698] ppid=9683 vsize=879496 CPUtime=2.79
/proc/9696/task/9698/stat : 9698 (java) S 9683 9682 4159 34819 4229 4202560 40928 0 0 0 265 14 0 0 16 0 11 0 878996007 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9699] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9699/stat : 9699 (java) S 9683 9682 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9700] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9700/stat : 9700 (java) S 9683 9682 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 878996008 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9701] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9701/stat : 9701 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9702] ppid=9683 vsize=879496 CPUtime=4.08
/proc/9696/task/9702/stat : 9702 (java) S 9683 9682 4159 34819 4229 4202560 11245 0 0 0 404 4 0 0 15 0 11 0 878996011 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9703] ppid=9683 vsize=879496 CPUtime=0.5
/proc/9696/task/9703/stat : 9703 (java) S 9683 9682 4159 34819 4229 4202560 2971 0 0 0 50 0 0 0 15 0 11 0 878996011 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9704] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9704/stat : 9704 (java) S 9683 9682 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 878996011 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9705] ppid=9683 vsize=879496 CPUtime=0
/proc/9696/task/9705/stat : 9705 (java) S 9683 9682 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 878996011 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0
[pid=9696/tid=9748] ppid=9683 vsize=879496 CPUtime=0.11
/proc/9696/task/9748/stat : 9748 (java) R 9683 9682 4159 34819 4229 4202560 2 0 0 0 7 4 0 0 21 0 11 0 879002015 900603904 98756 1283457024 134512640 134550932 4291888528 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 60.16
Current children cumulated vsize (KiB) 884648

Child status: 0
Real time (s): 60.8711
CPU time (s): 60.2158
CPU user time (s): 59.7717
CPU system time (s): 0.444027
CPU usage (%): 98.9234
Max. virtual memory (cumulated for all children) (KiB): 885692

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 59.7717
system time used= 0.444027
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 110549
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= 3066
involuntary context switches= 3421

runsolver used 0 second user time and 0.004 second system time

The end