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/201012070034/p2cudf-trendy-1.9/3e4f8550-0b33-11df-942d-00163e1d94dc.cudf.debian-dudf.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/debian-dudf//3e4f8550-0b33-11df-942d-00163e1d94dc.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/3e4f8550-0b33-11df-942d-00163e1d94dc.cudf.debian-dudf.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.03 1.00 1/43 18669
/proc/meminfo: memFree=961988/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=3028 CPUtime=0
/proc/18669/stat : 18669 (runsolver) D 18668 18669 4159 34819 4229 4202560 73 0 0 0 0 0 0 0 22 0 1 0 881207300 3100672 94 1283457024 134512640 134586868 4289184656 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/18669/statm: 757 94 62 19 0 55 0

[startup+0.145985 s]
/proc/loadavg: 1.05 1.03 1.00 1/43 18669
/proc/meminfo: memFree=961988/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+0.206098 s]
/proc/loadavg: 1.05 1.03 1.00 1/43 18669
/proc/meminfo: memFree=961988/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+0.306012 s]
/proc/loadavg: 1.05 1.03 1.00 1/43 18669
/proc/meminfo: memFree=961988/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+0.706046 s]
/proc/loadavg: 1.05 1.03 1.00 1/43 18669
/proc/meminfo: memFree=961988/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+1.50611 s]
/proc/loadavg: 1.05 1.03 1.00 1/46 18683
/proc/meminfo: memFree=941948/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=856004 CPUtime=0.06
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 3603 0 150 0 6 0 0 0 18 0 10 0 881207383 876548096 3454 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 214001 3454 1590 10 0 211119 0
Current children cumulated CPU time (s) 0.06
Current children cumulated vsize (KiB) 861152

[startup+3.10919 s]
/proc/loadavg: 1.05 1.03 1.00 4/55 18692
/proc/meminfo: memFree=913120/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=872296 CPUtime=1.35
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 23299 0 152 0 127 8 0 0 18 0 10 0 881207383 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218074 19104 1743 10 0 215164 0
[pid=18683/tid=18684] ppid=18671 vsize=872296 CPUtime=0.43
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 13662 0 59 0 37 6 0 0 20 0 10 0 881207411 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4095187400 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 15 0 10 0 881207424 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 8 0 1 0 0 0 0 0 19 0 10 0 881207425 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 20 0 10 0 881207425 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 10 0 881207436 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=872296 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 420 0 1 0 18 0 0 0 16 0 10 0 881207436 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=872296 CPUtime=0.73
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 8187 0 7 0 72 1 0 0 16 0 10 0 881207436 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 10 0 881207436 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 10 0 881207436 893231104 19104 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.35
Current children cumulated vsize (KiB) 877444
heavy processes:

[startup+6.30678 s]
/proc/loadavg: 1.21 1.06 1.01 2/55 18692
/proc/meminfo: memFree=648732/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=872296 CPUtime=4.22
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 75480 0 153 0 392 30 0 0 18 0 10 0 881207383 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218074 68942 1767 10 0 215164 0
[pid=18683/tid=18684] ppid=18671 vsize=872296 CPUtime=1.8
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50293 0 59 0 155 25 0 0 23 0 10 0 881207411 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=872296 CPUtime=0.3
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 13104 0 0 0 28 2 0 0 15 0 10 0 881207424 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 10 0 881207425 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 10 0 881207425 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 10 0 881207436 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=872296 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 10 0 881207436 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=872296 CPUtime=1.92
/proc/18683/task/18690/stat : 18690 (java) R 18671 18669 4159 34819 4229 4202560 10633 0 8 0 190 2 0 0 16 0 10 0 881207436 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4148289144 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 10 0 881207436 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=872296 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) R 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 10 0 881207436 893231104 68942 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.22
Current children cumulated vsize (KiB) 877444
heavy processes:

[startup+12.7132 s]
/proc/loadavg: 1.19 1.06 1.01 2/56 18695
/proc/meminfo: memFree=570504/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=872988 CPUtime=10.57
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 95911 0 153 0 1023 34 0 0 18 0 11 0 881207383 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218247 88188 1793 10 0 215337 0
[pid=18683/tid=18684] ppid=18671 vsize=872988 CPUtime=6.11
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50358 0 59 0 586 25 0 0 25 0 11 0 881207411 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4095888071 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=872988 CPUtime=0.87
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 32201 0 0 0 83 4 0 0 15 0 11 0 881207424 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=872988 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 11 0 881207425 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=872988 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 881207425 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=872988 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=872988 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=872988 CPUtime=3.39
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11897 0 8 0 336 3 0 0 15 0 11 0 881207436 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=872988 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=872988 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18695] ppid=18671 vsize=872988 CPUtime=0
/proc/18683/task/18695/stat : 18695 (java) S 18671 18669 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 881208448 893939712 88188 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.57
Current children cumulated vsize (KiB) 878136

