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/rand933.cudf.dudf-random.log.runsolver ./p2cudf-trendy-1.13 /home/misc2010/data/2011/dudf-random//rand933.cudf /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/rand933.cudf.dudf-random.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.19 1.20 1.13 5/35 25605
/proc/meminfo: memFree=303536/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2572 CPUtime=0
/proc/25604/stat : 25604 (p2cudf) R 25603 25603 1511 34817 1511 4202496 346 0 0 0 0 0 0 0 25 0 1 0 4760265 2633728 272 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/25604/statm: 643 272 231 194 0 30 0
[pid=25605] ppid=25604 vsize=2572 CPUtime=0
/proc/25605/stat : 25605 (p2cudf) R 25604 25603 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 4760265 2633728 41 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/25605/statm: 643 41 0 194 0 30 0

[startup+0.185708 s]
/proc/loadavg: 1.19 1.20 1.13 5/35 25605
/proc/meminfo: memFree=303536/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.205701 s]
/proc/loadavg: 1.19 1.20 1.13 5/35 25605
/proc/meminfo: memFree=303536/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.305717 s]
/proc/loadavg: 1.19 1.20 1.13 5/35 25605
/proc/meminfo: memFree=303536/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.705781 s]
/proc/loadavg: 1.19 1.20 1.13 5/35 25605
/proc/meminfo: memFree=303536/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+1.50593 s]
/proc/loadavg: 1.33 1.23 1.14 4/45 25626
/proc/meminfo: memFree=263272/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=879092 CPUtime=1.46
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 20952 0 1 0 137 9 0 0 25 0 10 0 4760268 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 219773 18057 1724 10 0 216864 0
[pid=25617/tid=25618] ppid=25604 vsize=879092 CPUtime=0.77
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 10964 0 1 0 69 8 0 0 25 0 10 0 4760269 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4153379101 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=879092 CPUtime=0
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 17 0 10 0 4760271 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=879092 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 25 0 10 0 4760271 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=879092 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 4760271 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=879092 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4760274 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=879092 CPUtime=0.55
/proc/25617/task/25623/stat : 25623 (java) R 25604 25603 1511 34817 1511 4202560 7719 0 0 0 54 1 0 0 22 0 10 0 4760274 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4152980774 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=879092 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 10 0 4760274 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=879092 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4760274 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=879092 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 4760274 900190208 18057 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 884236

[startup+3.10622 s]
/proc/loadavg: 1.33 1.23 1.14 2/45 25626
/proc/meminfo: memFree=132948/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=878040 CPUtime=3.06
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 71467 0 1 0 282 24 0 0 25 0 10 0 4760268 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 219510 67967 1743 10 0 216601 0
[pid=25617/tid=25618] ppid=25604 vsize=878040 CPUtime=1.88
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 50091 0 1 0 167 21 0 0 25 0 10 0 4760269 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4095616213 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=878040 CPUtime=0.23
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 10975 0 0 0 21 2 0 0 16 0 10 0 4760271 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=878040 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 4760271 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=878040 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 4760271 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=878040 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4760274 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=878040 CPUtime=0.81
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 8136 0 0 0 80 1 0 0 15 0 10 0 4760274 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=878040 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 10 0 4760274 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=878040 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4760274 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=878040 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 4760274 899112960 67967 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 883184

[startup+6.30682 s]
/proc/loadavg: 1.39 1.24 1.14 3/46 25627
/proc/meminfo: memFree=5844/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=878364 CPUtime=6.23
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 86859 0 1 0 595 28 0 0 25 0 11 0 4760268 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 219591 81923 1774 10 0 216682 0
[pid=25617/tid=25618] ppid=25604 vsize=878364 CPUtime=3.37
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 50271 0 1 0 316 21 0 0 25 0 11 0 4760269 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4096603956 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=878364 CPUtime=0.53
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 24562 0 0 0 48 5 0 0 16 0 11 0 4760271 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=878364 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=878364 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=878364 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=878364 CPUtime=2.19
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9757 0 0 0 217 2 0 0 15 0 11 0 4760274 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=878364 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=878364 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=878364 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25627] ppid=25604 vsize=878364 CPUtime=0
/proc/25617/task/25627/stat : 25627 (java) S 25604 25603 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4760806 899444736 81923 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.26
Current children cumulated vsize (KiB) 883508

