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/201108311143/p2cudf-user-1.13/8222799a-9af8-11df-8b50-00163e46d37a.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//8222799a-9af8-11df-8b50-00163e46d37a.cudf /home/misc2010/tmp/201108311143/p2cudf-user-1.13/8222799a-9af8-11df-8b50-00163e46d37a.cudf.dudf-real.result -sum(installedsize) 

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.99 0.99 0.99 5/35 31548
/proc/meminfo: memFree=618360/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2572 CPUtime=0
/proc/31547/stat : 31547 (p2cudf) R 31546 31546 19908 34817 19908 4202496 346 0 0 0 0 0 0 0 25 0 1 0 66585133 2633728 272 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/31547/statm: 643 272 231 194 0 30 0
[pid=31548] ppid=31547 vsize=2572 CPUtime=0
/proc/31548/stat : 31548 (p2cudf) R 31547 31546 19908 34817 19908 4202560 0 0 0 0 0 0 0 0 25 0 1 0 66585133 2633728 41 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/31548/statm: 643 41 0 194 0 30 0

[startup+0.161503 s]
/proc/loadavg: 0.99 0.99 0.99 5/35 31548
/proc/meminfo: memFree=618360/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.211514 s]
/proc/loadavg: 0.99 0.99 0.99 5/35 31548
/proc/meminfo: memFree=618360/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.31154 s]
/proc/loadavg: 0.99 0.99 0.99 5/35 31548
/proc/meminfo: memFree=618360/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.711644 s]
/proc/loadavg: 0.99 0.99 0.99 5/35 31548
/proc/meminfo: memFree=618360/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+1.51185 s]
/proc/loadavg: 1.23 1.04 1.01 3/45 31569
/proc/meminfo: memFree=574684/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=881692 CPUtime=1.47
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 21356 0 1 0 140 7 0 0 25 0 10 0 66585136 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220423 17562 1734 10 0 217514 0
[pid=31560/tid=31561] ppid=31547 vsize=881692 CPUtime=0.7
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 9977 0 1 0 66 4 0 0 25 0 10 0 66585137 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4094806755 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 15 0 10 0 66585138 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 25 0 10 0 66585139 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 25 0 10 0 66585139 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66585141 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=881692 CPUtime=0.32
/proc/31560/task/31566/stat : 31566 (java) R 31547 31546 19908 34817 19908 4202560 5452 0 0 0 30 2 0 0 25 0 10 0 66585141 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=881692 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 23 0 10 0 66585141 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66585142 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 10 0 66585142 902852608 17562 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 886840

[startup+3.10226 s]
/proc/loadavg: 1.23 1.04 1.01 2/45 31569
/proc/meminfo: memFree=436548/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=881692 CPUtime=3.06
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 73587 0 1 0 278 28 0 0 25 0 10 0 66585136 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220423 69793 1752 10 0 217514 0
[pid=31560/tid=31561] ppid=31547 vsize=881692 CPUtime=1.59
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50087 0 1 0 141 18 0 0 25 0 10 0 66585137 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=881692 CPUtime=0.27
/proc/31560/task/31562/stat : 31562 (java) R 31547 31546 19908 34817 19908 4202560 12058 0 0 0 21 6 0 0 16 0 10 0 66585138 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4149588135 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 25 0 10 0 66585139 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 25 0 10 0 66585139 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66585141 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=881692 CPUtime=0.73
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 5521 0 0 0 71 2 0 0 18 0 10 0 66585141 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=881692 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 10 0 66585141 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66585142 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=881692 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 10 0 66585142 902852608 69793 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 886840

[startup+6.31303 s]
/proc/loadavg: 1.30 1.05 1.02 3/46 31570
/proc/meminfo: memFree=341308/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=6.26
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 83115 0 1 0 596 30 0 0 25 0 11 0 66585136 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 78271 1781 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=3.46
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50271 0 1 0 328 18 0 0 25 0 11 0 66585137 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4095540254 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=0.56
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 19939 0 0 0 49 7 0 0 16 0 11 0 66585138 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=1.78
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 6977 0 0 0 175 3 0 0 15 0 11 0 66585141 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 78271 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.29
Current children cumulated vsize (KiB) 887976

[startup+12.7062 s]
/proc/loadavg: 1.27 1.05 1.02 2/46 31570
/proc/meminfo: memFree=308208/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=12.63
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 83858 0 1 0 1232 31 0 0 25 0 11 0 66585136 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 79014 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=8.68
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 849 19 0 0 25 0 11 0 66585137 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4095540196 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=1.47
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 20653 0 0 0 140 7 0 0 15 0 11 0 66585138 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.02
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7005 0 0 0 199 3 0 0 15 0 11 0 66585141 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 79014 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.66
Current children cumulated vsize (KiB) 887976

