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/4028.cudf.caixa.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/caixa//4028.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/4028.cudf.caixa.result 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 seconds
Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB
Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 1.64 1.81 1.72 5/36 28026
/proc/meminfo: memFree=308944/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2568 CPUtime=0
/proc/28025/stat : 28025 (p2cudf) R 28024 28024 29493 34817 29493 4202496 345 0 0 0 0 0 0 0 25 0 1 0 76097699 2629632 270 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/28025/statm: 642 270 231 194 0 29 0
[pid=28026] ppid=28025 vsize=2568 CPUtime=0
/proc/28026/stat : 28026 (p2cudf) R 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 76097699 2629632 39 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/28026/statm: 642 39 0 194 0 29 0

[startup+0.117791 s]
/proc/loadavg: 1.64 1.81 1.72 5/36 28026
/proc/meminfo: memFree=308944/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.207786 s]
/proc/loadavg: 1.64 1.81 1.72 5/36 28026
/proc/meminfo: memFree=308944/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.307795 s]
/proc/loadavg: 1.64 1.81 1.72 5/36 28026
/proc/meminfo: memFree=308944/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.707919 s]
/proc/loadavg: 1.64 1.81 1.72 5/36 28026
/proc/meminfo: memFree=308944/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+1.50811 s]
/proc/loadavg: 1.67 1.81 1.73 5/46 28047
/proc/meminfo: memFree=266136/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
[pid=28038] ppid=28025 vsize=880268 CPUtime=1.48
/proc/28038/stat : 28038 (java) S 28025 28024 29493 34817 29493 4202496 19218 0 1 0 134 14 0 0 25 0 10 0 76097702 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/28038/statm: 220067 14428 1730 10 0 217158 0
[pid=28038/tid=28039] ppid=28025 vsize=880268 CPUtime=0.69
/proc/28038/task/28039/stat : 28039 (java) R 28025 28024 29493 34817 29493 4202560 7018 0 1 0 66 3 0 0 25 0 10 0 76097703 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28040] ppid=28025 vsize=880268 CPUtime=0
/proc/28038/task/28040/stat : 28040 (java) S 28025 28024 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 16 0 10 0 76097705 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28041] ppid=28025 vsize=880268 CPUtime=0
/proc/28038/task/28041/stat : 28041 (java) S 28025 28024 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76097705 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28042] ppid=28025 vsize=880268 CPUtime=0
/proc/28038/task/28042/stat : 28042 (java) S 28025 28024 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76097705 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28043] ppid=28025 vsize=880268 CPUtime=0
/proc/28038/task/28043/stat : 28043 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76097708 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28044] ppid=28025 vsize=880268 CPUtime=0.55
/proc/28038/task/28044/stat : 28044 (java) R 28025 28024 29493 34817 29493 4202560 9277 0 0 0 48 7 0 0 22 0 10 0 76097708 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4150199547 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28045] ppid=28025 vsize=880268 CPUtime=0.21
/proc/28038/task/28045/stat : 28045 (java) R 28025 28024 29493 34817 29493 4202560 1844 0 0 0 19 2 0 0 24 0 10 0 76097708 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4152259816 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28046] ppid=28025 vsize=880268 CPUtime=0
/proc/28038/task/28046/stat : 28046 (java) S 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 76097708 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28047] ppid=28025 vsize=880268 CPUtime=0
/proc/28038/task/28047/stat : 28047 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 76097708 901394432 14428 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 885412

