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/8680dd8a-8600-11e0-b285-00163e1e087d.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//8680dd8a-8600-11e0-b285-00163e1e087d.cudf /home/misc2010/tmp/201108300820/p2cudf-user-1.13/8680dd8a-8600-11e0-b285-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/35 13795
/proc/meminfo: memFree=938628/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=3020 CPUtime=0
/proc/13795/stat : 13795 (runsolver) D 13794 13795 2160 34817 2160 4202560 73 0 0 0 0 0 0 0 25 0 1 0 57026682 3092480 94 1283457024 134512640 134586868 4290594000 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/13795/statm: 755 94 62 19 0 54 0

[startup+0.177456 s]
/proc/loadavg: 0.85 0.97 0.99 1/35 13795
/proc/meminfo: memFree=938628/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.205494 s]
/proc/loadavg: 0.85 0.97 0.99 1/35 13795
/proc/meminfo: memFree=938628/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.305505 s]
/proc/loadavg: 0.85 0.97 0.99 1/35 13795
/proc/meminfo: memFree=938628/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.705492 s]
/proc/loadavg: 0.85 0.97 0.99 1/35 13795
/proc/meminfo: memFree=938628/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+1.50977 s]
/proc/loadavg: 0.85 0.97 0.99 4/47 13818
/proc/meminfo: memFree=917392/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=882284 CPUtime=0.6
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 12892 0 110 0 57 3 0 0 22 0 10 0 57026686 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220571 11357 1728 10 0 217662 0
[pid=13809/tid=13810] ppid=13797 vsize=882284 CPUtime=0.21
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 4112 0 15 0 20 1 0 0 25 0 10 0 57026720 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=882284 CPUtime=0
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 16 0 10 0 57026737 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=882284 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 24 0 10 0 57026738 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=882284 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 10 0 57026738 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=882284 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 10 0 57026753 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=882284 CPUtime=0.26
/proc/13809/task/13815/stat : 13815 (java) R 13797 13795 2160 34817 2160 4202560 6920 0 1 0 24 2 0 0 23 0 10 0 57026753 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4151351239 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=882284 CPUtime=0.12
/proc/13809/task/13816/stat : 13816 (java) R 13797 13795 2160 34817 2160 4202560 867 0 8 0 12 0 0 0 24 0 10 0 57026753 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4149396687 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=882284 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 57026753 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=882284 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 18 0 10 0 57026753 903458816 11357 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.62
Current children cumulated vsize (KiB) 887420

[startup+3.11019 s]
/proc/loadavg: 0.85 0.97 0.99 3/47 13818
/proc/meminfo: memFree=861220/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=881684 CPUtime=2.2
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 59554 0 110 0 196 24 0 0 22 0 10 0 57026686 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220421 56205 1745 10 0 217512 0
[pid=13809/tid=13810] ppid=13797 vsize=881684 CPUtime=1.34
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 48464 0 15 0 114 20 0 0 25 0 10 0 57026720 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4095346398 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=881684 CPUtime=0
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 16 0 10 0 57026737 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=881684 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 24 0 10 0 57026738 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=881684 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 10 0 57026738 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=881684 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 10 0 57026753 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=881684 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 21 0 10 0 57026753 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=881684 CPUtime=0.44
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 2828 0 8 0 42 2 0 0 23 0 10 0 57026753 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=881684 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 57026753 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=881684 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 10 0 57026753 902844416 56205 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.22
Current children cumulated vsize (KiB) 886820
heavy processes:

[startup+6.31146 s]
/proc/loadavg: 0.86 0.97 0.99 2/47 13819
/proc/meminfo: memFree=651080/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=882828 CPUtime=5.14
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 75657 0 111 0 482 32 0 0 22 0 11 0 57026686 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220707 70927 1779 10 0 217798 0
[pid=13809/tid=13810] ppid=13797 vsize=882828 CPUtime=2.5
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50277 0 15 0 228 22 0 0 25 0 11 0 57026720 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4095976438 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=882828 CPUtime=0.27
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 12549 0 0 0 23 4 0 0 16 0 11 0 57026737 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=882828 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=882828 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=882828 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=882828 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=882828 CPUtime=1.93
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4569 0 9 0 190 3 0 0 15 0 11 0 57026753 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=882828 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=882828 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=882828 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 904015872 70927 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.16
Current children cumulated vsize (KiB) 887964

