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/80e3fda2-9501-11e0-8001-00163e1e087d.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//80e3fda2-9501-11e0-8001-00163e1e087d.cudf /home/misc2010/tmp/201108311143/p2cudf-user-1.13/80e3fda2-9501-11e0-8001-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: 1.00 1.01 1.00 3/35 31380
/proc/meminfo: memFree=638628/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) R 31378 31379 19908 34817 19908 4202496 356 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2568 CPUtime=0
/proc/31380/stat : 31380 (p2cudf-user-1.1) R 31379 31379 19908 34817 19908 4202560 0 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 39 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/31380/statm: 642 39 0 194 0 29 0

[startup+0.175724 s]
/proc/loadavg: 1.00 1.01 1.00 3/35 31380
/proc/meminfo: memFree=638628/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+0.205724 s]
/proc/loadavg: 1.00 1.01 1.00 3/35 31380
/proc/meminfo: memFree=638628/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+0.305744 s]
/proc/loadavg: 1.00 1.01 1.00 3/35 31380
/proc/meminfo: memFree=638628/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+0.705855 s]
/proc/loadavg: 1.00 1.01 1.00 3/35 31380
/proc/meminfo: memFree=638628/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+1.50606 s]
/proc/loadavg: 1.00 1.01 1.00 3/46 31402
/proc/meminfo: memFree=585576/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=885684 CPUtime=1.46
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 22222 0 1 0 141 5 0 0 25 0 10 0 66497494 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 221421 18834 1720 10 0 218512 0
[pid=31393/tid=31394] ppid=31380 vsize=885684 CPUtime=0.79
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 10574 0 1 0 76 3 0 0 25 0 10 0 66497494 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4095060281 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=885684 CPUtime=0
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 16 0 10 0 66497496 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=885684 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 25 0 10 0 66497496 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=885684 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 25 0 10 0 66497496 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=885684 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66497499 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=885684 CPUtime=0.48
/proc/31393/task/31399/stat : 31399 (java) R 31380 31379 19908 34817 19908 4202560 8651 0 0 0 46 2 0 0 25 0 10 0 66497499 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=885684 CPUtime=0.18
/proc/31393/task/31400/stat : 31400 (java) R 31380 31379 19908 34817 19908 4202560 1918 0 0 0 18 0 0 0 18 0 10 0 66497499 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4147586688 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=885684 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66497499 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=885684 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 10 0 66497499 906940416 18834 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 890824

[startup+3.10642 s]
/proc/loadavg: 1.16 1.04 1.01 4/46 31402
/proc/meminfo: memFree=507208/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=882100 CPUtime=3.05
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 76052 0 1 0 285 20 0 0 25 0 10 0 66497494 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 220525 71321 1752 10 0 217616 0
[pid=31393/tid=31394] ppid=31380 vsize=882100 CPUtime=1.65
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50086 0 1 0 152 13 0 0 25 0 10 0 66497494 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4095367683 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=882100 CPUtime=0.31
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 13352 0 0 0 26 5 0 0 16 0 10 0 66497496 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=882100 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 10 0 66497496 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=882100 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 10 0 66497496 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=882100 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66497499 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=882100 CPUtime=0.6
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 8710 0 0 0 58 2 0 0 21 0 10 0 66497499 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=882100 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 10 0 66497499 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=882100 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66497499 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=882100 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) R 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 10 0 66497499 903270400 71321 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 887240

[startup+6.30704 s]
/proc/loadavg: 1.16 1.04 1.01 4/47 31403
/proc/meminfo: memFree=357036/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=882316 CPUtime=6.25
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 85559 0 1 0 601 24 0 0 25 0 11 0 66497494 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 220579 79216 1781 10 0 217670 0
[pid=31393/tid=31394] ppid=31380 vsize=882316 CPUtime=3.39
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50273 0 1 0 325 14 0 0 25 0 11 0 66497494 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4095642848 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=882316 CPUtime=0.62
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 20883 0 0 0 54 8 0 0 16 0 11 0 66497496 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=882316 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=882316 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=882316 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=882316 CPUtime=1.75
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10495 0 0 0 173 2 0 0 16 0 11 0 66497499 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=882316 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=882316 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=882316 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=882316 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 903491584 79216 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.28
Current children cumulated vsize (KiB) 887456

[startup+12.7103 s]
/proc/loadavg: 1.14 1.04 1.01 2/47 31403
/proc/meminfo: memFree=329516/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=12.64
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 86042 0 1 0 1240 24 0 0 25 0 11 0 66497494 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 78719 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=8.7
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 856 14 0 0 25 0 11 0 66497494 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4095700513 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=1.58
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 21350 0 0 0 150 8 0 0 16 0 11 0 66497496 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.88
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10510 0 0 0 186 2 0 0 16 0 11 0 66497499 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 78719 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.67
Current children cumulated vsize (KiB) 883520

