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/201108241238/p2cudf-trendy-1.13/rand214.cudf.s-e.log.runsolver ./p2cudf-trendy-1.13 /home/misc2010/data/2011/incremental/s-e/rand214.cudf /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/rand214.cudf.s-e.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.00 1.00 5/35 4727
/proc/meminfo: memFree=573680/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2568 CPUtime=0
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 352 0 0 0 0 0 0 0 25 0 1 0 1282261 2629632 271 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0
/proc/4726/statm: 642 271 231 194 0 29 0
[pid=4727] ppid=4726 vsize=2568 CPUtime=0
/proc/4727/stat : 4727 (p2cudf) R 4726 4725 1511 34817 1511 4202560 94 0 0 0 0 0 0 0 25 0 1 0 1282261 2629632 118 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/4727/statm: 642 118 77 194 0 29 0
[pid=4728] ppid=4727 vsize=2568 CPUtime=0
/proc/4728/stat : 4728 (p2cudf) R 4727 4725 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 1282261 2629632 41 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/4728/statm: 642 41 0 194 0 29 0

[startup+0.164174 s]
/proc/loadavg: 1.00 1.00 1.00 5/35 4727
/proc/meminfo: memFree=573680/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+0.204172 s]
/proc/loadavg: 1.00 1.00 1.00 5/35 4727
/proc/meminfo: memFree=573680/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+0.304184 s]
/proc/loadavg: 1.00 1.00 1.00 5/35 4727
/proc/meminfo: memFree=573680/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+0.704241 s]
/proc/loadavg: 1.00 1.00 1.00 5/35 4727
/proc/meminfo: memFree=573680/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+1.50435 s]
/proc/loadavg: 1.00 1.00 1.00 5/45 4748
/proc/meminfo: memFree=513636/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=898524 CPUtime=1.47
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 25733 0 1 0 143 4 0 0 25 0 10 0 1282264 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 224631 22090 1719 10 0 221722 0
[pid=4739/tid=4740] ppid=4726 vsize=898524 CPUtime=0.79
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 11095 0 1 0 78 1 0 0 25 0 10 0 1282265 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4152271498 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=898524 CPUtime=0
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 7 0 0 0 0 0 0 0 17 0 10 0 1282266 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=898524 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 1282267 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=898524 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 1282267 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=898524 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1282269 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=898524 CPUtime=0.44
/proc/4739/task/4745/stat : 4745 (java) R 4726 4725 1511 34817 1511 4202560 8917 0 0 0 44 0 0 0 25 0 10 0 1282269 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=898524 CPUtime=0.22
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 4641 0 0 0 20 2 0 0 22 0 10 0 1282270 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=898524 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1282270 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=898524 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 1282270 920088576 22090 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 903660

[startup+3.10466 s]
/proc/loadavg: 1.00 1.00 1.00 2/45 4748
/proc/meminfo: memFree=409848/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=898104 CPUtime=3.06
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 58942 0 1 0 290 16 0 0 25 0 10 0 1282264 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 224526 52174 1744 10 0 221617 0
[pid=4739/tid=4740] ppid=4726 vsize=898104 CPUtime=1.39
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 40641 0 1 0 125 14 0 0 25 0 10 0 1282265 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=898104 CPUtime=0
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 7 0 0 0 0 0 0 0 16 0 10 0 1282266 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=898104 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 1282267 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=898104 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 1282267 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=898104 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1282269 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=898104 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 10 0 1282269 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=898104 CPUtime=1.16
/proc/4739/task/4746/stat : 4746 (java) R 4726 4725 1511 34817 1511 4202560 8238 0 0 0 114 2 0 0 16 0 10 0 1282270 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4150306442 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=898104 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1282270 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=898104 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 1282270 919658496 52174 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 903240

[startup+6.30565 s]
/proc/loadavg: 1.08 1.02 1.01 2/46 4749
/proc/meminfo: memFree=293776/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=896316 CPUtime=6.27
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 78675 0 1 0 603 24 0 0 25 0 11 0 1282264 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 224079 71512 1783 10 0 221170 0
[pid=4739/tid=4740] ppid=4726 vsize=896316 CPUtime=3.33
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 50278 0 1 0 315 18 0 0 25 0 11 0 1282265 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4095397142 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=896316 CPUtime=0.23
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 9916 0 0 0 20 3 0 0 15 0 11 0 1282266 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=896316 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1282267 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=896316 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1282267 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=896316 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282269 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=896316 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 11 0 1282269 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=896316 CPUtime=2.18
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8419 0 0 0 216 2 0 0 15 0 11 0 1282270 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=896316 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282270 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=896316 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1282270 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4749] ppid=4726 vsize=896316 CPUtime=0
/proc/4739/task/4749/stat : 4749 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1282683 917827584 71512 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.29
Current children cumulated vsize (KiB) 901452

