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/4a69cf16-c731-11df-9182-00163e3d3b7c.cudf.debian.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/debian//4a69cf16-c731-11df-9182-00163e3d3b7c.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/4a69cf16-c731-11df-9182-00163e3d3b7c.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.20 1.09 1.02 5/39 19243
/proc/meminfo: memFree=649760/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) R 19241 19242 29493 34817 29493 4202496 356 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2568 CPUtime=0
/proc/19243/stat : 19243 (p2cudf-trendy-1) R 19242 19242 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 39 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/19243/statm: 642 39 0 194 0 29 0

[startup+0.185596 s]
/proc/loadavg: 1.20 1.09 1.02 5/39 19243
/proc/meminfo: memFree=649760/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.04
Current children cumulated vsize (KiB) 5140

[startup+0.205592 s]
/proc/loadavg: 1.20 1.09 1.02 5/39 19243
/proc/meminfo: memFree=649760/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.04
Current children cumulated vsize (KiB) 5140

[startup+0.30561 s]
/proc/loadavg: 1.20 1.09 1.02 5/39 19243
/proc/meminfo: memFree=649760/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.04
Current children cumulated vsize (KiB) 5140

[startup+0.705709 s]
/proc/loadavg: 1.20 1.09 1.02 5/39 19243
/proc/meminfo: memFree=649760/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.04
Current children cumulated vsize (KiB) 5140

[startup+1.50588 s]
/proc/loadavg: 1.20 1.09 1.02 3/50 19265
/proc/meminfo: memFree=601728/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=885136 CPUtime=1.46
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 20027 0 1 0 140 6 0 0 25 0 10 0 74919732 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 221284 16421 1729 10 0 218375 0
[pid=19256/tid=19257] ppid=19243 vsize=885136 CPUtime=0.79
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 8316 0 1 0 76 3 0 0 25 0 10 0 74919733 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4095342532 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=885136 CPUtime=0
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 18 0 10 0 74919735 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=885136 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 74919735 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=885136 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 74919735 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=885136 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74919738 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=885136 CPUtime=0.45
/proc/19256/task/19262/stat : 19262 (java) R 19243 19242 29493 34817 29493 4202560 8376 0 0 0 42 3 0 0 25 0 10 0 74919738 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=885136 CPUtime=0.22
/proc/19256/task/19263/stat : 19263 (java) R 19243 19242 29493 34817 29493 4202560 2253 0 0 0 22 0 0 0 18 0 10 0 74919738 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4147671081 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=885136 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74919738 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=885136 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) S 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 74919738 906379264 16421 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 890276

[startup+3.11638 s]
/proc/loadavg: 1.20 1.09 1.02 3/50 19265
/proc/meminfo: memFree=509348/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=881724 CPUtime=3.07
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 74540 0 1 0 279 28 0 0 25 0 10 0 74919732 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220431 70328 1741 10 0 217522 0
[pid=19256/tid=19257] ppid=19243 vsize=881724 CPUtime=1.8
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 50067 0 1 0 157 23 0 0 25 0 10 0 74919733 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=881724 CPUtime=0.26
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 12505 0 0 0 24 2 0 0 16 0 10 0 74919735 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=881724 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 74919735 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=881724 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 74919735 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=881724 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74919738 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=881724 CPUtime=0.6
/proc/19256/task/19262/stat : 19262 (java) R 19243 19242 29493 34817 29493 4202560 8439 0 0 0 57 3 0 0 15 0 10 0 74919738 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4150213394 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=881724 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 10 0 74919738 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=881724 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74919738 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=881724 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) R 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 74919738 902885376 70328 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.11
Current children cumulated vsize (KiB) 886864

[startup+6.3072 s]
/proc/loadavg: 1.27 1.11 1.02 3/50 19265
/proc/meminfo: memFree=324712/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=882540 CPUtime=6.26
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 88681 0 1 0 592 34 0 0 25 0 11 0 74919732 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220635 82855 1774 10 0 217726 0
[pid=19256/tid=19257] ppid=19243 vsize=882540 CPUtime=2.89
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 50260 0 1 0 266 23 0 0 25 0 11 0 74919733 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4096446261 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=882540 CPUtime=0.56
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 24450 0 0 0 50 6 0 0 16 0 11 0 74919735 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=882540 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74919735 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=882540 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74919735 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=882540 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=882540 CPUtime=2.39
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10438 0 0 0 235 4 0 0 16 0 11 0 74919738 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=882540 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 11 0 74919738 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=882540 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=882540 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) S 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74919738 903720960 82855 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 887680

[startup+12.7092 s]
/proc/loadavg: 1.24 1.11 1.02 3/51 19267
/proc/meminfo: memFree=325696/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880536 CPUtime=12.67
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 88714 0 1 0 1233 34 0 0 25 0 11 0 74919732 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220134 82405 1780 10 0 217225 0
[pid=19256/tid=19257] ppid=19243 vsize=880536 CPUtime=9.08
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 50262 0 1 0 885 23 0 0 25 0 11 0 74919733 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4096446294 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880536 CPUtime=0.56
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 24450 0 0 0 50 6 0 0 15 0 11 0 74919735 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880536 CPUtime=2.61
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10469 0 0 0 257 4 0 0 15 0 11 0 74919738 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880536 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 11 0 74919738 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) R 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74919738 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74920323 901668864 82405 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.71
Current children cumulated vsize (KiB) 885676