[startup+25.5062 s]
/proc/loadavg: 1.12 1.04 1.01 2/47 31403
/proc/meminfo: memFree=326788/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=25.4
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 86707 0 1 0 2516 24 0 0 25 0 11 0 66497494 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 79384 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=20.05
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 1991 14 0 0 25 0 11 0 66497494 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4095642755 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=2.99
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 22015 0 0 0 291 8 0 0 16 0 11 0 66497496 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.88
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10510 0 0 0 186 2 0 0 16 0 11 0 66497499 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 79384 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.43
Current children cumulated vsize (KiB) 883520

[startup+51.1122 s]
/proc/loadavg: 1.08 1.03 1.01 2/46 31403
/proc/meminfo: memFree=322952/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=50.97
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 87653 0 1 0 5073 24 0 0 25 0 11 0 66497494 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 80330 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=43.79
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 4365 14 0 0 25 0 11 0 66497494 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=4.82
/proc/31393/task/31395/stat : 31395 (java) R 31380 31379 19908 34817 19908 4202560 22961 0 0 0 474 8 0 0 16 0 11 0 66497496 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4152371797 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.88
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10510 0 0 0 186 2 0 0 16 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51
Current children cumulated vsize (KiB) 883520

[startup+102.305 s]
/proc/loadavg: 1.11 1.04 1.01 2/46 31403
/proc/meminfo: memFree=322828/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=102.03
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 87653 0 1 0 10177 26 0 0 25 0 11 0 66497494 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 80330 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=91.11
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 9096 15 0 0 25 0 11 0 66497494 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4095218479 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=8.55
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 22961 0 0 0 847 8 0 0 16 0 11 0 66497496 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.88
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10510 0 0 0 186 2 0 0 15 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 80330 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.06
Current children cumulated vsize (KiB) 883520

[startup+162.305 s]
/proc/loadavg: 1.08 1.05 1.01 2/46 31403
/proc/meminfo: memFree=321836/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=161.91
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 87864 0 1 0 16165 26 0 0 25 0 11 0 66497494 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 80541 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=146.58
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 14643 15 0 0 25 0 11 0 66497494 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4095609484 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=12.96
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 23172 0 0 0 1288 8 0 0 15 0 11 0 66497496 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.88
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10510 0 0 0 186 2 0 0 15 0 11 0 66497499 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 80541 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.94
Current children cumulated vsize (KiB) 883520

[startup+222.311 s]
/proc/loadavg: 1.07 1.05 1.01 2/46 31403
/proc/meminfo: memFree=321588/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=221.82
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 87892 0 1 0 22155 27 0 0 25 0 11 0 66497494 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 80569 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=202.36
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 20221 15 0 0 25 0 11 0 66497494 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4095605220 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=17.06
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 23199 0 0 0 1697 9 0 0 15 0 11 0 66497496 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.9
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10511 0 0 0 188 2 0 0 15 0 11 0 66497499 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 80569 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 221.85
Current children cumulated vsize (KiB) 883520

[startup+282.308 s]
/proc/loadavg: 1.02 1.04 1.00 2/48 31406
/proc/meminfo: memFree=320324/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=281.74
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 88160 0 1 0 28146 28 0 0 25 0 11 0 66497494 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 80837 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=258.15
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 25800 15 0 0 25 0 11 0 66497494 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4095614969 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=21.2
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 23467 0 0 0 2110 10 0 0 15 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.9
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10511 0 0 0 188 2 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 281.77
Current children cumulated vsize (KiB) 883520

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

[startup+283.108 s]
/proc/loadavg: 1.02 1.04 1.00 2/48 31406
/proc/meminfo: memFree=320324/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=282.54
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 88160 0 1 0 28226 28 0 0 25 0 11 0 66497494 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 80837 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=258.95
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 25880 15 0 0 25 0 11 0 66497494 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4095642846 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=21.2
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 23467 0 0 0 2110 10 0 0 15 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.9
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10511 0 0 0 188 2 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.57
Current children cumulated vsize (KiB) 883520

[startup+283.918 s]
/proc/loadavg: 1.02 1.04 1.00 2/48 31406
/proc/meminfo: memFree=320324/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878380 CPUtime=283.35
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 88160 0 1 0 28307 28 0 0 25 0 11 0 66497494 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219595 80837 1783 10 0 216686 0
[pid=31393/tid=31394] ppid=31380 vsize=878380 CPUtime=259.65
/proc/31393/task/31394/stat : 31394 (java) R 31380 31379 19908 34817 19908 4202560 50274 0 1 0 25950 15 0 0 25 0 11 0 66497494 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878380 CPUtime=21.31
/proc/31393/task/31395/stat : 31395 (java) R 31380 31379 19908 34817 19908 4202560 23467 0 0 0 2121 10 0 0 15 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4147381932 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878380 CPUtime=1.9
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10511 0 0 0 188 2 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878380 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31403] ppid=31380 vsize=878380 CPUtime=0
/proc/31393/task/31403/stat : 31403 (java) S 31380 31379 19908 34817 19908 4202560 4 0 0 0 0 0 0 0 24 0 11 0 66497914 899461120 80837 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.38
Current children cumulated vsize (KiB) 883520

