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/201103031013/p2cudf-trendy-1.12/815.cudf.caixa.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/caixa//815.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/815.cudf.caixa.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.54 1.71 1.70 5/36 32156
/proc/meminfo: memFree=285992/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) R 32154 32155 29493 34817 29493 4202496 356 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2564 CPUtime=0
/proc/32156/stat : 32156 (p2cudf-trendy-1) R 32155 32155 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 39 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/32156/statm: 641 39 0 194 0 28 0

[startup+0.145802 s]
/proc/loadavg: 1.54 1.71 1.70 5/36 32156
/proc/meminfo: memFree=285992/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.205804 s]
/proc/loadavg: 1.54 1.71 1.70 5/36 32156
/proc/meminfo: memFree=285992/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.305821 s]
/proc/loadavg: 1.54 1.71 1.70 5/36 32156
/proc/meminfo: memFree=285992/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.70591 s]
/proc/loadavg: 1.54 1.71 1.70 5/36 32156
/proc/meminfo: memFree=285992/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+1.5061 s]
/proc/loadavg: 1.54 1.71 1.70 5/47 32178
/proc/meminfo: memFree=241432/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
[pid=32169] ppid=32156 vsize=884988 CPUtime=1.47
/proc/32169/stat : 32169 (java) S 32156 32155 29493 34817 29493 4202496 18175 0 1 0 140 7 0 0 25 0 10 0 76365433 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32169/statm: 221247 14363 1725 10 0 218338 0
[pid=32169/tid=32170] ppid=32156 vsize=884988 CPUtime=0.64
/proc/32169/task/32170/stat : 32170 (java) R 32156 32155 29493 34817 29493 4202560 5898 0 1 0 62 2 0 0 25 0 10 0 76365434 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32171] ppid=32156 vsize=884988 CPUtime=0
/proc/32169/task/32171/stat : 32171 (java) S 32156 32155 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 10 0 76365436 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32172] ppid=32156 vsize=884988 CPUtime=0
/proc/32169/task/32172/stat : 32172 (java) S 32156 32155 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76365436 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32173] ppid=32156 vsize=884988 CPUtime=0
/proc/32169/task/32173/stat : 32173 (java) S 32156 32155 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76365436 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32174] ppid=32156 vsize=884988 CPUtime=0
/proc/32169/task/32174/stat : 32174 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32175] ppid=32156 vsize=884988 CPUtime=0.55
/proc/32169/task/32175/stat : 32175 (java) R 32156 32155 29493 34817 29493 4202560 8057 0 0 0 52 3 0 0 21 0 10 0 76365439 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4150201848 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32176] ppid=32156 vsize=884988 CPUtime=0.28
/proc/32169/task/32176/stat : 32176 (java) R 32156 32155 29493 34817 29493 4202560 3140 0 0 0 26 2 0 0 24 0 10 0 76365439 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4152280468 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32177] ppid=32156 vsize=884988 CPUtime=0
/proc/32169/task/32177/stat : 32177 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32178] ppid=32156 vsize=884988 CPUtime=0
/proc/32169/task/32178/stat : 32178 (java) S 32156 32155 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 76365439 906227712 14363 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 890120

[startup+3.10644 s]
/proc/loadavg: 1.54 1.71 1.70 4/47 32178
/proc/meminfo: memFree=176828/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
[pid=32169] ppid=32156 vsize=885516 CPUtime=3.06
/proc/32169/stat : 32169 (java) S 32156 32155 29493 34817 29493 4202496 60812 0 1 0 284 22 0 0 25 0 10 0 76365433 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32169/statm: 221379 55184 1743 10 0 218470 0
[pid=32169/tid=32170] ppid=32156 vsize=885516 CPUtime=1.57
/proc/32169/task/32170/stat : 32170 (java) R 32156 32155 29493 34817 29493 4202560 46398 0 1 0 141 16 0 0 25 0 10 0 76365434 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4095998787 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32171] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32171/stat : 32171 (java) S 32156 32155 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 10 0 76365436 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32172] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32172/stat : 32172 (java) S 32156 32155 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76365436 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32173] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32173/stat : 32173 (java) S 32156 32155 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76365436 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32174] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32174/stat : 32174 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32175] ppid=32156 vsize=885516 CPUtime=1.05
/proc/32169/task/32175/stat : 32175 (java) S 32156 32155 29493 34817 29493 4202560 9301 0 0 0 101 4 0 0 17 0 10 0 76365439 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32176] ppid=32156 vsize=885516 CPUtime=0.43
/proc/32169/task/32176/stat : 32176 (java) S 32156 32155 29493 34817 29493 4202560 4033 0 0 0 41 2 0 0 25 0 10 0 76365439 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32177] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32177/stat : 32177 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32178] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32178/stat : 32178 (java) S 32156 32155 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 76365439 906768384 55184 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 890648

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

