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/201108300820/p2cudf-user-1.13/ab9005be-bacc-11e0-b0f6-00163e1e087d.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//ab9005be-bacc-11e0-b0f6-00163e1e087d.cudf /home/misc2010/tmp/201108300820/p2cudf-user-1.13/ab9005be-bacc-11e0-b0f6-00163e1e087d.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.85 0.97 0.99 1/33 14625
/proc/meminfo: memFree=936548/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=3024 CPUtime=0
/proc/14625/stat : 14625 (runsolver) D 14624 14625 2160 34817 2160 4202560 73 0 0 0 0 0 0 0 25 0 1 0 57492002 3096576 94 1283457024 134512640 134586868 4291249520 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/14625/statm: 756 94 62 19 0 55 0

[startup+0.186333 s]
/proc/loadavg: 0.85 0.97 0.99 1/33 14625
/proc/meminfo: memFree=936548/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.206369 s]
/proc/loadavg: 0.85 0.97 0.99 1/33 14625
/proc/meminfo: memFree=936548/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.306361 s]
/proc/loadavg: 0.85 0.97 0.99 1/33 14625
/proc/meminfo: memFree=936548/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.706372 s]
/proc/loadavg: 0.85 0.97 0.99 1/33 14625
/proc/meminfo: memFree=936548/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+1.50627 s]
/proc/loadavg: 0.94 0.99 0.99 5/45 14648
/proc/meminfo: memFree=894976/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=890364 CPUtime=0.82
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 17901 0 110 0 79 3 0 0 22 0 10 0 57492006 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222591 15607 1726 10 0 219682 0
[pid=14639/tid=14640] ppid=14627 vsize=890364 CPUtime=0.43
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 6956 0 15 0 43 0 0 0 25 0 10 0 57492040 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4149585120 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=890364 CPUtime=0
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 7 0 0 0 0 0 0 0 17 0 10 0 57492049 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=890364 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 25 0 10 0 57492050 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=890364 CPUtime=0
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 57492051 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=890364 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 57492060 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=890364 CPUtime=0.1
/proc/14639/task/14645/stat : 14645 (java) R 14627 14625 2160 34817 2160 4202560 3542 0 1 0 10 0 0 0 18 0 10 0 57492060 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4149726102 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=890364 CPUtime=0.29
/proc/14639/task/14646/stat : 14646 (java) R 14627 14625 2160 34817 2160 4202560 6410 0 8 0 26 3 0 0 25 0 10 0 57492060 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=890364 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 10 0 57492060 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=890364 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 16 0 10 0 57492060 911732736 15607 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.84
Current children cumulated vsize (KiB) 895500

[startup+3.12661 s]
/proc/loadavg: 0.94 0.99 0.99 3/45 14648
/proc/meminfo: memFree=831364/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889436 CPUtime=2.44
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 66483 0 110 0 220 24 0 0 22 0 10 0 57492006 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222359 62999 1746 10 0 219450 0
[pid=14639/tid=14640] ppid=14627 vsize=889436 CPUtime=1.39
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50040 0 15 0 119 20 0 0 25 0 10 0 57492040 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889436 CPUtime=0.07
/proc/14639/task/14641/stat : 14641 (java) R 14627 14625 2160 34817 2160 4202560 3330 0 0 0 6 1 0 0 15 0 10 0 57492049 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4149618312 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889436 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 25 0 10 0 57492050 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889436 CPUtime=0
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 57492051 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889436 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 57492060 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889436 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 10 0 57492060 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889436 CPUtime=0.51
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 7223 0 8 0 48 3 0 0 24 0 10 0 57492060 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889436 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 10 0 57492060 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889436 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) R 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 10 0 57492060 910782464 62999 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.46
Current children cumulated vsize (KiB) 894572
heavy processes:

[startup+6.30438 s]
/proc/loadavg: 0.94 0.99 0.99 4/46 14649
/proc/meminfo: memFree=641908/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=5.46
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 77136 0 111 0 516 30 0 0 22 0 11 0 57492006 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 72670 1781 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=2.64
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50275 0 15 0 242 22 0 0 25 0 11 0 57492040 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=0.27
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 12635 0 0 0 23 4 0 0 16 0 11 0 57492049 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.07
/proc/14639/task/14646/stat : 14646 (java) R 14627 14625 2160 34817 2160 4202560 8329 0 9 0 203 4 0 0 16 0 11 0 57492060 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4147667344 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 72670 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.48
Current children cumulated vsize (KiB) 894220

[startup+12.7157 s]
/proc/loadavg: 1.10 1.02 1.01 2/46 14649
/proc/meminfo: memFree=563116/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=11.86
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 96750 0 111 0 1151 35 0 0 22 0 11 0 57492006 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 92284 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=8.26
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 804 22 0 0 25 0 11 0 57492040 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4096519340 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=0.89
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 32224 0 0 0 80 9 0 0 15 0 11 0 57492049 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.23
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8352 0 9 0 219 4 0 0 15 0 11 0 57492060 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 92284 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 11.88
Current children cumulated vsize (KiB) 894220

