runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

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

command line: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/rand477.cudf.s-e-l-s.log.runsolver ./p2cudf-trendy-1.13 /home/misc2010/data/2011/incremental/s-e-l-s/rand477.cudf /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/rand477.cudf.s-e-l-s.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: 0.85 0.98 0.99 4/35 15395
/proc/meminfo: memFree=463572/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) R 15393 15394 1511 34817 1511 4202496 356 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2568 CPUtime=0
/proc/15395/stat : 15395 (p2cudf-trendy-1) R 15394 15394 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 40 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/15395/statm: 642 40 0 194 0 29 0

[startup+0.191669 s]
/proc/loadavg: 0.85 0.98 0.99 4/35 15395
/proc/meminfo: memFree=463572/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.211664 s]
/proc/loadavg: 0.85 0.98 0.99 4/35 15395
/proc/meminfo: memFree=463572/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.311691 s]
/proc/loadavg: 0.85 0.98 0.99 4/35 15395
/proc/meminfo: memFree=463572/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.711821 s]
/proc/loadavg: 0.85 0.98 0.99 4/35 15395
/proc/meminfo: memFree=463572/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+1.512 s]
/proc/loadavg: 0.85 0.98 0.99 4/46 15417
/proc/meminfo: memFree=415072/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=894008 CPUtime=1.46
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 24594 0 1 0 133 13 0 0 25 0 10 0 3507029 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 223502 20960 1724 10 0 220593 0
[pid=15408/tid=15409] ppid=15395 vsize=894008 CPUtime=0.78
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 10864 0 1 0 70 8 0 0 25 0 10 0 3507030 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4094959402 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=894008 CPUtime=0
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 3507031 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=894008 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 3507032 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=894008 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 3507032 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=894008 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3507034 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=894008 CPUtime=0.44
/proc/15408/task/15414/stat : 15414 (java) R 15395 15394 1511 34817 1511 4202560 8969 0 0 0 40 4 0 0 25 0 10 0 3507034 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=894008 CPUtime=0.23
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 3682 0 0 0 23 0 0 0 23 0 10 0 3507034 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=894008 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3507034 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=894008 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3507034 915464192 20960 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 899152

[startup+3.11248 s]
/proc/loadavg: 0.86 0.98 0.99 3/46 15417
/proc/meminfo: memFree=310044/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=891872 CPUtime=3.06
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 78470 0 1 0 276 30 0 0 25 0 10 0 3507029 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 222968 72940 1732 10 0 220059 0
[pid=15408/tid=15409] ppid=15395 vsize=891872 CPUtime=1.71
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50037 0 1 0 150 21 0 0 25 0 10 0 3507030 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4095292354 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=891872 CPUtime=0.28
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 12649 0 0 0 26 2 0 0 16 0 10 0 3507031 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=891872 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 3507032 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=891872 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 3507032 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=891872 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3507034 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=891872 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 10 0 3507034 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=891872 CPUtime=0.55
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 5677 0 0 0 53 2 0 0 15 0 10 0 3507034 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=891872 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3507034 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=891872 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) R 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3507034 913276928 72940 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 897016

[startup+6.33076 s]
/proc/loadavg: 0.86 0.98 0.99 2/46 15417
/proc/meminfo: memFree=118836/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=893016 CPUtime=6.26
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 94108 0 1 0 594 32 0 0 25 0 11 0 3507029 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 223254 87695 1771 10 0 220345 0
[pid=15408/tid=15409] ppid=15395 vsize=893016 CPUtime=2.96
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50270 0 1 0 274 22 0 0 25 0 11 0 3507030 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=893016 CPUtime=0.6
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 26759 0 0 0 57 3 0 0 16 0 11 0 3507031 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=893016 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=893016 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=893016 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=893016 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=893016 CPUtime=2.18
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 6972 0 0 0 216 2 0 0 15 0 11 0 3507034 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=893016 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=893016 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 914448384 87695 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.28
Current children cumulated vsize (KiB) 898160

[startup+12.7142 s]
/proc/loadavg: 0.88 0.98 0.99 2/47 15418
/proc/meminfo: memFree=123548/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=12.65
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 94194 0 1 0 1233 32 0 0 25 0 11 0 3507029 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 86362 1784 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=8.87
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50280 0 1 0 865 22 0 0 25 0 11 0 3507030 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4096189988 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=0.6
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 26759 0 0 0 57 3 0 0 16 0 11 0 3507031 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=2.66
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7044 0 0 0 264 2 0 0 15 0 11 0 3507034 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 86362 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.67
Current children cumulated vsize (KiB) 892340