[startup+3.22649 s]
/proc/loadavg: 1.54 1.71 1.70 4/47 32178
/proc/meminfo: memFree=176828/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
[pid=32169] ppid=32156 vsize=885516 CPUtime=3.19
/proc/32169/stat : 32169 (java) S 32156 32155 29493 34817 29493 4202496 67943 0 1 0 295 24 0 0 25 0 10 0 76365433 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32169/statm: 221379 62315 1744 10 0 218470 0
[pid=32169/tid=32170] ppid=32156 vsize=885516 CPUtime=1.6
/proc/32169/task/32170/stat : 32170 (java) R 32156 32155 29493 34817 29493 4202560 50064 0 1 0 144 16 0 0 25 0 10 0 76365434 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32171] ppid=32156 vsize=885516 CPUtime=0.07
/proc/32169/task/32171/stat : 32171 (java) R 32156 32155 29493 34817 29493 4202560 3471 0 0 0 6 1 0 0 15 0 10 0 76365436 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4149466589 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32172] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32172/stat : 32172 (java) S 32156 32155 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76365436 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32173] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32173/stat : 32173 (java) S 32156 32155 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76365436 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32174] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32174/stat : 32174 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32175] ppid=32156 vsize=885516 CPUtime=1.06
/proc/32169/task/32175/stat : 32175 (java) S 32156 32155 29493 34817 29493 4202560 9302 0 0 0 102 4 0 0 17 0 10 0 76365439 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32176] ppid=32156 vsize=885516 CPUtime=0.43
/proc/32169/task/32176/stat : 32176 (java) S 32156 32155 29493 34817 29493 4202560 4033 0 0 0 41 2 0 0 25 0 10 0 76365439 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32177] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32177/stat : 32177 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32178] ppid=32156 vsize=885516 CPUtime=0
/proc/32169/task/32178/stat : 32178 (java) S 32156 32155 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 76365439 906768384 62315 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.22
Current children cumulated vsize (KiB) 890648

[startup+4.00665 s]
/proc/loadavg: 1.54 1.71 1.70 3/47 32178
/proc/meminfo: memFree=18480/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
[pid=32169] ppid=32156 vsize=885360 CPUtime=3.97
/proc/32169/stat : 32169 (java) S 32156 32155 29493 34817 29493 4202496 82133 0 1 0 371 26 0 0 25 0 10 0 76365433 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32169/statm: 221340 74899 1748 10 0 218431 0
[pid=32169/tid=32170] ppid=32156 vsize=885360 CPUtime=1.72
/proc/32169/task/32170/stat : 32170 (java) R 32156 32155 29493 34817 29493 4202560 50188 0 1 0 156 16 0 0 25 0 10 0 76365434 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32171] ppid=32156 vsize=885360 CPUtime=0.34
/proc/32169/task/32171/stat : 32171 (java) S 32156 32155 29493 34817 29493 4202560 15916 0 0 0 31 3 0 0 16 0 10 0 76365436 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32172] ppid=32156 vsize=885360 CPUtime=0
/proc/32169/task/32172/stat : 32172 (java) S 32156 32155 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 76365436 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32173] ppid=32156 vsize=885360 CPUtime=0
/proc/32169/task/32173/stat : 32173 (java) S 32156 32155 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 76365436 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32174] ppid=32156 vsize=885360 CPUtime=0
/proc/32169/task/32174/stat : 32174 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32175] ppid=32156 vsize=885360 CPUtime=1.45
/proc/32169/task/32175/stat : 32175 (java) R 32156 32155 29493 34817 29493 4202560 10921 0 0 0 141 4 0 0 16 0 10 0 76365439 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4151834237 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32169/tid=32176] ppid=32156 vsize=885360 CPUtime=0.43
/proc/32169/task/32176/stat : 32176 (java) S 32156 32155 29493 34817 29493 4202560 4033 0 0 0 41 2 0 0 25 0 10 0 76365439 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32177] ppid=32156 vsize=885360 CPUtime=0
/proc/32169/task/32177/stat : 32177 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32178] ppid=32156 vsize=885360 CPUtime=0
/proc/32169/task/32178/stat : 32178 (java) S 32156 32155 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 76365439 906608640 74899 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4
Current children cumulated vsize (KiB) 890492

