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/rand601.cudf.heavy.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/heavy//rand601.cudf /home/misc2010/tmp/201103021629/p2cudf-trendy-1.12/rand601.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
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 1.00 1.04 1.00 5/40 15321
/proc/meminfo: memFree=302032/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2564 CPUtime=0
/proc/15320/stat : 15320 (p2cudf) R 15319 15319 1684 34817 1684 4202496 345 0 0 0 0 0 0 0 25 0 1 0 68575154 2625536 271 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/15320/statm: 641 271 231 194 0 28 0
[pid=15321] ppid=15320 vsize=2564 CPUtime=0
/proc/15321/stat : 15321 (p2cudf) R 15320 15319 1684 34817 1684 4202560 0 0 0 0 0 0 0 0 25 0 1 0 68575155 2625536 40 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/15321/statm: 641 40 0 194 0 28 0

[startup+0.169314 s]
/proc/loadavg: 1.00 1.04 1.00 5/40 15321
/proc/meminfo: memFree=302032/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.209315 s]
/proc/loadavg: 1.00 1.04 1.00 5/40 15321
/proc/meminfo: memFree=302032/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.309351 s]
/proc/loadavg: 1.00 1.04 1.00 5/40 15321
/proc/meminfo: memFree=302032/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.709466 s]
/proc/loadavg: 1.00 1.04 1.00 5/40 15321
/proc/meminfo: memFree=302032/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+1.50973 s]
/proc/loadavg: 1.00 1.04 1.00 4/50 15342
/proc/meminfo: memFree=248376/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=884196 CPUtime=1.44
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 22626 0 1 0 132 12 0 0 25 0 10 0 68575159 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 221049 19195 1724 10 0 218140 0
[pid=15333/tid=15334] ppid=15320 vsize=884196 CPUtime=0.8
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 11283 0 1 0 70 10 0 0 25 0 10 0 68575160 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4095227745 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=884196 CPUtime=0
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 7 0 0 0 0 0 0 0 18 0 10 0 68575162 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=884196 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 8 0 0 0 0 0 0 0 25 0 10 0 68575162 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=884196 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 10 0 68575162 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=884196 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68575165 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=884196 CPUtime=0.44
/proc/15333/task/15339/stat : 15339 (java) R 15320 15319 1684 34817 1684 4202560 8510 0 0 0 42 2 0 0 25 0 10 0 68575165 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=884196 CPUtime=0.2
/proc/15333/task/15340/stat : 15340 (java) R 15320 15319 1684 34817 1684 4202560 1754 0 0 0 20 0 0 0 18 0 10 0 68575165 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4152353279 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=884196 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68575165 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=884196 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 10 0 68575165 905416704 19195 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.47
Current children cumulated vsize (KiB) 889328

[startup+3.11016 s]
/proc/loadavg: 1.08 1.06 1.01 4/50 15342
/proc/meminfo: memFree=155252/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=882168 CPUtime=3.05
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 69362 0 1 0 275 30 0 0 25 0 10 0 68575159 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220542 65325 1742 10 0 217633 0
[pid=15333/tid=15334] ppid=15320 vsize=882168 CPUtime=1.61
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50069 0 1 0 137 24 0 0 25 0 10 0 68575160 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=882168 CPUtime=0.16
/proc/15333/task/15335/stat : 15335 (java) R 15320 15319 1684 34817 1684 4202560 7356 0 0 0 16 0 0 0 18 0 10 0 68575162 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4149367160 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=882168 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 8 0 0 0 0 0 0 0 25 0 10 0 68575162 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=882168 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 10 0 68575162 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=882168 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68575165 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=882168 CPUtime=0.79
/proc/15333/task/15339/stat : 15339 (java) R 15320 15319 1684 34817 1684 4202560 8587 0 0 0 77 2 0 0 21 0 10 0 68575165 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4150103880 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=882168 CPUtime=0.46
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 2278 0 0 0 44 2 0 0 16 0 10 0 68575165 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=882168 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 10 0 68575165 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=882168 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) R 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 10 0 68575165 903340032 65325 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 887300

[startup+6.3111 s]
/proc/loadavg: 1.08 1.06 1.01 2/51 15343
/proc/meminfo: memFree=24424/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=882688 CPUtime=6.25
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 76599 0 1 0 591 34 0 0 25 0 11 0 68575159 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220672 70950 1779 10 0 217763 0
[pid=15333/tid=15334] ppid=15320 vsize=882688 CPUtime=3.4
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50261 0 1 0 314 26 0 0 25 0 11 0 68575160 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4096141696 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=882688 CPUtime=0.28
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 12535 0 0 0 26 2 0 0 16 0 11 0 68575162 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=882688 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68575162 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=882688 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68575162 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=882688 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=882688 CPUtime=1.65
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8753 0 0 0 163 2 0 0 15 0 11 0 68575165 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=882688 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 11 0 68575165 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=882688 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=882688 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 11 0 68575165 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=882688 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68575618 903872512 70950 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.28
Current children cumulated vsize (KiB) 887820

