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/201012061533/p2cudf-paranoid-1.9/rand13b58e.cudf.easy.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/2010/easy//rand13b58e.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/rand13b58e.cudf.easy.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.35 1.17 1.11 5/56 5567
/proc/meminfo: memFree=689836/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2576 CPUtime=0
/proc/5566/stat : 5566 (p2cudf) R 5565 5565 2059 34821 2220 4202496 350 0 0 0 0 0 0 0 25 0 1 0 874557242 2637824 275 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/5566/statm: 644 275 234 194 0 30 0
[pid=5567] ppid=5566 vsize=2576 CPUtime=0
/proc/5567/stat : 5567 (p2cudf) R 5566 5565 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 874557243 2637824 41 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/5567/statm: 644 41 0 194 0 30 0

[startup+0.126406 s]
/proc/loadavg: 1.35 1.17 1.11 5/56 5567
/proc/meminfo: memFree=689836/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5156

[startup+0.206425 s]
/proc/loadavg: 1.35 1.17 1.11 5/56 5567
/proc/meminfo: memFree=689836/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5156

[startup+0.306437 s]
/proc/loadavg: 1.35 1.17 1.11 5/56 5567
/proc/meminfo: memFree=689836/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5156

[startup+0.706505 s]
/proc/loadavg: 1.35 1.17 1.11 5/56 5567
/proc/meminfo: memFree=689836/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5156

[startup+1.50666 s]
/proc/loadavg: 1.35 1.17 1.11 4/66 5588
/proc/meminfo: memFree=650692/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
[pid=5579] ppid=5566 vsize=874652 CPUtime=1.46
/proc/5579/stat : 5579 (java) S 5566 5565 2059 34821 2220 4202496 19335 0 1 0 140 6 0 0 25 0 10 0 874557246 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5579/statm: 218663 16799 1732 10 0 215753 0
[pid=5579/tid=5580] ppid=5566 vsize=874652 CPUtime=0.79
/proc/5579/task/5580/stat : 5580 (java) R 5566 5565 2059 34821 2220 4202560 11256 0 1 0 76 3 0 0 25 0 10 0 874557247 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4152552894 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5581] ppid=5566 vsize=874652 CPUtime=0
/proc/5579/task/5581/stat : 5581 (java) S 5566 5565 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 874557248 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5582] ppid=5566 vsize=874652 CPUtime=0
/proc/5579/task/5582/stat : 5582 (java) S 5566 5565 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 874557249 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5583] ppid=5566 vsize=874652 CPUtime=0
/proc/5579/task/5583/stat : 5583 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 874557249 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5584] ppid=5566 vsize=874652 CPUtime=0
/proc/5579/task/5584/stat : 5584 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874557251 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5585] ppid=5566 vsize=874652 CPUtime=0.38
/proc/5579/task/5585/stat : 5585 (java) R 5566 5565 2059 34821 2220 4202560 6146 0 0 0 37 1 0 0 23 0 10 0 874557251 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4149686048 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5586] ppid=5566 vsize=874652 CPUtime=0.28
/proc/5579/task/5586/stat : 5586 (java) R 5566 5565 2059 34821 2220 4202560 818 0 0 0 26 2 0 0 25 0 10 0 874557251 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4149202627 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5587] ppid=5566 vsize=874652 CPUtime=0
/proc/5579/task/5587/stat : 5587 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874557251 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5588] ppid=5566 vsize=874652 CPUtime=0
/proc/5579/task/5588/stat : 5588 (java) S 5566 5565 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 874557251 895643648 16799 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 879808