[startup+25.5088 s]
/proc/loadavg: 1.17 1.04 1.01 2/46 14649
/proc/meminfo: memFree=550964/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=24.65
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 99765 0 111 0 2427 38 0 0 22 0 11 0 57492006 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 95299 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=20.42
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 2020 22 0 0 25 0 11 0 57492040 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4096659952 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=1.45
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 35234 0 0 0 134 11 0 0 15 0 11 0 57492049 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.29
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8357 0 9 0 225 4 0 0 16 0 11 0 57492060 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 95299 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 24.67
Current children cumulated vsize (KiB) 894220

[startup+51.1048 s]
/proc/loadavg: 1.11 1.03 1.01 2/46 14649
/proc/meminfo: memFree=545260/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=50.22
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 101148 0 111 0 4984 38 0 0 22 0 11 0 57492006 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 96682 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=44.8
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 4458 22 0 0 25 0 11 0 57492040 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4096659984 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=2.59
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 36608 0 0 0 248 11 0 0 15 0 11 0 57492049 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.34
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 230 4 0 0 15 0 11 0 57492060 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 96682 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 50.24
Current children cumulated vsize (KiB) 894220

[startup+102.306 s]
/proc/loadavg: 1.26 1.08 1.02 2/48 14654
/proc/meminfo: memFree=523288/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=101.39
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 106566 0 111 0 10099 40 0 0 22 0 11 0 57492006 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 102100 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=94.82
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 9460 22 0 0 25 0 11 0 57492040 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4096474944 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=3.73
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 42026 0 0 0 360 13 0 0 15 0 11 0 57492049 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.34
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 230 4 0 0 15 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 101.41
Current children cumulated vsize (KiB) 894220

[startup+162.307 s]
/proc/loadavg: 1.09 1.06 1.01 2/48 14660
/proc/meminfo: memFree=523164/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=161.31
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 106566 0 111 0 16091 40 0 0 22 0 11 0 57492006 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 102100 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=153.56
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 15334 22 0 0 25 0 11 0 57492040 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4096632136 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=4.91
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 42026 0 0 0 478 13 0 0 15 0 11 0 57492049 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.34
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 230 4 0 0 15 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 102100 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.33
Current children cumulated vsize (KiB) 894220

[startup+222.313 s]
/proc/loadavg: 1.03 1.05 1.01 2/47 14660
/proc/meminfo: memFree=494660/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=221.25
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 113714 0 111 0 22083 42 0 0 22 0 11 0 57492006 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 109248 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=212.28
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 21206 22 0 0 25 0 11 0 57492040 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4096604780 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=6.14
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 49174 0 0 0 599 15 0 0 15 0 11 0 57492049 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.34
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 230 4 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) R 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 221.27
Current children cumulated vsize (KiB) 894220

[startup+282.305 s]
/proc/loadavg: 1.01 1.03 1.00 2/46 14660
/proc/meminfo: memFree=494668/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=281.18
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 113714 0 111 0 28076 42 0 0 22 0 11 0 57492006 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 109248 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=271.3
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 27107 23 0 0 25 0 11 0 57492040 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4096699464 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=7.07
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 49174 0 0 0 692 15 0 0 15 0 11 0 57492049 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.34
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 230 4 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 281.2
Current children cumulated vsize (KiB) 894220

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

[startup+284.806 s]
/proc/loadavg: 1.01 1.03 1.00 2/46 14660
/proc/meminfo: memFree=494668/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889084 CPUtime=283.68
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 113714 0 111 0 28326 42 0 0 22 0 11 0 57492006 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222271 109248 1787 10 0 219362 0
[pid=14639/tid=14640] ppid=14627 vsize=889084 CPUtime=273.8
/proc/14639/task/14640/stat : 14640 (java) R 14627 14625 2160 34817 2160 4202560 50277 0 15 0 27357 23 0 0 25 0 11 0 57492040 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4096588808 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889084 CPUtime=7.07
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 49174 0 0 0 692 15 0 0 15 0 11 0 57492049 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889084 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889084 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889084 CPUtime=2.34
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 230 4 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14649] ppid=14627 vsize=889084 CPUtime=0
/proc/14639/task/14649/stat : 14649 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57492545 910422016 109248 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.7
Current children cumulated vsize (KiB) 894220

[startup+285.604 s]
/proc/loadavg: 1.01 1.03 1.00 2/46 14660
/proc/meminfo: memFree=494668/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889408 CPUtime=284.36
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 113727 0 111 0 28394 42 0 0 22 0 11 0 57492006 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222352 109261 1788 10 0 219443 0
[pid=14639/tid=14640] ppid=14627 vsize=889408 CPUtime=274.46
/proc/14639/task/14640/stat : 14640 (java) S 14627 14625 2160 34817 2160 4202560 50285 0 15 0 27423 23 0 0 25 0 11 0 57492040 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889408 CPUtime=7.07
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 49174 0 0 0 692 15 0 0 16 0 11 0 57492049 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889408 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889408 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889408 CPUtime=2.36
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 232 4 0 0 15 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.38
Current children cumulated vsize (KiB) 894544