[startup+3.10855 s]
/proc/loadavg: 1.67 1.81 1.73 4/46 28047
/proc/meminfo: memFree=234020/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
[pid=28038] ppid=28025 vsize=886020 CPUtime=3.07
/proc/28038/stat : 28038 (java) S 28025 28024 29493 34817 29493 4202496 49821 0 1 0 278 29 0 0 25 0 10 0 76097702 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/28038/statm: 221505 42365 1742 10 0 218596 0
[pid=28038/tid=28039] ppid=28025 vsize=886020 CPUtime=1.4
/proc/28038/task/28039/stat : 28039 (java) R 28025 28024 29493 34817 29493 4202560 33383 0 1 0 125 15 0 0 25 0 10 0 76097703 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28040] ppid=28025 vsize=886020 CPUtime=0
/proc/28038/task/28040/stat : 28040 (java) S 28025 28024 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 10 0 76097705 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28041] ppid=28025 vsize=886020 CPUtime=0
/proc/28038/task/28041/stat : 28041 (java) S 28025 28024 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76097705 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28042] ppid=28025 vsize=886020 CPUtime=0
/proc/28038/task/28042/stat : 28042 (java) S 28025 28024 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76097705 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28043] ppid=28025 vsize=886020 CPUtime=0
/proc/28038/task/28043/stat : 28043 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76097708 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28044] ppid=28025 vsize=886020 CPUtime=0.99
/proc/28038/task/28044/stat : 28044 (java) S 28025 28024 29493 34817 29493 4202560 11526 0 0 0 92 7 0 0 22 0 10 0 76097708 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28045] ppid=28025 vsize=886020 CPUtime=0.64
/proc/28038/task/28045/stat : 28045 (java) R 28025 28024 29493 34817 29493 4202560 3833 0 0 0 59 5 0 0 24 0 10 0 76097708 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4149742802 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28046] ppid=28025 vsize=886020 CPUtime=0
/proc/28038/task/28046/stat : 28046 (java) S 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 76097708 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28047] ppid=28025 vsize=886020 CPUtime=0
/proc/28038/task/28047/stat : 28047 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 76097708 907284480 42365 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 891164

[startup+6.3094 s]
/proc/loadavg: 1.67 1.81 1.73 3/47 28048
/proc/meminfo: memFree=22352/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
[pid=28038] ppid=28025 vsize=886344 CPUtime=6.26
/proc/28038/stat : 28038 (java) S 28025 28024 29493 34817 29493 4202496 80965 0 1 0 589 37 0 0 25 0 11 0 76097702 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/28038/statm: 221586 73145 1777 10 0 218677 0
[pid=28038/tid=28039] ppid=28025 vsize=886344 CPUtime=3
/proc/28038/task/28039/stat : 28039 (java) R 28025 28024 29493 34817 29493 4202560 50260 0 1 0 282 18 0 0 25 0 11 0 76097703 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4096571565 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28040] ppid=28025 vsize=886344 CPUtime=0.33
/proc/28038/task/28040/stat : 28040 (java) S 28025 28024 29493 34817 29493 4202560 13742 0 0 0 30 3 0 0 15 0 11 0 76097705 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28041] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28041/stat : 28041 (java) S 28025 28024 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 76097705 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28042] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28042/stat : 28042 (java) S 28025 28024 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 76097705 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28043] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28043/stat : 28043 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76097708 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28044] ppid=28025 vsize=886344 CPUtime=1.59
/proc/28038/task/28044/stat : 28044 (java) S 28025 28024 29493 34817 29493 4202560 11625 0 0 0 151 8 0 0 16 0 11 0 76097708 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28045] ppid=28025 vsize=886344 CPUtime=1.31
/proc/28038/task/28045/stat : 28045 (java) S 28025 28024 29493 34817 29493 4202560 4260 0 0 0 126 5 0 0 24 0 11 0 76097708 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28046] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28046/stat : 28046 (java) S 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 76097708 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28047] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28047/stat : 28047 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 76097708 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28048] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28048/stat : 28048 (java) S 28025 28024 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 76098187 907616256 73145 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.28
Current children cumulated vsize (KiB) 891488

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