[startup+3.10702 s]
/proc/loadavg: 1.35 1.17 1.11 4/66 5588
/proc/meminfo: memFree=583900/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
[pid=5579] ppid=5566 vsize=894544 CPUtime=3.05
/proc/5579/stat : 5579 (java) S 5566 5565 2059 34821 2220 4202496 48910 0 1 0 288 17 0 0 25 0 10 0 874557246 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5579/statm: 223636 42766 1745 10 0 220726 0
[pid=5579/tid=5580] ppid=5566 vsize=894544 CPUtime=1.43
/proc/5579/task/5580/stat : 5580 (java) R 5566 5565 2059 34821 2220 4202560 31952 0 1 0 132 11 0 0 25 0 10 0 874557247 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5581] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5581/stat : 5581 (java) S 5566 5565 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 874557248 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5582] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5582/stat : 5582 (java) S 5566 5565 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 874557249 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5583] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5583/stat : 5583 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 874557249 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5584] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5584/stat : 5584 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874557251 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5585] ppid=5566 vsize=894544 CPUtime=1.01
/proc/5579/task/5585/stat : 5585 (java) R 5566 5565 2059 34821 2220 4202560 11701 0 0 0 98 3 0 0 22 0 10 0 874557251 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4151285838 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5586] ppid=5566 vsize=894544 CPUtime=0.59
/proc/5579/task/5586/stat : 5586 (java) S 5566 5565 2059 34821 2220 4202560 4142 0 0 0 57 2 0 0 25 0 10 0 874557251 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5587] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5587/stat : 5587 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874557251 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5588] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5588/stat : 5588 (java) S 5566 5565 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 874557251 916013056 42766 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 899700

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

[startup+3.20704 s]
/proc/loadavg: 1.35 1.17 1.11 4/66 5588
/proc/meminfo: memFree=583900/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
[pid=5579] ppid=5566 vsize=894544 CPUtime=3.15
/proc/5579/stat : 5579 (java) S 5566 5565 2059 34821 2220 4202496 49158 0 1 0 298 17 0 0 25 0 10 0 874557246 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5579/statm: 223636 42881 1745 10 0 220726 0
[pid=5579/tid=5580] ppid=5566 vsize=894544 CPUtime=1.43
/proc/5579/task/5580/stat : 5580 (java) R 5566 5565 2059 34821 2220 4202560 31967 0 1 0 132 11 0 0 25 0 10 0 874557247 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5581] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5581/stat : 5581 (java) S 5566 5565 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 874557248 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5582] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5582/stat : 5582 (java) S 5566 5565 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 874557249 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5583] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5583/stat : 5583 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 874557249 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5584] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5584/stat : 5584 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874557251 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5585] ppid=5566 vsize=894544 CPUtime=1.06
/proc/5579/task/5585/stat : 5585 (java) R 5566 5565 2059 34821 2220 4202560 11762 0 0 0 103 3 0 0 22 0 10 0 874557251 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4149686095 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5586] ppid=5566 vsize=894544 CPUtime=0.64
/proc/5579/task/5586/stat : 5586 (java) R 5566 5565 2059 34821 2220 4202560 4314 0 0 0 62 2 0 0 19 0 10 0 874557251 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4152826272 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5587] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5587/stat : 5587 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874557251 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5588] ppid=5566 vsize=894544 CPUtime=0
/proc/5579/task/5588/stat : 5588 (java) S 5566 5565 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 874557251 916013056 42881 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.18
Current children cumulated vsize (KiB) 899700

[startup+4.82045 s]
/proc/loadavg: 1.56 1.22 1.12 3/66 5590
/proc/meminfo: memFree=474044/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
[pid=5579] ppid=5566 vsize=894492 CPUtime=4.75
/proc/5579/stat : 5579 (java) S 5566 5565 2059 34821 2220 4202496 65649 0 1 0 455 20 0 0 25 0 11 0 874557246 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5579/statm: 223623 54857 1770 10 0 220713 0
[pid=5579/tid=5580] ppid=5566 vsize=894492 CPUtime=1.87
/proc/5579/task/5580/stat : 5580 (java) R 5566 5565 2059 34821 2220 4202560 43818 0 1 0 173 14 0 0 25 0 11 0 874557247 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5581] ppid=5566 vsize=894492 CPUtime=0
/proc/5579/task/5581/stat : 5581 (java) S 5566 5565 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 15 0 11 0 874557248 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5582] ppid=5566 vsize=894492 CPUtime=0
/proc/5579/task/5582/stat : 5582 (java) S 5566 5565 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 874557249 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5583] ppid=5566 vsize=894492 CPUtime=0
/proc/5579/task/5583/stat : 5583 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 874557249 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5584] ppid=5566 vsize=894492 CPUtime=0
/proc/5579/task/5584/stat : 5584 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5585] ppid=5566 vsize=894492 CPUtime=1.56
/proc/5579/task/5585/stat : 5585 (java) R 5566 5565 2059 34821 2220 4202560 14208 0 0 0 153 3 0 0 23 0 11 0 874557251 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4149154033 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5586] ppid=5566 vsize=894492 CPUtime=1.3
/proc/5579/task/5586/stat : 5586 (java) S 5566 5565 2059 34821 2220 4202560 6507 0 0 0 128 2 0 0 15 0 11 0 874557251 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5587] ppid=5566 vsize=894492 CPUtime=0
/proc/5579/task/5587/stat : 5587 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5588] ppid=5566 vsize=894492 CPUtime=0
/proc/5579/task/5588/stat : 5588 (java) S 5566 5565 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 874557251 915959808 54857 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.78
Current children cumulated vsize (KiB) 899648

