runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/9bb87ab4-4c36-11df-bc1e-00163e7a6f5e.cudf.debian.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/debian//9bb87ab4-4c36-11df-bc1e-00163e7a6f5e.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/9bb87ab4-4c36-11df-bc1e-00163e7a6f5e.cudf.debian.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.05 1.08 1.04 3/35 21032
/proc/meminfo: memFree=690668/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) R 21030 21031 29493 34817 29493 4202496 356 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2564 CPUtime=0
/proc/21032/stat : 21032 (p2cudf-trendy-1) R 21031 21031 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 40 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/21032/statm: 641 40 0 194 0 28 0

[startup+0.17559 s]
/proc/loadavg: 1.05 1.08 1.04 3/35 21032
/proc/meminfo: memFree=690668/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.205584 s]
/proc/loadavg: 1.05 1.08 1.04 3/35 21032
/proc/meminfo: memFree=690668/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.305596 s]
/proc/loadavg: 1.05 1.08 1.04 3/35 21032
/proc/meminfo: memFree=690668/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+0.705708 s]
/proc/loadavg: 1.05 1.08 1.04 3/35 21032
/proc/meminfo: memFree=690668/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5132

[startup+1.50597 s]
/proc/loadavg: 1.13 1.09 1.04 3/46 21054
/proc/meminfo: memFree=642016/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=884528 CPUtime=1.46
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 19659 0 1 0 139 7 0 0 25 0 10 0 75491179 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 221132 16265 1730 10 0 218223 0
[pid=21045/tid=21046] ppid=21032 vsize=884528 CPUtime=0.79
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 8296 0 1 0 77 2 0 0 25 0 10 0 75491179 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4094883105 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=884528 CPUtime=0
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75491181 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=884528 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75491181 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=884528 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75491181 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=884528 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75491184 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=884528 CPUtime=0.43
/proc/21045/task/21051/stat : 21051 (java) R 21032 21031 29493 34817 29493 4202560 8477 0 0 0 39 4 0 0 25 0 10 0 75491184 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=884528 CPUtime=0.22
/proc/21045/task/21052/stat : 21052 (java) R 21032 21031 29493 34817 29493 4202560 1808 0 0 0 22 0 0 0 18 0 10 0 75491184 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4151854255 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=884528 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75491184 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=884528 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75491184 905756672 16265 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 889660

[startup+3.10636 s]
/proc/loadavg: 1.13 1.09 1.04 3/46 21054
/proc/meminfo: memFree=552364/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=882140 CPUtime=3.06
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 74876 0 1 0 279 27 0 0 25 0 10 0 75491179 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220535 70876 1738 10 0 217626 0
[pid=21045/tid=21046] ppid=21032 vsize=882140 CPUtime=1.82
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 50020 0 1 0 162 20 0 0 25 0 10 0 75491179 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4095190052 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=882140 CPUtime=0.27
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 13011 0 0 0 27 0 0 0 16 0 10 0 75491181 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=882140 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 10 0 75491181 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=882140 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 10 0 75491181 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=882140 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75491184 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=882140 CPUtime=0.52
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 8536 0 0 0 48 4 0 0 15 0 10 0 75491184 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=882140 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 10 0 75491184 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=882140 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75491184 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=882140 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75491184 903311360 70876 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 887272

[startup+6.32708 s]
/proc/loadavg: 1.12 1.09 1.04 2/46 21054
/proc/meminfo: memFree=360784/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=882036 CPUtime=6.28
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 92517 0 1 0 592 36 0 0 25 0 10 0 75491179 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220509 86531 1764 10 0 217600 0
[pid=21045/tid=21046] ppid=21032 vsize=882036 CPUtime=2.97
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 50210 0 1 0 276 21 0 0 25 0 10 0 75491179 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=882036 CPUtime=0.76
/proc/21045/task/21047/stat : 21047 (java) R 21032 21031 29493 34817 29493 4202560 28257 0 0 0 69 7 0 0 16 0 10 0 75491181 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4149624080 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=882036 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 10 0 75491181 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=882036 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 10 0 75491181 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=882036 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75491184 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=882036 CPUtime=2.11
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10741 0 0 0 205 6 0 0 15 0 10 0 75491184 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=882036 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 10 0 75491184 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=882036 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75491184 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=882036 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75491184 903204864 86531 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.31
Current children cumulated vsize (KiB) 887168