[startup+25.5072 s]
/proc/loadavg: 0.90 0.98 0.99 2/47 15418
/proc/meminfo: memFree=74568/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=25.44
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106450 0 1 0 2507 37 0 0 25 0 11 0 3507029 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 98618 1788 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=20.19
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50282 0 1 0 1997 22 0 0 25 0 11 0 3507030 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4096190545 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=1.69
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39001 0 0 0 162 7 0 0 15 0 11 0 3507031 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=3.03
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 301 2 0 0 15 0 11 0 3507034 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 98618 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.46
Current children cumulated vsize (KiB) 892340

[startup+51.1042 s]
/proc/loadavg: 0.99 0.99 0.99 3/46 15418
/proc/meminfo: memFree=73956/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=51.04
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106586 0 1 0 5066 38 0 0 25 0 11 0 3507029 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 98754 1788 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=42.89
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50282 0 1 0 4267 22 0 0 25 0 11 0 3507030 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4096124582 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=4.5
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39137 0 0 0 442 8 0 0 15 0 11 0 3507031 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=3.12
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 310 2 0 0 16 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.06
Current children cumulated vsize (KiB) 892340

[startup+102.307 s]
/proc/loadavg: 0.99 0.99 0.99 2/46 15418
/proc/meminfo: memFree=73832/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=102.21
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106586 0 1 0 10182 39 0 0 25 0 11 0 3507029 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 98754 1788 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=91.75
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50282 0 1 0 9151 24 0 0 25 0 11 0 3507030 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4095168364 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=6.82
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39137 0 0 0 674 8 0 0 15 0 11 0 3507031 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=3.12
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 310 2 0 0 16 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.23
Current children cumulated vsize (KiB) 892340

[startup+162.311 s]
/proc/loadavg: 1.07 1.02 1.00 2/46 15418
/proc/meminfo: memFree=73832/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=162.15
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106586 0 1 0 16175 40 0 0 25 0 11 0 3507029 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 98754 1788 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=150.49
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50282 0 1 0 15024 25 0 0 25 0 11 0 3507030 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4095245173 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=8.02
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39137 0 0 0 794 8 0 0 15 0 11 0 3507031 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=3.12
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 310 2 0 0 16 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) R 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 162.17
Current children cumulated vsize (KiB) 892340

[startup+222.307 s]
/proc/loadavg: 1.02 1.02 1.00 2/46 15418
/proc/meminfo: memFree=73708/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=222.12
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106586 0 1 0 22170 42 0 0 25 0 11 0 3507029 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 98754 1788 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=210.04
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50282 0 1 0 20978 26 0 0 25 0 11 0 3507030 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4095168420 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=8.42
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39137 0 0 0 834 8 0 0 15 0 11 0 3507031 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=3.12
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 310 2 0 0 16 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 98754 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 222.14
Current children cumulated vsize (KiB) 892340

[startup+282.311 s]
/proc/loadavg: 1.01 1.01 1.00 2/46 15418
/proc/meminfo: memFree=73460/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=282.07
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106635 0 1 0 28165 42 0 0 25 0 11 0 3507029 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 98803 1788 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=269.2
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50282 0 1 0 26893 27 0 0 25 0 11 0 3507030 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4096098784 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=9.23
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39186 0 0 0 915 8 0 0 15 0 11 0 3507031 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=3.12
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 310 2 0 0 16 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.09
Current children cumulated vsize (KiB) 892340

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

[startup+284.614 s]
/proc/loadavg: 1.01 1.01 1.00 2/46 15418
/proc/meminfo: memFree=73460/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887196 CPUtime=284.35
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106635 0 1 0 28393 42 0 0 25 0 11 0 3507029 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221799 98803 1788 10 0 218890 0
[pid=15408/tid=15409] ppid=15395 vsize=887196 CPUtime=271.48
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4202560 50282 0 1 0 27121 27 0 0 25 0 11 0 3507030 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4096325109 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887196 CPUtime=9.23
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39186 0 0 0 915 8 0 0 15 0 11 0 3507031 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887196 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887196 CPUtime=3.12
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 310 2 0 0 16 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15418] ppid=15395 vsize=887196 CPUtime=0
/proc/15408/task/15418/stat : 15418 (java) S 15395 15394 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 3507628 908488704 98803 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.37
Current children cumulated vsize (KiB) 892340

[startup+286.195 s]
/proc/loadavg: 1.01 1.01 1.00 2/46 15418
/proc/meminfo: memFree=73460/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887520 CPUtime=285.84
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106648 0 1 0 28542 42 0 0 25 0 11 0 3507029 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221880 98816 1789 10 0 218971 0
[pid=15408/tid=15409] ppid=15395 vsize=887520 CPUtime=272.91
/proc/15408/task/15409/stat : 15409 (java) S 15395 15394 1511 34817 1511 4202560 50290 0 1 0 27264 27 0 0 25 0 11 0 3507030 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887520 CPUtime=9.23
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39186 0 0 0 915 8 0 0 16 0 11 0 3507031 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887520 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887520 CPUtime=3.18
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 316 2 0 0 15 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 285.86
Current children cumulated vsize (KiB) 892664