[startup+4.402 s]
/proc/loadavg: 1.57 1.72 1.70 1/48 32179
/proc/meminfo: memFree=7116/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
[pid=32169] ppid=32156 vsize=885684 CPUtime=4.3
/proc/32169/stat : 32169 (java) S 32156 32155 29493 34817 29493 4202496 82174 0 1 0 404 26 0 0 25 0 11 0 76365433 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32169/statm: 221421 74940 1754 10 0 218512 0
[pid=32169/tid=32170] ppid=32156 vsize=885684 CPUtime=1.88
/proc/32169/task/32170/stat : 32170 (java) S 32156 32155 29493 34817 29493 4202560 50201 0 1 0 171 17 0 0 25 0 11 0 76365434 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32171] ppid=32156 vsize=885684 CPUtime=0.34
/proc/32169/task/32171/stat : 32171 (java) S 32156 32155 29493 34817 29493 4202560 15916 0 0 0 31 3 0 0 16 0 11 0 76365436 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32172] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32172/stat : 32172 (java) S 32156 32155 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 76365436 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32173] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32173/stat : 32173 (java) S 32156 32155 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 76365436 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32174] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32174/stat : 32174 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32175] ppid=32156 vsize=885684 CPUtime=1.64
/proc/32169/task/32175/stat : 32175 (java) S 32156 32155 29493 34817 29493 4202560 10945 0 0 0 160 4 0 0 15 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32176] ppid=32156 vsize=885684 CPUtime=0.43
/proc/32169/task/32176/stat : 32176 (java) S 32156 32155 29493 34817 29493 4202560 4033 0 0 0 41 2 0 0 25 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32177] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32177/stat : 32177 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32178] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32178/stat : 32178 (java) S 32156 32155 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32179] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32179/stat : 32179 (java) S 32156 32155 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 76365865 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.33
Current children cumulated vsize (KiB) 890816

[startup+4.806 s]
/proc/loadavg: 1.57 1.72 1.70 1/48 32179
/proc/meminfo: memFree=7116/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
[pid=32169] ppid=32156 vsize=885684 CPUtime=4.3
/proc/32169/stat : 32169 (java) S 32156 32155 29493 34817 29493 4202496 82174 0 1 0 404 26 0 0 25 0 11 0 76365433 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32169/statm: 221421 74940 1754 10 0 218512 0
[pid=32169/tid=32170] ppid=32156 vsize=885684 CPUtime=1.88
/proc/32169/task/32170/stat : 32170 (java) S 32156 32155 29493 34817 29493 4202560 50201 0 1 0 171 17 0 0 25 0 11 0 76365434 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32171] ppid=32156 vsize=885684 CPUtime=0.34
/proc/32169/task/32171/stat : 32171 (java) S 32156 32155 29493 34817 29493 4202560 15916 0 0 0 31 3 0 0 15 0 11 0 76365436 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32172] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32172/stat : 32172 (java) S 32156 32155 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 76365436 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32173] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32173/stat : 32173 (java) S 32156 32155 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 76365436 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32174] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32174/stat : 32174 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32175] ppid=32156 vsize=885684 CPUtime=1.64
/proc/32169/task/32175/stat : 32175 (java) S 32156 32155 29493 34817 29493 4202560 10945 0 0 0 160 4 0 0 15 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32176] ppid=32156 vsize=885684 CPUtime=0.43
/proc/32169/task/32176/stat : 32176 (java) S 32156 32155 29493 34817 29493 4202560 4033 0 0 0 41 2 0 0 25 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32177] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32177/stat : 32177 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32178] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32178/stat : 32178 (java) S 32156 32155 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 76365439 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32179] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32179/stat : 32179 (java) S 32156 32155 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 76365865 906940416 74940 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.33
Current children cumulated vsize (KiB) 890816