[startup+12.709 s]
/proc/loadavg: 1.11 1.09 1.04 2/47 21055
/proc/meminfo: memFree=334868/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=880888 CPUtime=12.65
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 96372 0 1 0 1228 37 0 0 25 0 11 0 75491179 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220222 90018 1780 10 0 217313 0
[pid=21045/tid=21046] ppid=21032 vsize=880888 CPUtime=8.47
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 50262 0 1 0 826 21 0 0 25 0 11 0 75491179 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4096237939 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=880888 CPUtime=0.82
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 31959 0 0 0 74 8 0 0 15 0 11 0 75491181 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=880888 CPUtime=2.92
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10838 0 0 0 286 6 0 0 15 0 11 0 75491184 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=880888 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 11 0 75491184 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75491184 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 75491869 902029312 90018 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.68
Current children cumulated vsize (KiB) 886020

[startup+25.5034 s]
/proc/loadavg: 1.09 1.09 1.04 2/47 21055
/proc/meminfo: memFree=300644/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=880888 CPUtime=25.43
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 105318 0 1 0 2504 39 0 0 25 0 11 0 75491179 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220222 98964 1786 10 0 217313 0
[pid=21045/tid=21046] ppid=21032 vsize=880888 CPUtime=20.52
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 50263 0 1 0 2031 21 0 0 25 0 11 0 75491179 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4096180011 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=880888 CPUtime=1.31
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 40874 0 0 0 122 9 0 0 15 0 11 0 75491181 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=880888 CPUtime=3.15
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10868 0 0 0 309 6 0 0 16 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=880888 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 75491869 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.46
Current children cumulated vsize (KiB) 886020

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

[startup+31.9057 s]
/proc/loadavg: 1.08 1.08 1.04 2/47 21055
/proc/meminfo: memFree=300520/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=880888 CPUtime=31.83
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 105318 0 1 0 3144 39 0 0 25 0 11 0 75491179 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220222 98964 1786 10 0 217313 0
[pid=21045/tid=21046] ppid=21032 vsize=880888 CPUtime=26.92
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 50263 0 1 0 2671 21 0 0 25 0 11 0 75491179 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4096111609 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=880888 CPUtime=1.31
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 40874 0 0 0 122 9 0 0 15 0 11 0 75491181 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=880888 CPUtime=3.15
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10868 0 0 0 309 6 0 0 15 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=880888 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75491184 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 75491869 902029312 98964 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 31.86
Current children cumulated vsize (KiB) 886020

[startup+38.3077 s]
/proc/loadavg: 1.07 1.08 1.04 2/47 21055
/proc/meminfo: memFree=299528/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=880888 CPUtime=38.21
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 105568 0 1 0 3782 39 0 0 25 0 11 0 75491179 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220222 99214 1786 10 0 217313 0
[pid=21045/tid=21046] ppid=21032 vsize=880888 CPUtime=32.82
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 50264 0 1 0 3261 21 0 0 25 0 11 0 75491179 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4096111686 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=880888 CPUtime=1.74
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 41123 0 0 0 165 9 0 0 15 0 11 0 75491181 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=880888 CPUtime=3.2
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10868 0 0 0 314 6 0 0 15 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=880888 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 75491869 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 38.24
Current children cumulated vsize (KiB) 886020

[startup+41.5088 s]
/proc/loadavg: 1.06 1.08 1.04 2/47 21055
/proc/meminfo: memFree=299528/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=880888 CPUtime=41.41
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 105568 0 1 0 4102 39 0 0 25 0 11 0 75491179 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220222 99214 1786 10 0 217313 0
[pid=21045/tid=21046] ppid=21032 vsize=880888 CPUtime=36.02
/proc/21045/task/21046/stat : 21046 (java) R 21032 21031 29493 34817 29493 4202560 50264 0 1 0 3581 21 0 0 25 0 11 0 75491179 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4096157503 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=880888 CPUtime=1.74
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 41123 0 0 0 165 9 0 0 15 0 11 0 75491181 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 11 0 75491181 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=880888 CPUtime=3.2
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10868 0 0 0 314 6 0 0 15 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=880888 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75491184 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=880888 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 75491869 902029312 99214 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 41.44
Current children cumulated vsize (KiB) 886020

[startup+43.1042 s]
/proc/loadavg: 1.06 1.08 1.04 2/47 21055
/proc/meminfo: memFree=299404/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=881212 CPUtime=42.9
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 105581 0 1 0 4251 39 0 0 25 0 12 0 75491179 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220303 99227 1786 10 0 217394 0
[pid=21045/tid=21046] ppid=21032 vsize=881212 CPUtime=37.43
/proc/21045/task/21046/stat : 21046 (java) S 21032 21031 29493 34817 29493 4202560 50272 0 1 0 3722 21 0 0 25 0 12 0 75491179 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=881212 CPUtime=1.75
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 41123 0 0 0 166 9 0 0 16 0 12 0 75491181 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 12 0 75491181 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 12 0 75491181 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=881212 CPUtime=3.27
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10868 0 0 0 321 6 0 0 16 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=881212 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 12 0 75491869 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 42.93
Current children cumulated vsize (KiB) 886344