[startup+25.5129 s]
/proc/loadavg: 1.29 1.07 1.02 2/46 31570
/proc/meminfo: memFree=300148/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=25.41
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 85874 0 1 0 2509 32 0 0 25 0 11 0 66585136 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 81030 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=20.06
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 1987 19 0 0 25 0 11 0 66585137 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4095550208 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=2.81
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 22668 0 0 0 273 8 0 0 16 0 11 0 66585138 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.08
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 205 3 0 0 16 0 11 0 66585141 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 81030 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.44
Current children cumulated vsize (KiB) 887976

[startup+51.1022 s]
/proc/loadavg: 1.19 1.06 1.02 2/46 31570
/proc/meminfo: memFree=285268/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=50.95
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 89572 0 1 0 5061 34 0 0 25 0 11 0 66585136 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 84728 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=43.95
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 4375 20 0 0 25 0 11 0 66585137 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4095540207 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=4.42
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 26366 0 0 0 432 10 0 0 16 0 11 0 66585138 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.12
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 209 3 0 0 15 0 11 0 66585141 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 84728 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 50.98
Current children cumulated vsize (KiB) 887976

[startup+102.306 s]
/proc/loadavg: 1.08 1.05 1.01 2/46 31570
/proc/meminfo: memFree=277084/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=102.05
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91556 0 1 0 10171 34 0 0 25 0 11 0 66585136 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 86712 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=92.47
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 9227 20 0 0 25 0 11 0 66585137 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4095540295 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=7.01
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 691 10 0 0 15 0 11 0 66585138 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.12
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 209 3 0 0 15 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.08
Current children cumulated vsize (KiB) 887976

[startup+162.308 s]
/proc/loadavg: 1.08 1.05 1.01 2/46 31570
/proc/meminfo: memFree=276960/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=161.93
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91556 0 1 0 16157 36 0 0 25 0 11 0 66585136 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 86712 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=148.98
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 14878 20 0 0 25 0 11 0 66585137 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4095540192 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=10.36
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 1025 11 0 0 15 0 11 0 66585138 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.12
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 209 3 0 0 15 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.96
Current children cumulated vsize (KiB) 887976

[startup+222.306 s]
/proc/loadavg: 1.03 1.04 1.01 2/48 31574
/proc/meminfo: memFree=276688/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=221.84
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91556 0 1 0 22148 36 0 0 25 0 11 0 66585136 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 86712 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=205.76
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 20556 20 0 0 25 0 11 0 66585137 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4095494608 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=13.49
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 1338 11 0 0 15 0 11 0 66585138 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.12
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 209 3 0 0 15 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 221.87
Current children cumulated vsize (KiB) 887976

[startup+282.309 s]
/proc/loadavg: 1.01 1.03 1.00 2/48 31580
/proc/meminfo: memFree=276688/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=281.73
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91556 0 1 0 28135 38 0 0 25 0 11 0 66585136 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 86712 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=262.26
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 26204 22 0 0 25 0 11 0 66585137 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4095545570 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=16.87
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 1676 11 0 0 15 0 11 0 66585138 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.14
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 211 3 0 0 15 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 281.76
Current children cumulated vsize (KiB) 887976

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

[startup+283.009 s]
/proc/loadavg: 1.01 1.03 1.00 2/48 31580
/proc/meminfo: memFree=276688/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=282.43
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91556 0 1 0 28205 38 0 0 25 0 11 0 66585136 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 86712 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=262.96
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 26274 22 0 0 25 0 11 0 66585137 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4095550913 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=16.87
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 1676 11 0 0 15 0 11 0 66585138 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.14
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 211 3 0 0 15 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.46
Current children cumulated vsize (KiB) 887976

[startup+283.809 s]
/proc/loadavg: 1.01 1.03 1.00 2/48 31580
/proc/meminfo: memFree=276688/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=882828 CPUtime=283.23
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91556 0 1 0 28285 38 0 0 25 0 11 0 66585136 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220707 86712 1783 10 0 217798 0
[pid=31560/tid=31561] ppid=31547 vsize=882828 CPUtime=263.76
/proc/31560/task/31561/stat : 31561 (java) R 31547 31546 19908 34817 19908 4202560 50272 0 1 0 26354 22 0 0 25 0 11 0 66585137 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4094946067 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=882828 CPUtime=16.87
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 1676 11 0 0 15 0 11 0 66585138 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=882828 CPUtime=2.14
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 211 3 0 0 15 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=882828 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31570] ppid=31547 vsize=882828 CPUtime=0
/proc/31560/task/31570/stat : 31570 (java) S 31547 31546 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66585536 904015872 86712 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.26
Current children cumulated vsize (KiB) 887976