[startup+4.90603 s]
/proc/loadavg: 1.57 1.72 1.70 1/48 32179
/proc/meminfo: memFree=7116/1048576 swapFree=0/0
[pid=32155] ppid=32154 vsize=2564 CPUtime=0
/proc/32155/stat : 32155 (p2cudf-trendy-1) S 32154 32155 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 76365430 2625536 269 1283457024 134512640 135304128 4293378224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32155/statm: 641 269 230 194 0 28 0
[pid=32156] ppid=32155 vsize=2568 CPUtime=0.03
/proc/32156/stat : 32156 (p2cudf) S 32155 32155 29493 34817 29493 4202496 389 3013 0 1 0 0 0 3 25 0 1 0 76365430 2629632 279 1283457024 134512640 135304128 4290383312 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32156/statm: 642 279 239 194 0 29 0
[pid=32169] ppid=32156 vsize=885684 CPUtime=4.3
/proc/32169/stat : 32169 (java) S 32156 32155 29493 34817 29493 4202496 82176 0 1 0 404 26 0 0 25 0 10 0 76365433 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32169/statm: 221421 74942 1756 10 0 218512 0
[pid=32169/tid=32170] ppid=32156 vsize=885684 CPUtime=1.88
/proc/32169/task/32170/stat : 32170 (java) S 32156 32155 29493 34817 29493 4202560 50201 0 1 0 171 17 0 0 20 0 10 0 76365434 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32171] ppid=32156 vsize=885684 CPUtime=0.34
/proc/32169/task/32171/stat : 32171 (java) S 32156 32155 29493 34817 29493 4202560 15917 0 0 0 31 3 0 0 16 0 10 0 76365436 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 0 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32172] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32172/stat : 32172 (java) S 32156 32155 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 76365436 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32173] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32173/stat : 32173 (java) S 32156 32155 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 76365436 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32174] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32174/stat : 32174 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32175] ppid=32156 vsize=885684 CPUtime=1.64
/proc/32169/task/32175/stat : 32175 (java) S 32156 32155 29493 34817 29493 4202560 10945 0 0 0 160 4 0 0 15 0 10 0 76365439 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32176] ppid=32156 vsize=885684 CPUtime=0.43
/proc/32169/task/32176/stat : 32176 (java) S 32156 32155 29493 34817 29493 4202560 4033 0 0 0 41 2 0 0 25 0 10 0 76365439 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32177] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32177/stat : 32177 (java) S 32156 32155 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76365439 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
[pid=32169/tid=32178] ppid=32156 vsize=885684 CPUtime=0
/proc/32169/task/32178/stat : 32178 (java) S 32156 32155 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 76365439 906940416 74942 1283457024 134512640 134550932 4287129936 18446744073709551615 4294960130 0 4 0 16800975 18446612132758717760 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.33
Current children cumulated vsize (KiB) 890816

Child status: 0
Real time (s): 4.9428
CPU time (s): 4.38027
CPU user time (s): 4.05225
CPU system time (s): 0.32802
CPU usage (%): 88.6193
Max. virtual memory (cumulated for all children) (KiB): 894432

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 4.05225
system time used= 0.32802
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 85975
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= 412
involuntary context switches= 407

runsolver used 0 second user time and 0 second system time

The end