[startup+5.60423 s]
/proc/loadavg: 1.56 1.22 1.12 1/67 5593
/proc/meminfo: memFree=463744/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
[pid=5579] ppid=5566 vsize=894816 CPUtime=5.22
/proc/5579/stat : 5579 (java) S 5566 5565 2059 34821 2220 4202496 66859 0 1 0 502 20 0 0 25 0 11 0 874557246 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5579/statm: 223704 56067 1787 10 0 220794 0
[pid=5579/tid=5580] ppid=5566 vsize=894816 CPUtime=1.99
/proc/5579/task/5580/stat : 5580 (java) S 5566 5565 2059 34821 2220 4202560 44970 0 1 0 185 14 0 0 25 0 11 0 874557247 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5581] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5581/stat : 5581 (java) S 5566 5565 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 15 0 11 0 874557248 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5582] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5582/stat : 5582 (java) S 5566 5565 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 874557249 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5583] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5583/stat : 5583 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 874557249 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5584] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5584/stat : 5584 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5585] ppid=5566 vsize=894816 CPUtime=1.57
/proc/5579/task/5585/stat : 5585 (java) S 5566 5565 2059 34821 2220 4202560 14209 0 0 0 154 3 0 0 23 0 11 0 874557251 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5586] ppid=5566 vsize=894816 CPUtime=1.64
/proc/5579/task/5586/stat : 5586 (java) S 5566 5565 2059 34821 2220 4202560 6557 0 0 0 162 2 0 0 16 0 11 0 874557251 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5587] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5587/stat : 5587 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5588] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5588/stat : 5588 (java) S 5566 5565 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 874557251 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5593] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5593/stat : 5593 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 874557772 916291584 56067 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.25
Current children cumulated vsize (KiB) 899972

[startup+5.81017 s]
/proc/loadavg: 1.56 1.22 1.12 1/67 5593
/proc/meminfo: memFree=463744/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
[pid=5579] ppid=5566 vsize=894816 CPUtime=5.22
/proc/5579/stat : 5579 (java) S 5566 5565 2059 34821 2220 4202496 66883 0 1 0 502 20 0 0 25 0 11 0 874557246 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5579/statm: 223704 56091 1787 10 0 220794 0
[pid=5579/tid=5580] ppid=5566 vsize=894816 CPUtime=1.99
/proc/5579/task/5580/stat : 5580 (java) S 5566 5565 2059 34821 2220 4202560 44970 0 1 0 185 14 0 0 25 0 11 0 874557247 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5581] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5581/stat : 5581 (java) S 5566 5565 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 15 0 11 0 874557248 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5582] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5582/stat : 5582 (java) S 5566 5565 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 874557249 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5583] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5583/stat : 5583 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 874557249 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5584] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5584/stat : 5584 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5585] ppid=5566 vsize=894816 CPUtime=1.57
/proc/5579/task/5585/stat : 5585 (java) S 5566 5565 2059 34821 2220 4202560 14209 0 0 0 154 3 0 0 23 0 11 0 874557251 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5586] ppid=5566 vsize=894816 CPUtime=1.65
/proc/5579/task/5586/stat : 5586 (java) R 5566 5565 2059 34821 2220 4202560 6557 0 0 0 163 2 0 0 16 0 11 0 874557251 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4151872309 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5587] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5587/stat : 5587 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5588] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5588/stat : 5588 (java) R 5566 5565 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 874557251 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5593] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5593/stat : 5593 (java) R 5566 5565 2059 34821 2220 4202560 28 0 0 0 0 0 0 0 20 0 11 0 874557772 916291584 56091 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.25
Current children cumulated vsize (KiB) 899972