[startup+12.7128 s]
/proc/loadavg: 1.07 1.06 1.00 2/51 15343
/proc/meminfo: memFree=5884/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881012 CPUtime=12.65
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86211 0 1 0 1228 37 0 0 25 0 11 0 68575159 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220253 80173 1788 10 0 217344 0
[pid=15333/tid=15334] ppid=15320 vsize=881012 CPUtime=9.15
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50263 0 1 0 889 26 0 0 25 0 11 0 68575160 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4096259129 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881012 CPUtime=0.68
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22110 0 0 0 63 5 0 0 15 0 11 0 68575162 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881012 CPUtime=1.91
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8788 0 0 0 189 2 0 0 16 0 11 0 68575165 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881012 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 11 0 68575165 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 11 0 68575165 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68575618 902156288 80173 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.68
Current children cumulated vsize (KiB) 886144

[startup+25.507 s]
/proc/loadavg: 1.06 1.05 1.00 2/51 15343
/proc/meminfo: memFree=5980/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881012 CPUtime=25.42
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86868 0 1 0 2504 38 0 0 25 0 11 0 68575159 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220253 80830 1788 10 0 217344 0
[pid=15333/tid=15334] ppid=15320 vsize=881012 CPUtime=20.99
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50263 0 1 0 2073 26 0 0 25 0 11 0 68575160 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4096292242 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881012 CPUtime=1.52
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22757 0 0 0 146 6 0 0 16 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881012 CPUtime=2
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8798 0 0 0 198 2 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881012 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68575618 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.45
Current children cumulated vsize (KiB) 886144

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

[startup+25.607 s]
/proc/loadavg: 1.06 1.05 1.00 2/51 15343
/proc/meminfo: memFree=5980/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881012 CPUtime=25.52
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86868 0 1 0 2514 38 0 0 25 0 11 0 68575159 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220253 80830 1788 10 0 217344 0
[pid=15333/tid=15334] ppid=15320 vsize=881012 CPUtime=21.09
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50263 0 1 0 2083 26 0 0 25 0 11 0 68575160 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4096189462 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881012 CPUtime=1.52
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22757 0 0 0 146 6 0 0 16 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881012 CPUtime=2
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8798 0 0 0 198 2 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881012 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68575618 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.55
Current children cumulated vsize (KiB) 886144

[startup+28.8079 s]
/proc/loadavg: 1.05 1.05 1.00 2/51 15343
/proc/meminfo: memFree=5980/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881012 CPUtime=28.72
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86868 0 1 0 2834 38 0 0 25 0 11 0 68575159 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220253 80830 1788 10 0 217344 0
[pid=15333/tid=15334] ppid=15320 vsize=881012 CPUtime=24.29
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50263 0 1 0 2403 26 0 0 25 0 11 0 68575160 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4096292168 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881012 CPUtime=1.52
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22757 0 0 0 146 6 0 0 15 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881012 CPUtime=2
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8798 0 0 0 198 2 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881012 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68575618 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 28.75
Current children cumulated vsize (KiB) 886144

[startup+30.4083 s]
/proc/loadavg: 1.05 1.05 1.00 3/51 15343
/proc/meminfo: memFree=5856/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881012 CPUtime=30.32
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86868 0 1 0 2994 38 0 0 25 0 11 0 68575159 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220253 80830 1788 10 0 217344 0
[pid=15333/tid=15334] ppid=15320 vsize=881012 CPUtime=25.89
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50263 0 1 0 2563 26 0 0 25 0 11 0 68575160 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4096259114 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881012 CPUtime=1.52
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22757 0 0 0 146 6 0 0 15 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881012 CPUtime=2
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8798 0 0 0 198 2 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881012 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68575618 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 30.35
Current children cumulated vsize (KiB) 886144

[startup+32.0086 s]
/proc/loadavg: 1.05 1.05 1.00 2/51 15343
/proc/meminfo: memFree=5856/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881012 CPUtime=31.92
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86868 0 1 0 3154 38 0 0 25 0 11 0 68575159 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220253 80830 1788 10 0 217344 0
[pid=15333/tid=15334] ppid=15320 vsize=881012 CPUtime=27.49
/proc/15333/task/15334/stat : 15334 (java) R 15320 15319 1684 34817 1684 4202560 50263 0 1 0 2723 26 0 0 25 0 11 0 68575160 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4096132847 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881012 CPUtime=1.52
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22757 0 0 0 146 6 0 0 15 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 11 0 68575162 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881012 CPUtime=2
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8798 0 0 0 198 2 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881012 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 11 0 68575165 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881012 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 11 0 68575618 902156288 80830 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 31.95
Current children cumulated vsize (KiB) 886144