[startup+6.40941 s]
/proc/loadavg: 1.67 1.81 1.73 3/47 28048
/proc/meminfo: memFree=22352/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
[pid=28038] ppid=28025 vsize=886344 CPUtime=6.36
/proc/28038/stat : 28038 (java) S 28025 28024 29493 34817 29493 4202496 80968 0 1 0 598 38 0 0 25 0 11 0 76097702 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/28038/statm: 221586 73148 1778 10 0 218677 0
[pid=28038/tid=28039] ppid=28025 vsize=886344 CPUtime=3.09
/proc/28038/task/28039/stat : 28039 (java) R 28025 28024 29493 34817 29493 4202560 50260 0 1 0 289 20 0 0 25 0 11 0 76097703 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4160218963 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28040] ppid=28025 vsize=886344 CPUtime=0.33
/proc/28038/task/28040/stat : 28040 (java) S 28025 28024 29493 34817 29493 4202560 13742 0 0 0 30 3 0 0 15 0 11 0 76097705 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28041] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28041/stat : 28041 (java) S 28025 28024 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 76097705 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28042] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28042/stat : 28042 (java) S 28025 28024 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 76097705 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28043] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28043/stat : 28043 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76097708 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28044] ppid=28025 vsize=886344 CPUtime=1.59
/proc/28038/task/28044/stat : 28044 (java) S 28025 28024 29493 34817 29493 4202560 11625 0 0 0 151 8 0 0 16 0 11 0 76097708 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28045] ppid=28025 vsize=886344 CPUtime=1.32
/proc/28038/task/28045/stat : 28045 (java) R 28025 28024 29493 34817 29493 4202560 4263 0 0 0 127 5 0 0 22 0 11 0 76097708 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28046] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28046/stat : 28046 (java) S 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 76097708 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28047] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28047/stat : 28047 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 76097708 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28048] ppid=28025 vsize=886344 CPUtime=0
/proc/28038/task/28048/stat : 28048 (java) S 28025 28024 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 76098187 907616256 73148 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.38
Current children cumulated vsize (KiB) 891488

[startup+6.80618 s]
/proc/loadavg: 1.69 1.81 1.73 3/47 28048
/proc/meminfo: memFree=22352/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
[pid=28038] ppid=28025 vsize=885740 CPUtime=6.57
/proc/28038/stat : 28038 (java) S 28025 28024 29493 34817 29493 4202496 80995 0 2 0 619 38 0 0 25 0 12 0 76097702 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/28038/statm: 221435 72944 1780 10 0 218526 0
[pid=28038/tid=28039] ppid=28025 vsize=885740 CPUtime=3.17
/proc/28038/task/28039/stat : 28039 (java) S 28025 28024 29493 34817 29493 4202560 50268 0 1 0 297 20 0 0 25 0 12 0 76097703 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28040] ppid=28025 vsize=885740 CPUtime=0.33
/proc/28038/task/28040/stat : 28040 (java) S 28025 28024 29493 34817 29493 4202560 13742 0 0 0 30 3 0 0 16 0 12 0 76097705 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28041] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28041/stat : 28041 (java) S 28025 28024 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 76097705 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28042] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28042/stat : 28042 (java) S 28025 28024 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 76097705 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28043] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28043/stat : 28043 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 76097708 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28044] ppid=28025 vsize=885740 CPUtime=1.6
/proc/28038/task/28044/stat : 28044 (java) S 28025 28024 29493 34817 29493 4202560 11626 0 0 0 152 8 0 0 15 0 12 0 76097708 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28045] ppid=28025 vsize=885740 CPUtime=1.44
/proc/28038/task/28045/stat : 28045 (java) S 28025 28024 29493 34817 29493 4202560 4277 0 1 0 139 5 0 0 22 0 12 0 76097708 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28046] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28046/stat : 28046 (java) S 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 12 0 76097708 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28047] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28047/stat : 28047 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 76097708 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28048] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28048/stat : 28048 (java) S 28025 28024 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 76098187 906997760 72944 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.59
Current children cumulated vsize (KiB) 890884

[startup+7.21022 s]
/proc/loadavg: 1.69 1.81 1.73 3/47 28048
/proc/meminfo: memFree=22352/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
[pid=28038] ppid=28025 vsize=885740 CPUtime=6.65
/proc/28038/stat : 28038 (java) S 28025 28024 29493 34817 29493 4202496 80997 0 2 0 625 40 0 0 25 0 12 0 76097702 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/28038/statm: 221435 72946 1780 10 0 218526 0
[pid=28038/tid=28039] ppid=28025 vsize=885740 CPUtime=3.17
/proc/28038/task/28039/stat : 28039 (java) S 28025 28024 29493 34817 29493 4202560 50268 0 1 0 297 20 0 0 25 0 12 0 76097703 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28040] ppid=28025 vsize=885740 CPUtime=0.33
/proc/28038/task/28040/stat : 28040 (java) S 28025 28024 29493 34817 29493 4202560 13742 0 0 0 30 3 0 0 16 0 12 0 76097705 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28041] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28041/stat : 28041 (java) S 28025 28024 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 76097705 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28042] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28042/stat : 28042 (java) S 28025 28024 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 76097705 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28043] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28043/stat : 28043 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 76097708 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28044] ppid=28025 vsize=885740 CPUtime=1.6
/proc/28038/task/28044/stat : 28044 (java) S 28025 28024 29493 34817 29493 4202560 11626 0 0 0 152 8 0 0 15 0 12 0 76097708 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28045] ppid=28025 vsize=885740 CPUtime=1.45
/proc/28038/task/28045/stat : 28045 (java) S 28025 28024 29493 34817 29493 4202560 4277 0 1 0 140 5 0 0 16 0 12 0 76097708 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28046] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28046/stat : 28046 (java) S 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 12 0 76097708 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28047] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28047/stat : 28047 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 76097708 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28048] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28048/stat : 28048 (java) S 28025 28024 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 76098187 906997760 72946 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.67
Current children cumulated vsize (KiB) 890884