[startup+284.207 s]
/proc/loadavg: 1.01 1.03 1.00 2/48 31580
/proc/meminfo: memFree=276688/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=883152 CPUtime=283.49
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91569 0 1 0 28311 38 0 0 25 0 11 0 66585136 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220788 86725 1784 10 0 217879 0
[pid=31560/tid=31561] ppid=31547 vsize=883152 CPUtime=264.01
/proc/31560/task/31561/stat : 31561 (java) S 31547 31546 19908 34817 19908 4202560 50279 0 1 0 26379 22 0 0 25 0 11 0 66585137 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=883152 CPUtime=16.87
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 1676 11 0 0 16 0 11 0 66585138 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=883152 CPUtime=2.15
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 212 3 0 0 15 0 11 0 66585141 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=883152 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904347648 86725 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.52
Current children cumulated vsize (KiB) 888300

[startup+284.613 s]
/proc/loadavg: 1.01 1.03 1.00 2/48 31580
/proc/meminfo: memFree=276688/1048576 swapFree=0/0
[pid=31546] ppid=31545 vsize=2568 CPUtime=0.01
/proc/31546/stat : 31546 (p2cudf-user-1.1) S 31545 31546 19908 34817 19908 4202496 374 0 0 0 1 0 0 0 18 0 1 0 66585133 2629632 270 1283457024 134512640 135304128 4287920928 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31546/statm: 642 270 230 194 0 29 0
[pid=31547] ppid=31546 vsize=2580 CPUtime=0.02
/proc/31547/stat : 31547 (p2cudf) S 31546 31546 19908 34817 19908 4202496 395 3012 0 1 0 0 2 0 25 0 1 0 66585133 2641920 282 1283457024 134512640 135304128 4290549184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31547/statm: 645 282 239 194 0 32 0
[pid=31560] ppid=31547 vsize=883152 CPUtime=283.51
/proc/31560/stat : 31560 (java) S 31547 31546 19908 34817 19908 4202496 91570 0 1 0 28312 39 0 0 25 0 11 0 66585136 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31560/statm: 220788 86726 1784 10 0 217879 0
[pid=31560/tid=31561] ppid=31547 vsize=883152 CPUtime=264.01
/proc/31560/task/31561/stat : 31561 (java) S 31547 31546 19908 34817 19908 4202560 50279 0 1 0 26379 22 0 0 25 0 11 0 66585137 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31562] ppid=31547 vsize=883152 CPUtime=16.87
/proc/31560/task/31562/stat : 31562 (java) S 31547 31546 19908 34817 19908 4202560 28350 0 0 0 1676 11 0 0 16 0 11 0 66585138 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31563] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31563/stat : 31563 (java) S 31547 31546 19908 34817 19908 4202560 9 0 0 0 0 0 0 0 18 0 11 0 66585139 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31564] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31564/stat : 31564 (java) S 31547 31546 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66585139 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31565] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31565/stat : 31565 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585141 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31566] ppid=31547 vsize=883152 CPUtime=2.15
/proc/31560/task/31566/stat : 31566 (java) S 31547 31546 19908 34817 19908 4202560 7006 0 0 0 212 3 0 0 15 0 11 0 66585141 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31567] ppid=31547 vsize=883152 CPUtime=0.43
/proc/31560/task/31567/stat : 31567 (java) S 31547 31546 19908 34817 19908 4202560 4847 0 0 0 43 0 0 0 22 0 11 0 66585141 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31568] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31568/stat : 31568 (java) S 31547 31546 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66585142 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=31560/tid=31569] ppid=31547 vsize=883152 CPUtime=0
/proc/31560/task/31569/stat : 31569 (java) S 31547 31546 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66585142 904347648 86726 1283457024 134512640 134550932 4292134368 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.54
Current children cumulated vsize (KiB) 888300

Child status: 0
Real time (s): 284.683
CPU time (s): 283.618
CPU user time (s): 283.174
CPU system time (s): 0.444027
CPU usage (%): 99.6259
Max. virtual memory (cumulated for all children) (KiB): 888956

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 283.174
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= 95376
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= 6005
involuntary context switches= 8703

runsolver used 0 second user time and 0 second system time

The end