[startup+32.4074 s]
/proc/loadavg: 1.05 1.05 1.00 2/51 15343
/proc/meminfo: memFree=5856/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881336 CPUtime=32.15
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86879 0 1 0 3176 39 0 0 25 0 12 0 68575159 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220334 80841 1788 10 0 217425 0
[pid=15333/tid=15334] ppid=15320 vsize=881336 CPUtime=27.67
/proc/15333/task/15334/stat : 15334 (java) S 15320 15319 1684 34817 1684 4202560 50270 0 1 0 2741 26 0 0 25 0 12 0 68575160 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881336 CPUtime=1.52
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22757 0 0 0 146 6 0 0 16 0 12 0 68575162 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 12 0 68575162 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 12 0 68575162 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 12 0 68575165 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881336 CPUtime=2.03
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8798 0 0 0 201 2 0 0 15 0 12 0 68575165 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881336 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 12 0 68575165 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 12 0 68575165 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 12 0 68575165 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 12 0 68575618 902488064 80841 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 32.18
Current children cumulated vsize (KiB) 886468

[startup+32.8113 s]
/proc/loadavg: 1.05 1.05 1.00 2/51 15343
/proc/meminfo: memFree=5856/1048576 swapFree=0/0
[pid=15319] ppid=15318 vsize=2564 CPUtime=0
/proc/15319/stat : 15319 (p2cudf-trendy-1) S 15318 15319 1684 34817 1684 4202496 374 0 0 0 0 0 0 0 18 0 1 0 68575154 2625536 270 1283457024 134512640 135304128 4288666672 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15319/statm: 641 270 230 194 0 28 0
[pid=15320] ppid=15319 vsize=2568 CPUtime=0.03
/proc/15320/stat : 15320 (p2cudf) S 15319 15319 1684 34817 1684 4202496 391 3010 0 1 0 0 3 0 25 0 1 0 68575154 2629632 280 1283457024 134512640 135304128 4288523264 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15320/statm: 642 280 239 194 0 29 0
[pid=15333] ppid=15320 vsize=881336 CPUtime=32.22
/proc/15333/stat : 15333 (java) S 15320 15319 1684 34817 1684 4202496 86881 0 1 0 3182 40 0 0 25 0 12 0 68575159 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15333/statm: 220334 80843 1788 10 0 217425 0
[pid=15333/tid=15334] ppid=15320 vsize=881336 CPUtime=27.67
/proc/15333/task/15334/stat : 15334 (java) S 15320 15319 1684 34817 1684 4202560 50270 0 1 0 2741 26 0 0 25 0 12 0 68575160 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15335] ppid=15320 vsize=881336 CPUtime=1.52
/proc/15333/task/15335/stat : 15335 (java) S 15320 15319 1684 34817 1684 4202560 22757 0 0 0 146 6 0 0 16 0 12 0 68575162 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 0 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15336] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15336/stat : 15336 (java) S 15320 15319 1684 34817 1684 4202560 9 0 0 0 0 0 0 0 18 0 12 0 68575162 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15337] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15337/stat : 15337 (java) S 15320 15319 1684 34817 1684 4202560 6 0 0 0 0 0 0 0 18 0 12 0 68575162 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15338] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15338/stat : 15338 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 12 0 68575165 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15339] ppid=15320 vsize=881336 CPUtime=2.04
/proc/15333/task/15339/stat : 15339 (java) S 15320 15319 1684 34817 1684 4202560 8798 0 0 0 202 2 0 0 15 0 12 0 68575165 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15340] ppid=15320 vsize=881336 CPUtime=0.89
/proc/15333/task/15340/stat : 15340 (java) S 15320 15319 1684 34817 1684 4202560 3971 0 0 0 86 3 0 0 15 0 12 0 68575165 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15341] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15341/stat : 15341 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 25 0 12 0 68575165 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15342] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15342/stat : 15342 (java) S 15320 15319 1684 34817 1684 4202560 1 0 0 0 0 0 0 0 15 0 12 0 68575165 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
[pid=15333/tid=15343] ppid=15320 vsize=881336 CPUtime=0
/proc/15333/task/15343/stat : 15343 (java) S 15320 15319 1684 34817 1684 4202560 4 0 0 0 0 0 0 0 25 0 12 0 68575618 902488064 80843 1283457024 134512640 134550932 4292968144 18446744073709551615 4294960130 0 4 0 16800975 18446612133131621504 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 32.25
Current children cumulated vsize (KiB) 886468

Child status: 0
Real time (s): 32.8855
CPU time (s): 32.298
CPU user time (s): 31.862
CPU system time (s): 0.436027
CPU usage (%): 98.2135
Max. virtual memory (cumulated for all children) (KiB): 891156

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 31.862
system time used= 0.436027
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 90682
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= 1405
involuntary context switches= 1672

runsolver used 0 second user time and 0 second system time

The end