[startup+25.5074 s]
/proc/loadavg: 1.15 1.06 1.01 2/56 18706
/proc/meminfo: memFree=541676/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873312 CPUtime=23.35
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103078 0 153 0 2298 37 0 0 18 0 11 0 881207383 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218328 95355 1794 10 0 215418 0
[pid=18683/tid=18684] ppid=18671 vsize=873312 CPUtime=18.18
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50361 0 59 0 1793 25 0 0 25 0 11 0 881207411 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4096298644 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873312 CPUtime=1.3
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 39341 0 0 0 122 8 0 0 16 0 11 0 881207424 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 11 0 881207425 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 881207425 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873312 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873312 CPUtime=3.67
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 364 3 0 0 15 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18706] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18706/stat : 18706 (java) S 18671 18669 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 23 0 11 0 881209659 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 23.35
Current children cumulated vsize (KiB) 878460

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

[startup+25.6074 s]
/proc/loadavg: 1.15 1.06 1.01 2/56 18706
/proc/meminfo: memFree=541676/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873312 CPUtime=23.45
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103078 0 153 0 2308 37 0 0 18 0 11 0 881207383 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218328 95355 1794 10 0 215418 0
[pid=18683/tid=18684] ppid=18671 vsize=873312 CPUtime=18.28
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50361 0 59 0 1803 25 0 0 25 0 11 0 881207411 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4096170612 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873312 CPUtime=1.3
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 39341 0 0 0 122 8 0 0 16 0 11 0 881207424 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 11 0 881207425 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 881207425 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873312 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873312 CPUtime=3.67
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 364 3 0 0 15 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18706] ppid=18671 vsize=873312 CPUtime=0
/proc/18683/task/18706/stat : 18706 (java) S 18671 18669 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 23 0 11 0 881209659 894271488 95355 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 23.45
Current children cumulated vsize (KiB) 878460

[startup+38.4116 s]
/proc/loadavg: 1.20 1.07 1.02 4/56 18717
/proc/meminfo: memFree=540560/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873636 CPUtime=36.24
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103600 0 153 0 3587 37 0 0 18 0 11 0 881207383 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218409 95877 1794 10 0 215499 0
[pid=18683/tid=18684] ppid=18671 vsize=873636 CPUtime=30.1
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50362 0 59 0 2985 25 0 0 25 0 11 0 881207411 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4096209525 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873636 CPUtime=2.1
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 39857 0 0 0 202 8 0 0 16 0 11 0 881207424 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873636 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873636 CPUtime=3.84
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 381 3 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18717] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18717/stat : 18717 (java) S 18671 18669 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 23 0 11 0 881210897 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 36.24
Current children cumulated vsize (KiB) 878784

[startup+41.6124 s]
/proc/loadavg: 1.34 1.10 1.03 2/56 18717
/proc/meminfo: memFree=539568/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873636 CPUtime=39.44
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103600 0 153 0 3907 37 0 0 18 0 11 0 881207383 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218409 95877 1794 10 0 215499 0
[pid=18683/tid=18684] ppid=18671 vsize=873636 CPUtime=33.3
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50362 0 59 0 3305 25 0 0 25 0 11 0 881207411 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4096181676 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873636 CPUtime=2.1
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 39857 0 0 0 202 8 0 0 15 0 11 0 881207424 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873636 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873636 CPUtime=3.84
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 381 3 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18717] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18717/stat : 18717 (java) S 18671 18669 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 23 0 11 0 881210897 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 39.44
Current children cumulated vsize (KiB) 878784

[startup+43.2128 s]
/proc/loadavg: 1.31 1.10 1.03 2/56 18717
/proc/meminfo: memFree=539568/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873636 CPUtime=41.04
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103600 0 153 0 4067 37 0 0 18 0 11 0 881207383 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218409 95877 1794 10 0 215499 0
[pid=18683/tid=18684] ppid=18671 vsize=873636 CPUtime=34.9
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50362 0 59 0 3465 25 0 0 25 0 11 0 881207411 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4095446215 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873636 CPUtime=2.1
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 39857 0 0 0 202 8 0 0 15 0 11 0 881207424 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873636 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873636 CPUtime=3.84
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 381 3 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18717] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18717/stat : 18717 (java) S 18671 18669 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 23 0 11 0 881210897 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 41.04
Current children cumulated vsize (KiB) 878784

[startup+44.0131 s]
/proc/loadavg: 1.31 1.10 1.03 4/56 18718
/proc/meminfo: memFree=539568/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873636 CPUtime=41.83
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103600 0 153 0 4146 37 0 0 18 0 11 0 881207383 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218409 95877 1794 10 0 215499 0
[pid=18683/tid=18684] ppid=18671 vsize=873636 CPUtime=35.68
/proc/18683/task/18684/stat : 18684 (java) R 18671 18669 4159 34819 4229 4202560 50362 0 59 0 3543 25 0 0 25 0 11 0 881207411 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873636 CPUtime=2.11
/proc/18683/task/18685/stat : 18685 (java) R 18671 18669 4159 34819 4229 4202560 39857 0 0 0 203 8 0 0 16 0 11 0 881207424 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4153088449 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 881207425 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873636 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873636 CPUtime=3.84
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 381 3 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18718] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18718/stat : 18718 (java) R 18671 18669 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 24 0 11 0 881211679 894603264 95877 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 41.83
Current children cumulated vsize (KiB) 878784