[startup+12.7138 s]
/proc/loadavg: 0.87 0.97 0.99 2/47 13819
/proc/meminfo: memFree=602660/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=881948 CPUtime=11.54
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 87894 0 111 0 1116 38 0 0 22 0 11 0 57026686 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220487 82976 1786 10 0 217578 0
[pid=13809/tid=13810] ppid=13797 vsize=881948 CPUtime=8.27
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 805 22 0 0 25 0 11 0 57026720 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4095907929 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=881948 CPUtime=0.71
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 24757 0 0 0 61 10 0 0 15 0 11 0 57026737 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=881948 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=881948 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=881948 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=881948 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=881948 CPUtime=2.12
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4596 0 9 0 209 3 0 0 15 0 11 0 57026753 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=881948 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=881948 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=881948 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 903114752 82976 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 11.56
Current children cumulated vsize (KiB) 887084

[startup+25.5065 s]
/proc/loadavg: 0.90 0.97 0.99 2/47 13819
/proc/meminfo: memFree=593484/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880504 CPUtime=24.32
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 90373 0 111 0 2394 38 0 0 22 0 11 0 57026686 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220126 85099 1786 10 0 217217 0
[pid=13809/tid=13810] ppid=13797 vsize=880504 CPUtime=20.08
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 1986 22 0 0 25 0 11 0 57026720 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4095923977 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880504 CPUtime=1.6
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 27226 0 0 0 150 10 0 0 15 0 11 0 57026737 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880504 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880504 CPUtime=2.21
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4606 0 9 0 218 3 0 0 16 0 11 0 57026753 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 901636096 85099 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 24.34
Current children cumulated vsize (KiB) 885640

[startup+51.1119 s]
/proc/loadavg: 0.93 0.97 0.99 2/47 13819
/proc/meminfo: memFree=586540/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880504 CPUtime=49.91
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 92237 0 111 0 4953 38 0 0 22 0 11 0 57026686 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220126 86963 1786 10 0 217217 0
[pid=13809/tid=13810] ppid=13797 vsize=880504 CPUtime=44.48
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 4426 22 0 0 25 0 11 0 57026720 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4096010516 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880504 CPUtime=2.76
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 29088 0 0 0 266 10 0 0 15 0 11 0 57026737 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880504 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880504 CPUtime=2.23
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 220 3 0 0 15 0 11 0 57026753 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 901636096 86963 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 49.93
Current children cumulated vsize (KiB) 885640

[startup+102.305 s]
/proc/loadavg: 0.97 0.97 0.99 2/46 13819
/proc/meminfo: memFree=574644/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880504 CPUtime=101.06
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 95175 0 111 0 10068 38 0 0 22 0 11 0 57026686 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220126 89901 1786 10 0 217217 0
[pid=13809/tid=13810] ppid=13797 vsize=880504 CPUtime=94.17
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 9395 22 0 0 25 0 11 0 57026720 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4095878124 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880504 CPUtime=4.22
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 32026 0 0 0 412 10 0 0 15 0 11 0 57026737 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880504 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880504 CPUtime=2.23
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 220 3 0 0 15 0 11 0 57026753 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 901636096 89901 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 101.08
Current children cumulated vsize (KiB) 885640

[startup+162.308 s]
/proc/loadavg: 0.99 0.97 0.99 3/46 13819
/proc/meminfo: memFree=559144/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880504 CPUtime=161.01
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 99029 0 111 0 16063 38 0 0 22 0 11 0 57026686 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220126 93755 1786 10 0 217217 0
[pid=13809/tid=13810] ppid=13797 vsize=880504 CPUtime=152.9
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 15268 22 0 0 25 0 11 0 57026720 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4095256235 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880504 CPUtime=5.41
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 35880 0 0 0 531 10 0 0 15 0 11 0 57026737 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880504 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880504 CPUtime=2.25
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 222 3 0 0 15 0 11 0 57026753 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 901636096 93755 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.03
Current children cumulated vsize (KiB) 885640