[startup+286.004 s]
/proc/loadavg: 0.93 1.02 0.99 1/46 14661
/proc/meminfo: memFree=494544/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889408 CPUtime=284.36
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 113727 0 111 0 28394 42 0 0 22 0 11 0 57492006 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222352 109261 1788 10 0 219443 0
[pid=14639/tid=14640] ppid=14627 vsize=889408 CPUtime=274.46
/proc/14639/task/14640/stat : 14640 (java) S 14627 14625 2160 34817 2160 4202560 50285 0 15 0 27423 23 0 0 25 0 11 0 57492040 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889408 CPUtime=7.07
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 49174 0 0 0 692 15 0 0 16 0 11 0 57492049 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889408 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889408 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889408 CPUtime=2.36
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 232 4 0 0 15 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14661] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14661/stat : 14661 (java) S 14627 14625 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 21 0 11 0 57520553 910753792 109261 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.38
Current children cumulated vsize (KiB) 894544

[startup+286.206 s]
/proc/loadavg: 0.93 1.02 0.99 1/46 14661
/proc/meminfo: memFree=494544/1048576 swapFree=0/0
[pid=14625] ppid=14624 vsize=2568 CPUtime=0
/proc/14625/stat : 14625 (p2cudf-user-1.1) S 14624 14625 2160 34817 2160 4202496 376 0 0 0 0 0 0 0 25 0 1 0 57492002 2629632 271 1283457024 134512640 135304128 4291532432 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14625/statm: 642 271 230 194 0 29 0
[pid=14627] ppid=14625 vsize=2568 CPUtime=0.02
/proc/14627/stat : 14627 (p2cudf) S 14625 14625 2160 34817 2160 4202496 389 3013 0 1 0 0 0 2 25 0 1 0 57492003 2629632 279 1283457024 134512640 135304128 4294226240 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/14627/statm: 642 279 239 194 0 29 0
[pid=14639] ppid=14627 vsize=889408 CPUtime=284.57
/proc/14639/stat : 14639 (java) S 14627 14625 2160 34817 2160 4202496 113729 0 111 0 28408 49 0 0 22 0 11 0 57492006 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/14639/statm: 222352 109263 1788 10 0 219443 0
[pid=14639/tid=14640] ppid=14627 vsize=889408 CPUtime=274.46
/proc/14639/task/14640/stat : 14640 (java) S 14627 14625 2160 34817 2160 4202560 50285 0 15 0 27423 23 0 0 25 0 11 0 57492040 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14641] ppid=14627 vsize=889408 CPUtime=7.07
/proc/14639/task/14641/stat : 14641 (java) S 14627 14625 2160 34817 2160 4202560 49174 0 0 0 692 15 0 0 16 0 11 0 57492049 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 0 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14642] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14642/stat : 14642 (java) S 14627 14625 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57492050 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14643] ppid=14627 vsize=889408 CPUtime=0.01
/proc/14639/task/14643/stat : 14643 (java) S 14627 14625 2160 34817 2160 4202560 6 0 0 0 1 0 0 0 18 0 11 0 57492051 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14644] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14644/stat : 14644 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57492060 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14645] ppid=14627 vsize=889408 CPUtime=0.46
/proc/14639/task/14645/stat : 14645 (java) S 14627 14625 2160 34817 2160 4202560 4904 0 1 0 46 0 0 0 15 0 11 0 57492060 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14646] ppid=14627 vsize=889408 CPUtime=2.42
/proc/14639/task/14646/stat : 14646 (java) S 14627 14625 2160 34817 2160 4202560 8366 0 9 0 238 4 0 0 15 0 11 0 57492060 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14647] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14647/stat : 14647 (java) S 14627 14625 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 57492060 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14648] ppid=14627 vsize=889408 CPUtime=0
/proc/14639/task/14648/stat : 14648 (java) S 14627 14625 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 57492060 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 18446612132371206464 0 0 -1 0 0 0 0
[pid=14639/tid=14661] ppid=14627 vsize=889408 CPUtime=0.13
/proc/14639/task/14661/stat : 14661 (java) R 14627 14625 2160 34817 2160 4202560 6 0 0 0 7 6 0 0 21 0 11 0 57520553 910753792 109263 1283457024 134512640 134550932 4287861200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.59
Current children cumulated vsize (KiB) 894544

Child status: 0
Real time (s): 286.286
CPU time (s): 284.654
CPU user time (s): 284.114
CPU system time (s): 0.540033
CPU usage (%): 99.4299
Max. virtual memory (cumulated for all children) (KiB): 895948

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 284.114
system time used= 0.540033
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 117531
page faults= 112
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 6521
involuntary context switches= 8822

runsolver used 0.012 second user time and 0 second system time

The end