[startup+44.4048 s]
/proc/loadavg: 1.31 1.10 1.03 4/56 18718
/proc/meminfo: memFree=539568/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873636 CPUtime=41.9
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103606 0 153 0 4153 37 0 0 18 0 11 0 881207383 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218409 95883 1794 10 0 215499 0
[pid=18683/tid=18684] ppid=18671 vsize=873636 CPUtime=35.69
/proc/18683/task/18684/stat : 18684 (java) S 18671 18669 4159 34819 4229 4202560 50368 0 59 0 3544 25 0 0 25 0 11 0 881207411 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873636 CPUtime=2.11
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 39857 0 0 0 203 8 0 0 16 0 11 0 881207424 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 881207425 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 881207425 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 881207436 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873636 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 11 0 881207436 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873636 CPUtime=3.9
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 387 3 0 0 16 0 11 0 881207436 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 881207436 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 11 0 881207436 894603264 95883 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 41.9
Current children cumulated vsize (KiB) 878784

[startup+44.8048 s]
/proc/loadavg: 1.31 1.10 1.03 4/56 18718
/proc/meminfo: memFree=539568/1048576 swapFree=2077676/2097144
[pid=18669] ppid=18668 vsize=2572 CPUtime=0
/proc/18669/stat : 18669 (p2cudf-trendy-1) S 18668 18669 4159 34819 4229 4202496 365 0 11 0 0 0 0 0 23 0 1 0 881207300 2633728 272 1283457024 134512640 135304128 4289592864 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18669/statm: 643 272 233 194 0 29 0
[pid=18671] ppid=18669 vsize=2576 CPUtime=0
/proc/18671/stat : 18671 (p2cudf) S 18669 18669 4159 34819 4229 4202496 394 2779 0 136 0 0 0 0 18 0 1 0 881207308 2637824 283 1283457024 134512640 135304128 4291557024 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18671/statm: 644 283 241 194 0 30 0
[pid=18683] ppid=18671 vsize=873636 CPUtime=42.08
/proc/18683/stat : 18683 (java) S 18671 18669 4159 34819 4229 4202496 103608 0 153 0 4166 42 0 0 18 0 10 0 881207383 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18683/statm: 218409 95885 1794 10 0 215499 0
[pid=18683/tid=18684] ppid=18671 vsize=873636 CPUtime=35.69
/proc/18683/task/18684/stat : 18684 (java) S 18671 18669 4159 34819 4229 4202560 50368 0 59 0 3544 25 0 0 19 0 10 0 881207411 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18685] ppid=18671 vsize=873636 CPUtime=2.11
/proc/18683/task/18685/stat : 18685 (java) S 18671 18669 4159 34819 4229 4202560 39857 0 0 0 203 8 0 0 16 0 10 0 881207424 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 0 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18686] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18686/stat : 18686 (java) S 18671 18669 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 10 0 881207425 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18687] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18687/stat : 18687 (java) S 18671 18669 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 10 0 881207425 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18688] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18688/stat : 18688 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 10 0 881207436 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18689] ppid=18671 vsize=873636 CPUtime=0.18
/proc/18683/task/18689/stat : 18689 (java) S 18671 18669 4159 34819 4229 4202560 422 0 1 0 18 0 0 0 15 0 10 0 881207436 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18690] ppid=18671 vsize=873636 CPUtime=3.93
/proc/18683/task/18690/stat : 18690 (java) S 18671 18669 4159 34819 4229 4202560 11917 0 8 0 390 3 0 0 15 0 10 0 881207436 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18691] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18691/stat : 18691 (java) S 18671 18669 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 10 0 881207436 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
[pid=18683/tid=18692] ppid=18671 vsize=873636 CPUtime=0
/proc/18683/task/18692/stat : 18692 (java) S 18671 18669 4159 34819 4229 4202560 3 0 0 0 0 0 0 0 15 0 10 0 881207436 894603264 95885 1283457024 134512640 134550932 4290647136 18446744073709551615 4294960130 0 4 0 16800975 18446612132549438336 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 42.08
Current children cumulated vsize (KiB) 878784

Child status: 0
Real time (s): 44.8658
CPU time (s): 42.1066
CPU user time (s): 41.6626
CPU system time (s): 0.444027
CPU usage (%): 93.8502
Max. virtual memory (cumulated for all children) (KiB): 882004

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 41.6626
system time used= 0.444027
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 107167
page faults= 300
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 3352
involuntary context switches= 2859

runsolver used 0 second user time and 0.012 second system time

The end