[startup+222.312 s]
/proc/loadavg: 0.99 0.97 0.99 2/46 13819
/proc/meminfo: memFree=544636/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880504 CPUtime=220.93
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 102616 0 111 0 22054 39 0 0 22 0 11 0 57026686 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220126 97342 1786 10 0 217217 0
[pid=13809/tid=13810] ppid=13797 vsize=880504 CPUtime=211.57
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 21135 22 0 0 25 0 11 0 57026720 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4095929656 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880504 CPUtime=6.68
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 39467 0 0 0 657 11 0 0 15 0 11 0 57026737 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880504 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880504 CPUtime=2.25
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 222 3 0 0 15 0 11 0 57026753 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) R 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 901636096 97342 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 220.95
Current children cumulated vsize (KiB) 885640

[startup+282.304 s]
/proc/loadavg: 0.99 0.97 0.99 2/46 13819
/proc/meminfo: memFree=542404/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880504 CPUtime=280.87
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 103192 0 111 0 28048 39 0 0 22 0 11 0 57026686 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220126 97918 1786 10 0 217217 0
[pid=13809/tid=13810] ppid=13797 vsize=880504 CPUtime=270.52
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 27030 22 0 0 25 0 11 0 57026720 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4095923936 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880504 CPUtime=7.66
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 40043 0 0 0 755 11 0 0 15 0 11 0 57026737 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880504 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880504 CPUtime=2.25
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 222 3 0 0 15 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 280.89
Current children cumulated vsize (KiB) 885640

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

[startup+284.705 s]
/proc/loadavg: 0.99 0.97 0.99 2/46 13819
/proc/meminfo: memFree=542404/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880504 CPUtime=283.27
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 103192 0 111 0 28288 39 0 0 22 0 11 0 57026686 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220126 97918 1786 10 0 217217 0
[pid=13809/tid=13810] ppid=13797 vsize=880504 CPUtime=272.92
/proc/13809/task/13810/stat : 13810 (java) R 13797 13795 2160 34817 2160 4202560 50279 0 15 0 27270 22 0 0 25 0 11 0 57026720 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4095929971 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880504 CPUtime=7.66
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 40043 0 0 0 755 11 0 0 15 0 11 0 57026737 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880504 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880504 CPUtime=2.25
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 222 3 0 0 15 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13819] ppid=13797 vsize=880504 CPUtime=0
/proc/13809/task/13819/stat : 13819 (java) S 13797 13795 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 57027215 901636096 97918 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.29
Current children cumulated vsize (KiB) 885640

[startup+285.507 s]
/proc/loadavg: 0.99 0.97 0.99 2/46 13819
/proc/meminfo: memFree=542404/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880828 CPUtime=283.95
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 103206 0 111 0 28356 39 0 0 22 0 11 0 57026686 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220207 97932 1787 10 0 217298 0
[pid=13809/tid=13810] ppid=13797 vsize=880828 CPUtime=273.58
/proc/13809/task/13810/stat : 13810 (java) S 13797 13795 2160 34817 2160 4202560 50287 0 15 0 27336 22 0 0 25 0 11 0 57026720 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880828 CPUtime=7.66
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 40043 0 0 0 755 11 0 0 16 0 11 0 57026737 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880828 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880828 CPUtime=2.27
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 224 3 0 0 15 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.97
Current children cumulated vsize (KiB) 885964