[startup+25.5224 s]
/proc/loadavg: 1.19 1.10 1.02 3/51 19268
/proc/meminfo: memFree=325696/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880536 CPUtime=25.48
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 88783 0 1 0 2514 34 0 0 25 0 11 0 74919732 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220134 82474 1788 10 0 217225 0
[pid=19256/tid=19257] ppid=19243 vsize=880536 CPUtime=21.3
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 50264 0 1 0 2107 23 0 0 25 0 11 0 74919733 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880536 CPUtime=0.6
/proc/19256/task/19258/stat : 19258 (java) R 19243 19242 29493 34817 29493 4202560 24450 0 0 0 54 6 0 0 15 0 11 0 74919735 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4150255064 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880536 CPUtime=3.16
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10536 0 0 0 312 4 0 0 16 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880536 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) R 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74920323 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.52
Current children cumulated vsize (KiB) 885676

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

[startup+25.6225 s]
/proc/loadavg: 1.19 1.10 1.02 3/51 19268
/proc/meminfo: memFree=325696/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880536 CPUtime=25.58
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 88783 0 1 0 2524 34 0 0 25 0 11 0 74919732 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220134 82474 1788 10 0 217225 0
[pid=19256/tid=19257] ppid=19243 vsize=880536 CPUtime=21.3
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 50264 0 1 0 2107 23 0 0 25 0 11 0 74919733 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880536 CPUtime=0.7
/proc/19256/task/19258/stat : 19258 (java) R 19243 19242 29493 34817 29493 4202560 24450 0 0 0 64 6 0 0 15 0 11 0 74919735 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4150255064 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880536 CPUtime=3.16
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10536 0 0 0 312 4 0 0 16 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880536 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) S 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74919738 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74920323 901668864 82474 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.62
Current children cumulated vsize (KiB) 885676

[startup+27.2128 s]
/proc/loadavg: 1.19 1.10 1.02 3/51 19268
/proc/meminfo: memFree=288992/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880536 CPUtime=27.17
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 97933 0 1 0 2683 34 0 0 25 0 11 0 74919732 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220134 91624 1788 10 0 217225 0
[pid=19256/tid=19257] ppid=19243 vsize=880536 CPUtime=22.61
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 50264 0 1 0 2238 23 0 0 25 0 11 0 74919733 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4096439352 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880536 CPUtime=0.96
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 33600 0 0 0 90 6 0 0 16 0 11 0 74919735 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880536 CPUtime=3.18
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10536 0 0 0 314 4 0 0 16 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880536 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) S 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74920323 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 27.21
Current children cumulated vsize (KiB) 885676

[startup+28.8132 s]
/proc/loadavg: 1.19 1.10 1.02 2/51 19269
/proc/meminfo: memFree=288992/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880536 CPUtime=28.77
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 97933 0 1 0 2843 34 0 0 25 0 11 0 74919732 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220134 91624 1788 10 0 217225 0
[pid=19256/tid=19257] ppid=19243 vsize=880536 CPUtime=24.22
/proc/19256/task/19257/stat : 19257 (java) R 19243 19242 29493 34817 29493 4202560 50264 0 1 0 2399 23 0 0 25 0 11 0 74919733 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4096415400 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880536 CPUtime=0.96
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 33600 0 0 0 90 6 0 0 15 0 11 0 74919735 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 74919735 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880536 CPUtime=3.18
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10536 0 0 0 314 4 0 0 16 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880536 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) R 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74919738 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880536 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74920323 901668864 91624 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 28.81
Current children cumulated vsize (KiB) 885676

[startup+29.5074 s]
/proc/loadavg: 1.19 1.10 1.02 2/51 19269
/proc/meminfo: memFree=288992/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880860 CPUtime=29.1
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 97945 0 1 0 2876 34 0 0 25 0 12 0 74919732 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220215 91636 1788 10 0 217306 0
[pid=19256/tid=19257] ppid=19243 vsize=880860 CPUtime=24.49
/proc/19256/task/19257/stat : 19257 (java) S 19243 19242 29493 34817 29493 4202560 50272 0 1 0 2426 23 0 0 25 0 12 0 74919733 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880860 CPUtime=0.96
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 33600 0 0 0 90 6 0 0 16 0 12 0 74919735 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 74919735 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 74919735 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 74919738 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880860 CPUtime=3.24
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10536 0 0 0 320 4 0 0 15 0 12 0 74919738 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880860 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 12 0 74919738 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 74919738 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) S 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 12 0 74919738 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 74920323 902000640 91636 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 29.14
Current children cumulated vsize (KiB) 886000