[startup+12.707 s]
/proc/loadavg: 1.07 1.02 1.00 2/46 4749
/proc/meminfo: memFree=267372/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=894940 CPUtime=12.66
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 85683 0 1 0 1238 28 0 0 25 0 11 0 1282264 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 223735 78181 1788 10 0 220826 0
[pid=4739/tid=4740] ppid=4726 vsize=894940 CPUtime=8.84
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 50280 0 1 0 866 18 0 0 25 0 11 0 1282265 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4096433624 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=894940 CPUtime=0.64
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 16798 0 0 0 57 7 0 0 16 0 11 0 1282266 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282269 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=894940 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 11 0 1282269 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=894940 CPUtime=2.66
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8543 0 0 0 264 2 0 0 15 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4749] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4749/stat : 4749 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1282683 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.68
Current children cumulated vsize (KiB) 900076

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

[startup+12.807 s]
/proc/loadavg: 1.07 1.02 1.00 2/46 4749
/proc/meminfo: memFree=267372/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=894940 CPUtime=12.76
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 85683 0 1 0 1248 28 0 0 25 0 11 0 1282264 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 223735 78181 1788 10 0 220826 0
[pid=4739/tid=4740] ppid=4726 vsize=894940 CPUtime=8.94
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 50280 0 1 0 876 18 0 0 25 0 11 0 1282265 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4096443719 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=894940 CPUtime=0.64
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 16798 0 0 0 57 7 0 0 16 0 11 0 1282266 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282269 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=894940 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 11 0 1282269 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=894940 CPUtime=2.66
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8543 0 0 0 264 2 0 0 15 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4749] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4749/stat : 4749 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1282683 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.78
Current children cumulated vsize (KiB) 900076

[startup+16.0077 s]
/proc/loadavg: 1.07 1.02 1.00 2/46 4749
/proc/meminfo: memFree=266876/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=894940 CPUtime=15.96
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 85683 0 1 0 1568 28 0 0 25 0 11 0 1282264 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 223735 78181 1788 10 0 220826 0
[pid=4739/tid=4740] ppid=4726 vsize=894940 CPUtime=12.14
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 50280 0 1 0 1196 18 0 0 25 0 11 0 1282265 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4096304836 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=894940 CPUtime=0.64
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 16798 0 0 0 57 7 0 0 15 0 11 0 1282266 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282269 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=894940 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 11 0 1282269 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=894940 CPUtime=2.66
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8543 0 0 0 264 2 0 0 15 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1282270 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4749] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4749/stat : 4749 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1282683 916418560 78181 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 15.98
Current children cumulated vsize (KiB) 900076

[startup+19.2084 s]
/proc/loadavg: 1.06 1.02 1.00 2/46 4749
/proc/meminfo: memFree=266504/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=894940 CPUtime=19.16
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 85786 0 1 0 1888 28 0 0 25 0 11 0 1282264 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 223735 78284 1788 10 0 220826 0
[pid=4739/tid=4740] ppid=4726 vsize=894940 CPUtime=15.15
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 50280 0 1 0 1497 18 0 0 25 0 11 0 1282265 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4096305263 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=894940 CPUtime=0.81
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 16901 0 0 0 74 7 0 0 15 0 11 0 1282266 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282269 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=894940 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 11 0 1282269 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=894940 CPUtime=2.68
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8543 0 0 0 266 2 0 0 15 0 11 0 1282270 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282270 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1282270 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4749] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4749/stat : 4749 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1282683 916418560 78284 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 19.18
Current children cumulated vsize (KiB) 900076

[startup+20.8088 s]
/proc/loadavg: 1.06 1.02 1.00 2/46 4749
/proc/meminfo: memFree=266504/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=894940 CPUtime=20.76
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 85791 0 1 0 2048 28 0 0 25 0 11 0 1282264 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 223735 78289 1788 10 0 220826 0
[pid=4739/tid=4740] ppid=4726 vsize=894940 CPUtime=16.51
/proc/4739/task/4740/stat : 4740 (java) R 4726 4725 1511 34817 1511 4202560 50280 0 1 0 1633 18 0 0 25 0 11 0 1282265 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4096314100 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=894940 CPUtime=1.01
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 16901 0 0 0 94 7 0 0 16 0 11 0 1282266 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1282267 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282269 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=894940 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 11 0 1282269 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=894940 CPUtime=2.72
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8548 0 0 0 270 2 0 0 16 0 11 0 1282270 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282270 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1282270 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4749] ppid=4726 vsize=894940 CPUtime=0
/proc/4739/task/4749/stat : 4749 (java) S 4726 4725 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1282683 916418560 78289 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 20.78
Current children cumulated vsize (KiB) 900076