[startup+284.301 s]
/proc/loadavg: 1.02 1.04 1.00 2/48 31406
/proc/meminfo: memFree=320324/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878704 CPUtime=283.69
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 88173 0 1 0 28341 28 0 0 25 0 11 0 66497494 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219676 80850 1784 10 0 216767 0
[pid=31393/tid=31394] ppid=31380 vsize=878704 CPUtime=259.85
/proc/31393/task/31394/stat : 31394 (java) S 31380 31379 19908 34817 19908 4202560 50281 0 1 0 25970 15 0 0 25 0 11 0 66497494 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878704 CPUtime=21.43
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 23467 0 0 0 2133 10 0 0 16 0 11 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878704 CPUtime=1.92
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10511 0 0 0 190 2 0 0 15 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878704 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.72
Current children cumulated vsize (KiB) 883844

[startup+284.705 s]
/proc/loadavg: 1.02 1.04 1.00 2/48 31406
/proc/meminfo: memFree=320324/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878704 CPUtime=283.69
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 88173 0 1 0 28341 28 0 0 25 0 11 0 66497494 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219676 80850 1784 10 0 216767 0
[pid=31393/tid=31394] ppid=31380 vsize=878704 CPUtime=259.85
/proc/31393/task/31394/stat : 31394 (java) S 31380 31379 19908 34817 19908 4202560 50281 0 1 0 25970 15 0 0 25 0 11 0 66497494 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878704 CPUtime=21.43
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 23467 0 0 0 2133 10 0 0 16 0 11 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 11 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 11 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878704 CPUtime=1.92
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10511 0 0 0 190 2 0 0 15 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878704 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 11 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.72
Current children cumulated vsize (KiB) 883844

[startup+284.806 s]
/proc/loadavg: 1.02 1.04 1.00 1/47 31407
/proc/meminfo: memFree=320208/1048576 swapFree=0/0
[pid=31379] ppid=31378 vsize=2568 CPUtime=0
/proc/31379/stat : 31379 (p2cudf-user-1.1) S 31378 31379 19908 34817 19908 4202496 374 0 0 0 0 0 0 0 25 0 1 0 66497490 2629632 269 1283457024 134512640 135304128 4290854368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31379/statm: 642 269 230 194 0 29 0
[pid=31380] ppid=31379 vsize=2572 CPUtime=0.03
/proc/31380/stat : 31380 (p2cudf) S 31379 31379 19908 34817 19908 4202496 391 3012 0 1 0 1 2 0 25 0 1 0 66497490 2633728 280 1283457024 134512640 135304128 4290809344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31380/statm: 643 280 239 194 0 30 0
[pid=31393] ppid=31380 vsize=878704 CPUtime=283.7
/proc/31393/stat : 31393 (java) S 31380 31379 19908 34817 19908 4202496 88173 0 1 0 28342 28 0 0 25 0 10 0 66497494 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31393/statm: 219676 80850 1784 10 0 216767 0
[pid=31393/tid=31394] ppid=31380 vsize=878704 CPUtime=259.85
/proc/31393/task/31394/stat : 31394 (java) S 31380 31379 19908 34817 19908 4202560 50281 0 1 0 25970 15 0 0 20 0 10 0 66497494 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31395] ppid=31380 vsize=878704 CPUtime=21.43
/proc/31393/task/31395/stat : 31395 (java) S 31380 31379 19908 34817 19908 4202560 23467 0 0 0 2133 10 0 0 16 0 10 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31396] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31396/stat : 31396 (java) S 31380 31379 19908 34817 19908 4202560 8 0 0 0 0 0 0 0 18 0 10 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31397] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31397/stat : 31397 (java) S 31380 31379 19908 34817 19908 4202560 6 0 0 0 0 0 0 0 18 0 10 0 66497496 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31398] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31398/stat : 31398 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31399] ppid=31380 vsize=878704 CPUtime=1.92
/proc/31393/task/31399/stat : 31399 (java) S 31380 31379 19908 34817 19908 4202560 10511 0 0 0 190 2 0 0 15 0 10 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31400] ppid=31380 vsize=878704 CPUtime=0.47
/proc/31393/task/31400/stat : 31400 (java) S 31380 31379 19908 34817 19908 4202560 2829 0 0 0 47 0 0 0 15 0 10 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31401] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31401/stat : 31401 (java) S 31380 31379 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=31393/tid=31402] ppid=31380 vsize=878704 CPUtime=0
/proc/31393/task/31402/stat : 31402 (java) S 31380 31379 19908 34817 19908 4202560 2 0 0 0 0 0 0 0 15 0 10 0 66497499 899792896 80850 1283457024 134512640 134550932 4289977296 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.73
Current children cumulated vsize (KiB) 883844

Child status: 0
Real time (s): 284.858
CPU time (s): 283.766
CPU user time (s): 283.442
CPU system time (s): 0.32402
CPU usage (%): 99.6165
Max. virtual memory (cumulated for all children) (KiB): 891096

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 283.442
system time used= 0.32402
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 91973
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= 6033
involuntary context switches= 8811

runsolver used 0 second user time and 0 second system time

The end