[startup+29.7153 s]
/proc/loadavg: 1.09 1.08 1.01 1/52 19270
/proc/meminfo: memFree=288860/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880860 CPUtime=29.16
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 97946 0 1 0 2882 34 0 0 25 0 12 0 74919732 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220215 91637 1788 10 0 217306 0
[pid=19256/tid=19257] ppid=19243 vsize=880860 CPUtime=24.49
/proc/19256/task/19257/stat : 19257 (java) S 19243 19242 29493 34817 29493 4202560 50272 0 1 0 2426 23 0 0 25 0 12 0 74919733 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880860 CPUtime=0.96
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 33600 0 0 0 90 6 0 0 16 0 12 0 74919735 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 74919735 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 74919735 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 74919738 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880860 CPUtime=3.24
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10536 0 0 0 320 4 0 0 16 0 12 0 74919738 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880860 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 12 0 74919738 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 74919738 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) S 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 12 0 74919738 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 74920323 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19270] ppid=19243 vsize=880860 CPUtime=0.04
/proc/19256/task/19270/stat : 19270 (java) R 19243 19242 29493 34817 29493 4202560 5 0 0 0 4 0 0 0 20 0 12 0 74922644 902000640 91637 1283457024 134512640 134550932 4288176640 18446744073709551615 4095366024 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 29.2
Current children cumulated vsize (KiB) 886000

[startup+29.8154 s]
/proc/loadavg: 1.09 1.08 1.01 1/52 19270
/proc/meminfo: memFree=288860/1048576 swapFree=0/0
[pid=19242] ppid=19241 vsize=2568 CPUtime=0
/proc/19242/stat : 19242 (p2cudf-trendy-1) S 19241 19242 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 25 0 1 0 74919729 2629632 269 1283457024 134512640 135304128 4290309504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19242/statm: 642 269 230 194 0 29 0
[pid=19243] ppid=19242 vsize=2572 CPUtime=0.04
/proc/19243/stat : 19243 (p2cudf) S 19242 19242 29493 34817 29493 4202496 391 3012 0 1 0 0 2 2 25 0 1 0 74919729 2633728 280 1283457024 134512640 135304128 4286689792 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/19243/statm: 643 280 239 194 0 30 0
[pid=19256] ppid=19243 vsize=880860 CPUtime=29.25
/proc/19256/stat : 19256 (java) S 19243 19242 29493 34817 29493 4202496 97951 0 1 0 2888 37 0 0 25 0 12 0 74919732 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/19256/statm: 220215 91642 1788 10 0 217306 0
[pid=19256/tid=19257] ppid=19243 vsize=880860 CPUtime=24.49
/proc/19256/task/19257/stat : 19257 (java) S 19243 19242 29493 34817 29493 4202560 50272 0 1 0 2426 23 0 0 25 0 12 0 74919733 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19258] ppid=19243 vsize=880860 CPUtime=0.96
/proc/19256/task/19258/stat : 19258 (java) S 19243 19242 29493 34817 29493 4202560 33600 0 0 0 90 6 0 0 16 0 12 0 74919735 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 0 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19259] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19259/stat : 19259 (java) S 19243 19242 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 74919735 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19260] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19260/stat : 19260 (java) S 19243 19242 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 74919735 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19261] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19261/stat : 19261 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 74919738 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19262] ppid=19243 vsize=880860 CPUtime=3.28
/proc/19256/task/19262/stat : 19262 (java) S 19243 19242 29493 34817 29493 4202560 10541 0 0 0 324 4 0 0 15 0 12 0 74919738 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19263] ppid=19243 vsize=880860 CPUtime=0.4
/proc/19256/task/19263/stat : 19263 (java) S 19243 19242 29493 34817 29493 4202560 2452 0 0 0 40 0 0 0 16 0 12 0 74919738 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19264] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19264/stat : 19264 (java) S 19243 19242 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 74919738 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19265] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19265/stat : 19265 (java) S 19243 19242 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 12 0 74919738 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19266] ppid=19243 vsize=880860 CPUtime=0
/proc/19256/task/19266/stat : 19266 (java) S 19243 19242 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 74920323 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 18446612133061569344 0 0 -1 0 0 0 0
[pid=19256/tid=19270] ppid=19243 vsize=880860 CPUtime=0.1
/proc/19256/task/19270/stat : 19270 (java) R 19243 19242 29493 34817 29493 4202560 5 0 0 0 7 3 0 0 20 0 12 0 74922644 902000640 91642 1283457024 134512640 134550932 4288176640 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 29.29
Current children cumulated vsize (KiB) 886000

Child status: 0
Real time (s): 29.9015
CPU time (s): 29.3658
CPU user time (s): 28.9218
CPU system time (s): 0.444027
CPU usage (%): 98.2085
Max. virtual memory (cumulated for all children) (KiB): 890988

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 28.9218
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= 101751
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= 1448
involuntary context switches= 1639

runsolver used 0 second user time and 0 second system time

The end