[startup+12.708 s]
/proc/loadavg: 1.44 1.26 1.15 2/46 25627
/proc/meminfo: memFree=7588/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=875912 CPUtime=12.63
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 86911 0 1 0 1234 29 0 0 25 0 11 0 4760268 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 218978 81362 1782 10 0 216069 0
[pid=25617/tid=25618] ppid=25604 vsize=875912 CPUtime=9.37
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 50279 0 1 0 915 22 0 0 25 0 11 0 4760269 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4096585684 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=875912 CPUtime=0.53
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 24562 0 0 0 48 5 0 0 16 0 11 0 4760271 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=875912 CPUtime=2.59
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9801 0 0 0 257 2 0 0 15 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=875912 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25627] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25627/stat : 25627 (java) S 25604 25603 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4760806 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.66
Current children cumulated vsize (KiB) 881056

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

[startup+12.808 s]
/proc/loadavg: 1.44 1.26 1.15 2/46 25627
/proc/meminfo: memFree=7588/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=875912 CPUtime=12.73
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 86911 0 1 0 1244 29 0 0 25 0 11 0 4760268 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 218978 81362 1782 10 0 216069 0
[pid=25617/tid=25618] ppid=25604 vsize=875912 CPUtime=9.47
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 50279 0 1 0 925 22 0 0 25 0 11 0 4760269 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4096537384 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=875912 CPUtime=0.53
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 24562 0 0 0 48 5 0 0 16 0 11 0 4760271 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=875912 CPUtime=2.59
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9801 0 0 0 257 2 0 0 15 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=875912 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25627] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25627/stat : 25627 (java) S 25604 25603 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4760806 896933888 81362 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.76
Current children cumulated vsize (KiB) 881056

[startup+19.1099 s]
/proc/loadavg: 1.40 1.25 1.15 2/46 25627
/proc/meminfo: memFree=7588/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=875912 CPUtime=19.03
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 86927 0 1 0 1874 29 0 0 25 0 11 0 4760268 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 218978 81378 1784 10 0 216069 0
[pid=25617/tid=25618] ppid=25604 vsize=875912 CPUtime=15.61
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 50280 0 1 0 1539 22 0 0 25 0 11 0 4760269 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4096561632 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=875912 CPUtime=0.53
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 24562 0 0 0 48 5 0 0 15 0 11 0 4760271 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=875912 CPUtime=2.75
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9816 0 0 0 273 2 0 0 15 0 11 0 4760274 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=875912 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25627] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25627/stat : 25627 (java) S 25604 25603 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4760806 896933888 81378 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 19.06
Current children cumulated vsize (KiB) 881056

[startup+20.7104 s]
/proc/loadavg: 1.40 1.25 1.15 3/46 25627
/proc/meminfo: memFree=6140/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=875912 CPUtime=20.61
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 96137 0 1 0 2032 29 0 0 25 0 11 0 4760268 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 218978 90588 1788 10 0 216069 0
[pid=25617/tid=25618] ppid=25604 vsize=875912 CPUtime=16.7
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 50280 0 1 0 1648 22 0 0 25 0 11 0 4760269 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4096561979 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=875912 CPUtime=0.97
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 33768 0 0 0 92 5 0 0 16 0 11 0 4760271 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=875912 CPUtime=2.8
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9820 0 0 0 278 2 0 0 15 0 11 0 4760274 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=875912 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25627] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25627/stat : 25627 (java) S 25604 25603 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4760806 896933888 90588 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 20.64
Current children cumulated vsize (KiB) 881056

[startup+21.5105 s]
/proc/loadavg: 1.37 1.25 1.14 3/46 25627
/proc/meminfo: memFree=5272/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=875912 CPUtime=21.41
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 96138 0 1 0 2112 29 0 0 25 0 11 0 4760268 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 218978 90589 1788 10 0 216069 0
[pid=25617/tid=25618] ppid=25604 vsize=875912 CPUtime=17.5
/proc/25617/task/25618/stat : 25618 (java) R 25604 25603 1511 34817 1511 4202560 50281 0 1 0 1728 22 0 0 25 0 11 0 4760269 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4096561683 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=875912 CPUtime=0.97
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 33768 0 0 0 92 5 0 0 15 0 11 0 4760271 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=875912 CPUtime=2.8
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9820 0 0 0 278 2 0 0 15 0 11 0 4760274 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=875912 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25627] ppid=25604 vsize=875912 CPUtime=0
/proc/25617/task/25627/stat : 25627 (java) S 25604 25603 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4760806 896933888 90589 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 21.44
Current children cumulated vsize (KiB) 881056