[startup+5.91019 s]
/proc/loadavg: 1.56 1.22 1.12 1/67 5593
/proc/meminfo: memFree=463744/1048576 swapFree=2073664/2097144
[pid=5565] ppid=5564 vsize=2576 CPUtime=0
/proc/5565/stat : 5565 (p2cudf-paranoid) S 5564 5565 2059 34821 2220 4202496 379 0 0 0 0 0 0 0 23 0 1 0 874557242 2637824 274 1283457024 134512640 135304128 4291450576 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5565/statm: 644 274 233 194 0 30 0
[pid=5566] ppid=5565 vsize=2580 CPUtime=0.03
/proc/5566/stat : 5566 (p2cudf) S 5565 5565 2059 34821 2220 4202496 395 2994 0 1 0 0 2 1 25 0 1 0 874557242 2641920 283 1283457024 134512640 135304128 4289348832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5566/statm: 645 283 241 194 0 31 0
[pid=5579] ppid=5566 vsize=894816 CPUtime=5.33
/proc/5579/stat : 5579 (java) S 5566 5565 2059 34821 2220 4202496 67093 0 1 0 511 22 0 0 25 0 11 0 874557246 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5579/statm: 223704 56301 1787 10 0 220794 0
[pid=5579/tid=5580] ppid=5566 vsize=894816 CPUtime=1.99
/proc/5579/task/5580/stat : 5580 (java) S 5566 5565 2059 34821 2220 4202560 44970 0 1 0 185 14 0 0 25 0 11 0 874557247 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5581] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5581/stat : 5581 (java) S 5566 5565 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 15 0 11 0 874557248 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 0 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5582] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5582/stat : 5582 (java) S 5566 5565 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 874557249 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5583] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5583/stat : 5583 (java) S 5566 5565 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 874557249 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5584] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5584/stat : 5584 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5585] ppid=5566 vsize=894816 CPUtime=1.57
/proc/5579/task/5585/stat : 5585 (java) S 5566 5565 2059 34821 2220 4202560 14209 0 0 0 154 3 0 0 23 0 11 0 874557251 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5586] ppid=5566 vsize=894816 CPUtime=1.69
/proc/5579/task/5586/stat : 5586 (java) R 5566 5565 2059 34821 2220 4202560 6561 0 0 0 167 2 0 0 16 0 11 0 874557251 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4151288400 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5579/tid=5587] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5587/stat : 5587 (java) S 5566 5565 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874557251 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5588] ppid=5566 vsize=894816 CPUtime=0
/proc/5579/task/5588/stat : 5588 (java) S 5566 5565 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 874557251 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 18446612133182646336 0 0 -1 0 0 0 0
[pid=5579/tid=5593] ppid=5566 vsize=894816 CPUtime=0.05
/proc/5579/task/5593/stat : 5593 (java) R 5566 5565 2059 34821 2220 4202560 234 0 0 0 4 1 0 0 21 0 11 0 874557772 916291584 56301 1283457024 134512640 134550932 4293119760 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.36
Current children cumulated vsize (KiB) 899972

Child status: 0
Real time (s): 6.00205
CPU time (s): 5.41634
CPU user time (s): 5.16432
CPU system time (s): 0.252015
CPU usage (%): 90.2415
Max. virtual memory (cumulated for all children) (KiB): 905168

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 5.16432
system time used= 0.252015
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 70902
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= 659
involuntary context switches= 695

runsolver used 0 second user time and 0 second system time

The end