[startup+285.907 s]
/proc/loadavg: 0.99 0.97 0.99 2/46 13820
/proc/meminfo: memFree=542280/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880828 CPUtime=283.95
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 103206 0 111 0 28356 39 0 0 22 0 11 0 57026686 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220207 97932 1787 10 0 217298 0
[pid=13809/tid=13810] ppid=13797 vsize=880828 CPUtime=273.58
/proc/13809/task/13810/stat : 13810 (java) S 13797 13795 2160 34817 2160 4202560 50287 0 15 0 27336 22 0 0 25 0 11 0 57026720 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880828 CPUtime=7.66
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 40043 0 0 0 755 11 0 0 16 0 11 0 57026737 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880828 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880828 CPUtime=2.27
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 224 3 0 0 15 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13820] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13820/stat : 13820 (java) R 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 20 0 11 0 57055222 901967872 97932 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.97
Current children cumulated vsize (KiB) 885964

[startup+286.107 s]
/proc/loadavg: 0.99 0.97 0.99 2/46 13820
/proc/meminfo: memFree=542280/1048576 swapFree=0/0
[pid=13795] ppid=13794 vsize=2568 CPUtime=0
/proc/13795/stat : 13795 (p2cudf-user-1.1) S 13794 13795 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 57026682 2629632 269 1283457024 134512640 135304128 4291419760 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13795/statm: 642 269 230 194 0 29 0
[pid=13797] ppid=13795 vsize=2568 CPUtime=0.02
/proc/13797/stat : 13797 (p2cudf) S 13795 13795 2160 34817 2160 4202496 389 3014 0 1 0 0 2 0 25 0 1 0 57026683 2629632 280 1283457024 134512640 135304128 4289428656 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/13797/statm: 642 280 239 194 0 29 0
[pid=13809] ppid=13797 vsize=880828 CPUtime=284.14
/proc/13809/stat : 13809 (java) S 13797 13795 2160 34817 2160 4202496 103208 0 111 0 28370 44 0 0 22 0 11 0 57026686 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/13809/statm: 220207 97934 1787 10 0 217298 0
[pid=13809/tid=13810] ppid=13797 vsize=880828 CPUtime=273.58
/proc/13809/task/13810/stat : 13810 (java) S 13797 13795 2160 34817 2160 4202560 50287 0 15 0 27336 22 0 0 25 0 11 0 57026720 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13811] ppid=13797 vsize=880828 CPUtime=7.66
/proc/13809/task/13811/stat : 13811 (java) S 13797 13795 2160 34817 2160 4202560 40043 0 0 0 755 11 0 0 16 0 11 0 57026737 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13812] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13812/stat : 13812 (java) S 13797 13795 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 57026738 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13813] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13813/stat : 13813 (java) S 13797 13795 2160 34817 2160 4202560 5 0 0 0 0 0 0 0 18 0 11 0 57026738 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13814] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13814/stat : 13814 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 24 0 11 0 57026753 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13815] ppid=13797 vsize=880828 CPUtime=0.42
/proc/13809/task/13815/stat : 13815 (java) S 13797 13795 2160 34817 2160 4202560 7269 0 1 0 40 2 0 0 18 0 11 0 57026753 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13816] ppid=13797 vsize=880828 CPUtime=2.35
/proc/13809/task/13816/stat : 13816 (java) S 13797 13795 2160 34817 2160 4202560 4608 0 9 0 232 3 0 0 15 0 11 0 57026753 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13817] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13817/stat : 13817 (java) S 13797 13795 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 57026753 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13818] ppid=13797 vsize=880828 CPUtime=0
/proc/13809/task/13818/stat : 13818 (java) S 13797 13795 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 57026753 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0
[pid=13809/tid=13820] ppid=13797 vsize=880828 CPUtime=0.12
/proc/13809/task/13820/stat : 13820 (java) R 13797 13795 2160 34817 2160 4202560 7 0 0 0 7 5 0 0 20 0 11 0 57055222 901967872 97934 1283457024 134512640 134550932 4287977968 18446744073709551615 4145576539 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.16
Current children cumulated vsize (KiB) 885964

Child status: 0
Real time (s): 286.206
CPU time (s): 284.234
CPU user time (s): 283.75
CPU system time (s): 0.48403
CPU usage (%): 99.3108
Max. virtual memory (cumulated for all children) (KiB): 890324

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 283.75
system time used= 0.48403
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 107007
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= 6444
involuntary context switches= 8686

runsolver used 0 second user time and 0.004 second system time

The end