[startup+286.503 s]
/proc/loadavg: 1.01 1.01 1.00 2/46 15418
/proc/meminfo: memFree=73460/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887520 CPUtime=285.84
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106648 0 1 0 28542 42 0 0 25 0 11 0 3507029 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221880 98816 1789 10 0 218971 0
[pid=15408/tid=15409] ppid=15395 vsize=887520 CPUtime=272.91
/proc/15408/task/15409/stat : 15409 (java) S 15395 15394 1511 34817 1511 4202560 50290 0 1 0 27264 27 0 0 25 0 11 0 3507030 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887520 CPUtime=9.23
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39186 0 0 0 915 8 0 0 16 0 11 0 3507031 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887520 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887520 CPUtime=3.18
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 316 2 0 0 15 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) R 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908820480 98816 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 285.86
Current children cumulated vsize (KiB) 892664

[startup+286.709 s]
/proc/loadavg: 1.01 1.01 1.00 2/46 15418
/proc/meminfo: memFree=73460/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=887520 CPUtime=285.93
/proc/15408/stat : 15408 (java) S 15395 15394 1511 34817 1511 4202496 106649 0 1 0 28549 44 0 0 25 0 11 0 3507029 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 221880 98817 1789 10 0 218971 0
[pid=15408/tid=15409] ppid=15395 vsize=887520 CPUtime=272.91
/proc/15408/task/15409/stat : 15409 (java) S 15395 15394 1511 34817 1511 4202560 50290 0 1 0 27264 27 0 0 25 0 11 0 3507030 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15410] ppid=15395 vsize=887520 CPUtime=9.23
/proc/15408/task/15410/stat : 15410 (java) S 15395 15394 1511 34817 1511 4202560 39186 0 0 0 915 8 0 0 16 0 11 0 3507031 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15411] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15411/stat : 15411 (java) S 15395 15394 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 3507032 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15412] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15412/stat : 15412 (java) S 15395 15394 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 3507032 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15413] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15413/stat : 15413 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15414] ppid=15395 vsize=887520 CPUtime=0.5
/proc/15408/task/15414/stat : 15414 (java) S 15395 15394 1511 34817 1511 4202560 9030 0 0 0 46 4 0 0 25 0 11 0 3507034 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15415] ppid=15395 vsize=887520 CPUtime=3.18
/proc/15408/task/15415/stat : 15415 (java) S 15395 15394 1511 34817 1511 4202560 7056 0 0 0 316 2 0 0 15 0 11 0 3507034 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15416] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15416/stat : 15416 (java) S 15395 15394 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 3507034 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
[pid=15408/tid=15417] ppid=15395 vsize=887520 CPUtime=0
/proc/15408/task/15417/stat : 15417 (java) S 15395 15394 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 3507034 908820480 98817 1283457024 134512640 134550932 4288469632 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 285.95
Current children cumulated vsize (KiB) 892664

[startup+286.805 s]
/proc/loadavg: 1.01 1.01 1.00 2/39 15421
/proc/meminfo: memFree=107668/1048576 swapFree=0/0
[pid=15394] ppid=15393 vsize=2568 CPUtime=0
/proc/15394/stat : 15394 (p2cudf-trendy-1) S 15393 15394 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 25 0 1 0 3507025 2629632 270 1283457024 134512640 135304128 4287644448 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15394/statm: 642 270 230 194 0 29 0
[pid=15395] ppid=15394 vsize=2576 CPUtime=0.02
/proc/15395/stat : 15395 (p2cudf) S 15394 15394 1511 34817 1511 4202496 392 3015 0 1 0 0 0 2 25 0 1 0 3507025 2637824 281 1283457024 134512640 135304128 4291712768 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/15395/statm: 644 281 239 194 0 31 0
[pid=15408] ppid=15395 vsize=0 CPUtime=285.98
/proc/15408/stat : 15408 (java) Z 15395 15394 1511 34817 1511 4203532 106655 0 1 0 28553 45 0 0 18 0 2 0 3507029 0 0 1283457024 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/15408/statm: 0 0 0 0 0 0 0
[pid=15408/tid=15409] ppid=15395 vsize=0 CPUtime=272.92
/proc/15408/task/15409/stat : 15409 (java) R 15395 15394 1511 34817 1511 4203588 50290 0 1 0 27264 28 0 0 19 0 2 0 3507030 0 0 1283457024 0 0 0 0 0 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 286
Current children cumulated vsize (KiB) 5144

Child status: 0
Real time (s): 286.822
CPU time (s): 286.034
CPU user time (s): 285.546
CPU system time (s): 0.48803
CPU usage (%): 99.7252
Max. virtual memory (cumulated for all children) (KiB): 901404

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 285.546
system time used= 0.48803
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 110456
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= 6201
involuntary context switches= 8894

runsolver used 0 second user time and 0.004 second system time

The end