[startup+21.6055 s]
/proc/loadavg: 1.06 1.02 1.00 3/46 4749
/proc/meminfo: memFree=266504/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=895264 CPUtime=21.36
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 85803 0 1 0 2108 28 0 0 25 0 11 0 1282264 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 223816 78301 1789 10 0 220907 0
[pid=4739/tid=4740] ppid=4726 vsize=895264 CPUtime=17.09
/proc/4739/task/4740/stat : 4740 (java) S 4726 4725 1511 34817 1511 4202560 50287 0 1 0 1691 18 0 0 25 0 11 0 1282265 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=895264 CPUtime=1.01
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 16901 0 0 0 94 7 0 0 16 0 11 0 1282266 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1282267 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1282267 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282269 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=895264 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 11 0 1282269 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=895264 CPUtime=2.74
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8548 0 0 0 272 2 0 0 15 0 11 0 1282270 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1282270 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1282270 916750336 78301 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 21.38
Current children cumulated vsize (KiB) 900400

[startup+22.0055 s]
/proc/loadavg: 1.06 1.02 1.00 1/45 4750
/proc/meminfo: memFree=266388/1048576 swapFree=0/0
[pid=4725] ppid=4724 vsize=2564 CPUtime=0
/proc/4725/stat : 4725 (p2cudf-trendy-1) S 4724 4725 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 1282261 2625536 269 1283457024 134512640 135304128 4293259392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4725/statm: 641 269 230 194 0 28 0
[pid=4726] ppid=4725 vsize=2572 CPUtime=0.02
/proc/4726/stat : 4726 (p2cudf) S 4725 4725 1511 34817 1511 4202496 391 3016 0 1 0 0 1 1 25 0 1 0 1282261 2633728 280 1283457024 134512640 135304128 4288277440 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4726/statm: 643 280 239 194 0 30 0
[pid=4739] ppid=4726 vsize=895264 CPUtime=21.42
/proc/4739/stat : 4739 (java) S 4726 4725 1511 34817 1511 4202496 85805 0 1 0 2114 28 0 0 25 0 10 0 1282264 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4739/statm: 223816 78303 1789 10 0 220907 0
[pid=4739/tid=4740] ppid=4726 vsize=895264 CPUtime=17.09
/proc/4739/task/4740/stat : 4740 (java) S 4726 4725 1511 34817 1511 4202560 50287 0 1 0 1691 18 0 0 20 0 10 0 1282265 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4741] ppid=4726 vsize=895264 CPUtime=1.01
/proc/4739/task/4741/stat : 4741 (java) S 4726 4725 1511 34817 1511 4202560 16901 0 0 0 94 7 0 0 16 0 10 0 1282266 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 0 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4742] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4742/stat : 4742 (java) S 4726 4725 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 10 0 1282267 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4743] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4743/stat : 4743 (java) S 4726 4725 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 1282267 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4744] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4744/stat : 4744 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1282269 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4745] ppid=4726 vsize=895264 CPUtime=0.5
/proc/4739/task/4745/stat : 4745 (java) S 4726 4725 1511 34817 1511 4202560 8983 0 0 0 50 0 0 0 25 0 10 0 1282269 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4746] ppid=4726 vsize=895264 CPUtime=2.75
/proc/4739/task/4746/stat : 4746 (java) S 4726 4725 1511 34817 1511 4202560 8548 0 0 0 273 2 0 0 15 0 10 0 1282270 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4747] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4747/stat : 4747 (java) S 4726 4725 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1282270 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
[pid=4739/tid=4748] ppid=4726 vsize=895264 CPUtime=0
/proc/4739/task/4748/stat : 4748 (java) S 4726 4725 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 1282270 916750336 78303 1283457024 134512640 134550932 4291417424 18446744073709551615 4294960130 0 4 0 16800975 18446612133275973824 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 21.44
Current children cumulated vsize (KiB) 900400

Child status: 0
Real time (s): 22.0434
CPU time (s): 21.4773
CPU user time (s): 21.1573
CPU system time (s): 0.32002
CPU usage (%): 97.4322
Max. virtual memory (cumulated for all children) (KiB): 904500

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 21.1573
system time used= 0.32002
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 89610
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= 1188
involuntary context switches= 1331

runsolver used 0 second user time and 0.012 second system time

The end