[startup+22.2071 s]
/proc/loadavg: 1.37 1.25 1.14 2/46 25627
/proc/meminfo: memFree=5272/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=876236 CPUtime=21.95
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 96151 0 1 0 2166 29 0 0 25 0 11 0 4760268 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 219059 90602 1789 10 0 216150 0
[pid=25617/tid=25618] ppid=25604 vsize=876236 CPUtime=18.01
/proc/25617/task/25618/stat : 25618 (java) S 25604 25603 1511 34817 1511 4202560 50289 0 1 0 1779 22 0 0 25 0 11 0 4760269 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=876236 CPUtime=0.97
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 33768 0 0 0 92 5 0 0 16 0 11 0 4760271 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=876236 CPUtime=2.82
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9820 0 0 0 280 2 0 0 16 0 11 0 4760274 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=876236 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 897265664 90602 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 21.98
Current children cumulated vsize (KiB) 881380

[startup+22.6111 s]
/proc/loadavg: 1.37 1.25 1.14 2/46 25627
/proc/meminfo: memFree=5272/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=876236 CPUtime=22.01
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 96152 0 1 0 2172 29 0 0 25 0 11 0 4760268 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 219059 90603 1789 10 0 216150 0
[pid=25617/tid=25618] ppid=25604 vsize=876236 CPUtime=18.01
/proc/25617/task/25618/stat : 25618 (java) S 25604 25603 1511 34817 1511 4202560 50289 0 1 0 1779 22 0 0 25 0 11 0 4760269 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=876236 CPUtime=0.97
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 33768 0 0 0 92 5 0 0 16 0 11 0 4760271 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=876236 CPUtime=2.82
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9820 0 0 0 280 2 0 0 15 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=876236 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 22.04
Current children cumulated vsize (KiB) 881380

[startup+22.7111 s]
/proc/loadavg: 1.37 1.25 1.14 2/46 25627
/proc/meminfo: memFree=5272/1048576 swapFree=0/0
[pid=25603] ppid=25602 vsize=2568 CPUtime=0
/proc/25603/stat : 25603 (p2cudf-trendy-1) S 25602 25603 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4760265 2629632 270 1283457024 134512640 135304128 4293066832 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25603/statm: 642 270 230 194 0 29 0
[pid=25604] ppid=25603 vsize=2576 CPUtime=0.03
/proc/25604/stat : 25604 (p2cudf) S 25603 25603 1511 34817 1511 4202496 393 3014 0 1 1 0 2 0 25 0 1 0 4760265 2637824 281 1283457024 134512640 135304128 4289057904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25604/statm: 644 281 239 194 0 31 0
[pid=25617] ppid=25604 vsize=876236 CPUtime=22.11
/proc/25617/stat : 25617 (java) S 25604 25603 1511 34817 1511 4202496 96152 0 1 0 2179 32 0 0 25 0 11 0 4760268 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25617/statm: 219059 90603 1789 10 0 216150 0
[pid=25617/tid=25618] ppid=25604 vsize=876236 CPUtime=18.01
/proc/25617/task/25618/stat : 25618 (java) S 25604 25603 1511 34817 1511 4202560 50289 0 1 0 1779 22 0 0 25 0 11 0 4760269 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25619] ppid=25604 vsize=876236 CPUtime=0.97
/proc/25617/task/25619/stat : 25619 (java) S 25604 25603 1511 34817 1511 4202560 33768 0 0 0 92 5 0 0 16 0 11 0 4760271 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 0 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25620] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25620/stat : 25620 (java) S 25604 25603 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4760271 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25621] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25621/stat : 25621 (java) S 25604 25603 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 4760271 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25622] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25622/stat : 25622 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25623] ppid=25604 vsize=876236 CPUtime=2.88
/proc/25617/task/25623/stat : 25623 (java) S 25604 25603 1511 34817 1511 4202560 9820 0 0 0 286 2 0 0 16 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25624] ppid=25604 vsize=876236 CPUtime=0.13
/proc/25617/task/25624/stat : 25624 (java) S 25604 25603 1511 34817 1511 4202560 1186 0 0 0 13 0 0 0 20 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25625] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25625/stat : 25625 (java) S 25604 25603 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
[pid=25617/tid=25626] ppid=25604 vsize=876236 CPUtime=0
/proc/25617/task/25626/stat : 25626 (java) S 25604 25603 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 4760274 897265664 90603 1283457024 134512640 134550932 4294606944 18446744073709551615 4294960130 0 4 0 16800975 18446612132974983872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 22.14
Current children cumulated vsize (KiB) 881380

Child status: 0
Real time (s): 22.7798
CPU time (s): 22.2134
CPU user time (s): 21.8454
CPU system time (s): 0.368023
CPU usage (%): 97.5134
Max. virtual memory (cumulated for all children) (KiB): 884720

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 21.8454
system time used= 0.368023
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 99953
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= 1337
involuntary context switches= 1496

runsolver used 0.012 second user time and 0 second system time

The end