[startup+43.5043 s]
/proc/loadavg: 1.06 1.08 1.04 2/47 21055
/proc/meminfo: memFree=299404/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=881212 CPUtime=42.9
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 105581 0 1 0 4251 39 0 0 25 0 12 0 75491179 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220303 99227 1786 10 0 217394 0
[pid=21045/tid=21046] ppid=21032 vsize=881212 CPUtime=37.43
/proc/21045/task/21046/stat : 21046 (java) S 21032 21031 29493 34817 29493 4202560 50272 0 1 0 3722 21 0 0 25 0 12 0 75491179 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=881212 CPUtime=1.75
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 41123 0 0 0 166 9 0 0 16 0 12 0 75491181 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 12 0 75491181 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 12 0 75491181 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=881212 CPUtime=3.27
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10868 0 0 0 321 6 0 0 16 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=881212 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 75491184 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 12 0 75491869 902361088 99227 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 42.93
Current children cumulated vsize (KiB) 886344

[startup+43.7003 s]
/proc/loadavg: 1.06 1.08 1.04 2/47 21055
/proc/meminfo: memFree=299404/1048576 swapFree=0/0
[pid=21031] ppid=21030 vsize=2564 CPUtime=0
/proc/21031/stat : 21031 (p2cudf-trendy-1) S 21030 21031 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 75491175 2625536 270 1283457024 134512640 135304128 4289068112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21031/statm: 641 270 230 194 0 28 0
[pid=21032] ppid=21031 vsize=2568 CPUtime=0.03
/proc/21032/stat : 21032 (p2cudf) S 21031 21031 29493 34817 29493 4202496 390 3016 0 1 1 0 2 0 25 0 1 0 75491175 2629632 280 1283457024 134512640 135304128 4288601040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/21032/statm: 642 280 239 194 0 29 0
[pid=21045] ppid=21032 vsize=881212 CPUtime=43.08
/proc/21045/stat : 21045 (java) S 21032 21031 29493 34817 29493 4202496 105584 0 1 0 4266 42 0 0 25 0 11 0 75491179 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/21045/statm: 220303 99230 1787 10 0 217394 0
[pid=21045/tid=21046] ppid=21032 vsize=881212 CPUtime=37.43
/proc/21045/task/21046/stat : 21046 (java) S 21032 21031 29493 34817 29493 4202560 50272 0 1 0 3722 21 0 0 19 0 11 0 75491179 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21047] ppid=21032 vsize=881212 CPUtime=1.75
/proc/21045/task/21047/stat : 21047 (java) S 21032 21031 29493 34817 29493 4202560 41123 0 0 0 166 9 0 0 16 0 11 0 75491181 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 0 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21048] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21048/stat : 21048 (java) S 21032 21031 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75491181 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21049] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21049/stat : 21049 (java) S 21032 21031 29493 34817 29493 4202560 5 0 0 0 0 0 0 0 18 0 11 0 75491181 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21050] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21050/stat : 21050 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21051] ppid=21032 vsize=881212 CPUtime=3.32
/proc/21045/task/21051/stat : 21051 (java) S 21032 21031 29493 34817 29493 4202560 10868 0 0 0 326 6 0 0 15 0 11 0 75491184 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21052] ppid=21032 vsize=881212 CPUtime=0.42
/proc/21045/task/21052/stat : 21052 (java) S 21032 21031 29493 34817 29493 4202560 2235 0 0 0 41 1 0 0 16 0 11 0 75491184 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21053] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21053/stat : 21053 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75491184 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21054] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21054/stat : 21054 (java) S 21032 21031 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75491184 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
[pid=21045/tid=21055] ppid=21032 vsize=881212 CPUtime=0
/proc/21045/task/21055/stat : 21055 (java) S 21032 21031 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 24 0 11 0 75491869 902361088 99230 1283457024 134512640 134550932 4290296848 18446744073709551615 4294960130 0 4 0 16800975 18446612132467746624 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 43.11
Current children cumulated vsize (KiB) 886344

Child status: 0
Real time (s): 43.7471
CPU time (s): 43.1587
CPU user time (s): 42.6987
CPU system time (s): 0.460028
CPU usage (%): 98.6551
Max. virtual memory (cumulated for all children) (KiB): 891104

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 42.6987
system time used= 0.460028
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 109387
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= 1775
involuntary context switches= 2158

runsolver used 0 second user time and 0 second system time

The end