[startup+7.31024 s]
/proc/loadavg: 1.69 1.81 1.73 3/47 28048
/proc/meminfo: memFree=22352/1048576 swapFree=0/0
[pid=28024] ppid=28023 vsize=2572 CPUtime=0
/proc/28024/stat : 28024 (p2cudf-trendy-1) S 28023 28024 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76097699 2633728 271 1283457024 134512640 135304128 4289922400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28024/statm: 643 271 230 194 0 30 0
[pid=28025] ppid=28024 vsize=2572 CPUtime=0.02
/proc/28025/stat : 28025 (p2cudf) S 28024 28024 29493 34817 29493 4202496 391 3011 0 1 0 0 2 0 25 0 1 0 76097699 2633728 280 1283457024 134512640 135304128 4289494256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/28025/statm: 643 280 239 194 0 30 0
[pid=28038] ppid=28025 vsize=885740 CPUtime=6.75
/proc/28038/stat : 28038 (java) S 28025 28024 29493 34817 29493 4202496 81004 0 2 0 632 43 0 0 25 0 12 0 76097702 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/28038/statm: 221435 72953 1780 10 0 218526 0
[pid=28038/tid=28039] ppid=28025 vsize=885740 CPUtime=3.17
/proc/28038/task/28039/stat : 28039 (java) S 28025 28024 29493 34817 29493 4202560 50268 0 1 0 297 20 0 0 25 0 12 0 76097703 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28040] ppid=28025 vsize=885740 CPUtime=0.33
/proc/28038/task/28040/stat : 28040 (java) S 28025 28024 29493 34817 29493 4202560 13742 0 0 0 30 3 0 0 16 0 12 0 76097705 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28041] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28041/stat : 28041 (java) S 28025 28024 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 76097705 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28042] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28042/stat : 28042 (java) S 28025 28024 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 76097705 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28043] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28043/stat : 28043 (java) S 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 76097708 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28044] ppid=28025 vsize=885740 CPUtime=1.6
/proc/28038/task/28044/stat : 28044 (java) S 28025 28024 29493 34817 29493 4202560 11626 0 0 0 152 8 0 0 15 0 12 0 76097708 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28045] ppid=28025 vsize=885740 CPUtime=1.5
/proc/28038/task/28045/stat : 28045 (java) S 28025 28024 29493 34817 29493 4202560 4284 0 1 0 145 5 0 0 16 0 12 0 76097708 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28046] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28046/stat : 28046 (java) S 28025 28024 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 12 0 76097708 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=28038/tid=28047] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28047/stat : 28047 (java) R 28025 28024 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 76097708 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=28038/tid=28048] ppid=28025 vsize=885740 CPUtime=0
/proc/28038/task/28048/stat : 28048 (java) S 28025 28024 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 76098187 906997760 72953 1283457024 134512640 134550932 4286576832 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.77
Current children cumulated vsize (KiB) 890884

Child status: 0
Real time (s): 7.39802
CPU time (s): 6.82843
CPU user time (s): 6.3564
CPU system time (s): 0.472029
CPU usage (%): 92.3007
Max. virtual memory (cumulated for all children) (KiB): 893592

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 6.3564
system time used= 0.472029
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 84803
page faults= 3
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 598
involuntary context switches= 589

runsolver used 0 second user time and 0 second system time

The end