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/201012070034/p2cudf-trendy-1.9/7266f636-4b23-11df-9e6e-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/debian-dudf//7266f636-4b23-11df-9e6e-00163e7a6f5e.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/7266f636-4b23-11df-9e6e-00163e7a6f5e.cudf.debian-dudf.result 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 seconds
Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB
Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 0.85 0.97 0.99 1/44 31351
/proc/meminfo: memFree=814816/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=3020 CPUtime=0
/proc/31351/stat : 31351 (runsolver) D 31350 31351 4159 34819 4229 4202560 72 0 0 0 0 0 0 0 24 0 1 0 884244893 3092480 93 1283457024 134512640 134586868 4294570160 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/31351/statm: 755 93 62 19 0 53 0

[startup+0.179113 s]
/proc/loadavg: 0.85 0.97 0.99 1/44 31351
/proc/meminfo: memFree=814816/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+0.207091 s]
/proc/loadavg: 0.85 0.97 0.99 1/44 31351
/proc/meminfo: memFree=814816/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+0.307139 s]
/proc/loadavg: 0.85 0.97 0.99 1/44 31351
/proc/meminfo: memFree=814816/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+0.707146 s]
/proc/loadavg: 0.85 0.97 0.99 1/44 31351
/proc/meminfo: memFree=814816/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2572

[startup+1.50721 s]
/proc/loadavg: 0.85 0.97 0.99 1/51 31369
/proc/meminfo: memFree=789048/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=856952 CPUtime=0.18
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 4609 0 147 0 16 2 0 0 19 0 10 0 884244955 877518848 4412 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 214238 4412 1713 10 0 211328 0
[pid=31365/tid=31366] ppid=31353 vsize=856952 CPUtime=0.12
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 2762 0 57 0 10 2 0 0 21 0 10 0 884244983 877518848 4412 1283457024 134512640 134550932 4290792576 18446744073709551615 4149613701 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=856952 CPUtime=0
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 20 0 10 0 884244996 877518848 4412 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=856952 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 8 0 1 0 0 0 0 0 20 0 10 0 884244996 877518848 4412 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=856952 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 21 0 10 0 884244996 877518848 4412 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.18
Current children cumulated vsize (KiB) 862100

[startup+3.10988 s]
/proc/loadavg: 0.85 0.97 0.99 3/56 31374
/proc/meminfo: memFree=759588/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871468 CPUtime=1.57
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 32821 0 149 0 143 14 0 0 19 0 10 0 884244955 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217867 28828 1743 10 0 214957 0
[pid=31365/tid=31366] ppid=31353 vsize=871468 CPUtime=0.7
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 23464 0 57 0 58 12 0 0 21 0 10 0 884244983 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4095298558 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 15 0 10 0 884244996 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 8 0 1 0 0 0 0 0 20 0 10 0 884244996 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 21 0 10 0 884244996 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 10 0 884245008 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871468 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 10 0 884245008 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871468 CPUtime=0.83
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 7753 0 2 0 81 2 0 0 15 0 10 0 884245008 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 10 0 884245008 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 884245008 892383232 28828 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.57
Current children cumulated vsize (KiB) 876616
heavy processes:

[startup+6.30509 s]
/proc/loadavg: 0.86 0.97 0.99 2/56 31374
/proc/meminfo: memFree=501192/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871468 CPUtime=4.55
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 88638 0 150 0 425 30 0 0 19 0 10 0 884244955 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217867 81424 1765 10 0 214957 0
[pid=31365/tid=31366] ppid=31353 vsize=871468 CPUtime=1.97
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 50300 0 57 0 177 20 0 0 25 0 10 0 884244983 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871468 CPUtime=0.58
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 25658 0 0 0 50 8 0 0 16 0 10 0 884244996 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 10 0 884244996 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 10 0 884244996 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 10 0 884245008 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871468 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 10 0 884245008 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871468 CPUtime=1.95
/proc/31365/task/31372/stat : 31372 (java) R 31353 31351 4159 34819 4229 4202560 11078 0 3 0 193 2 0 0 16 0 10 0 884245008 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4148570385 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 10 0 884245008 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871468 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 884245008 892383232 81424 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.55
Current children cumulated vsize (KiB) 876616
heavy processes:

[startup+12.7062 s]
/proc/loadavg: 1.03 1.00 1.00 2/57 31376
/proc/meminfo: memFree=426988/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=870512 CPUtime=10.87
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 95767 0 150 0 1054 33 0 0 19 0 11 0 884244955 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217628 87303 1793 10 0 214718 0
[pid=31365/tid=31366] ppid=31353 vsize=870512 CPUtime=6.69
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 50365 0 57 0 648 21 0 0 25 0 11 0 884244983 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4096149353 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=870512 CPUtime=0.8
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 31618 0 0 0 72 8 0 0 15 0 11 0 884244996 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 11 0 884244996 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 884244996 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=870512 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=870512 CPUtime=3.32
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12178 0 3 0 328 4 0 0 15 0 11 0 884245008 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31376] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31376/stat : 31376 (java) S 31353 31351 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884245954 891404288 87303 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.87
Current children cumulated vsize (KiB) 875660

[startup+25.5101 s]
/proc/loadavg: 1.17 1.03 1.01 2/57 31382
/proc/meminfo: memFree=391648/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=870512 CPUtime=23.66
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 104566 0 150 0 2330 36 0 0 19 0 11 0 884244955 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217628 96102 1794 10 0 214718 0
[pid=31365/tid=31366] ppid=31353 vsize=870512 CPUtime=18.78
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 50366 0 57 0 1857 21 0 0 25 0 11 0 884244983 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4096325216 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=870512 CPUtime=1.3
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40410 0 0 0 119 11 0 0 16 0 11 0 884244996 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 11 0 884244996 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 884244996 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=870512 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=870512 CPUtime=3.52
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12184 0 3 0 348 4 0 0 15 0 11 0 884245008 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31382] ppid=31353 vsize=870512 CPUtime=0
/proc/31365/task/31382/stat : 31382 (java) S 31353 31351 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884247366 891404288 96102 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 23.66
Current children cumulated vsize (KiB) 875660

[startup+51.1072 s]
/proc/loadavg: 1.17 1.04 1.01 2/57 31399
/proc/meminfo: memFree=389292/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871484 CPUtime=49.25
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 105128 0 150 0 4889 36 0 0 19 0 11 0 884244955 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217871 96664 1794 10 0 214961 0
[pid=31365/tid=31366] ppid=31353 vsize=871484 CPUtime=42.76
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 50370 0 57 0 4255 21 0 0 25 0 11 0 884244983 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4096183065 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871484 CPUtime=2.69
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40956 0 0 0 258 11 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871484 CPUtime=3.74
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12184 0 3 0 370 4 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31399] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31399/stat : 31399 (java) S 31353 31351 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884249130 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 49.25
Current children cumulated vsize (KiB) 876632

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

[startup+54.308 s]
/proc/loadavg: 1.15 1.04 1.01 2/57 31399
/proc/meminfo: memFree=389292/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871484 CPUtime=52.45
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 105128 0 150 0 5209 36 0 0 19 0 11 0 884244955 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217871 96664 1794 10 0 214961 0
[pid=31365/tid=31366] ppid=31353 vsize=871484 CPUtime=45.96
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 50370 0 57 0 4575 21 0 0 25 0 11 0 884244983 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4096177969 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871484 CPUtime=2.69
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40956 0 0 0 258 11 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871484 CPUtime=3.74
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12184 0 3 0 370 4 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31399] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31399/stat : 31399 (java) S 31353 31351 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884249130 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 52.45
Current children cumulated vsize (KiB) 876632

[startup+57.5089 s]
/proc/loadavg: 1.15 1.04 1.01 2/57 31399
/proc/meminfo: memFree=389292/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871484 CPUtime=55.62
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 105128 0 150 0 5526 36 0 0 19 0 11 0 884244955 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217871 96664 1794 10 0 214961 0
[pid=31365/tid=31366] ppid=31353 vsize=871484 CPUtime=49.13
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 50370 0 57 0 4892 21 0 0 25 0 11 0 884244983 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4096212390 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871484 CPUtime=2.69
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40956 0 0 0 258 11 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871484 CPUtime=3.74
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12184 0 3 0 370 4 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31399] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31399/stat : 31399 (java) S 31353 31351 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884249130 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 55.62
Current children cumulated vsize (KiB) 876632

[startup+58.3091 s]
/proc/loadavg: 1.15 1.04 1.01 3/57 31402
/proc/meminfo: memFree=389292/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871484 CPUtime=56.42
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 105128 0 150 0 5606 36 0 0 19 0 11 0 884244955 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217871 96664 1794 10 0 214961 0
[pid=31365/tid=31366] ppid=31353 vsize=871484 CPUtime=49.88
/proc/31365/task/31366/stat : 31366 (java) R 31353 31351 4159 34819 4229 4202560 50370 0 57 0 4967 21 0 0 25 0 11 0 884244983 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4145482096 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871484 CPUtime=2.69
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40956 0 0 0 258 11 0 0 16 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884244996 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871484 CPUtime=3.8
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12184 0 3 0 376 4 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31402] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31402/stat : 31402 (java) S 31353 31351 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884250700 892399616 96664 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 56.42
Current children cumulated vsize (KiB) 876632

[startup+59.0068 s]
/proc/loadavg: 1.15 1.04 1.01 3/57 31402
/proc/meminfo: memFree=389292/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871484 CPUtime=56.95
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 105134 0 150 0 5659 36 0 0 19 0 11 0 884244955 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217871 96670 1794 10 0 214961 0
[pid=31365/tid=31366] ppid=31353 vsize=871484 CPUtime=49.97
/proc/31365/task/31366/stat : 31366 (java) S 31353 31351 4159 34819 4229 4202560 50376 0 57 0 4976 21 0 0 25 0 11 0 884244983 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871484 CPUtime=3.11
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40956 0 0 0 300 11 0 0 16 0 11 0 884244996 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884244996 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884244996 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871484 CPUtime=3.82
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12184 0 3 0 378 4 0 0 16 0 11 0 884245008 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 892399616 96670 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 56.95
Current children cumulated vsize (KiB) 876632

[startup+59.4136 s]
/proc/loadavg: 1.30 1.08 1.02 1/57 31403
/proc/meminfo: memFree=389292/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871484 CPUtime=57
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 105135 0 150 0 5663 37 0 0 19 0 11 0 884244955 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217871 96671 1794 10 0 214961 0
[pid=31365/tid=31366] ppid=31353 vsize=871484 CPUtime=49.97
/proc/31365/task/31366/stat : 31366 (java) S 31353 31351 4159 34819 4229 4202560 50376 0 57 0 4976 21 0 0 25 0 11 0 884244983 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871484 CPUtime=3.11
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40956 0 0 0 300 11 0 0 16 0 11 0 884244996 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884244996 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884244996 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871484 CPUtime=3.83
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12184 0 3 0 379 4 0 0 15 0 11 0 884245008 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31403] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31403/stat : 31403 (java) R 31353 31351 4159 34819 4229 4202560 1 0 0 0 3 0 0 0 20 0 11 0 884250779 892399616 96671 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 57
Current children cumulated vsize (KiB) 876632

[startup+59.5028 s]
/proc/loadavg: 1.30 1.08 1.02 1/57 31403
/proc/meminfo: memFree=389292/1048576 swapFree=2077628/2097144
[pid=31351] ppid=31350 vsize=2572 CPUtime=0
/proc/31351/stat : 31351 (p2cudf-trendy-1) S 31350 31351 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 884244893 2633728 273 1283457024 134512640 135304128 4292181840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31351/statm: 643 273 233 194 0 29 0
[pid=31353] ppid=31351 vsize=2576 CPUtime=0
/proc/31353/stat : 31353 (p2cudf) S 31351 31351 4159 34819 4229 4202496 394 2786 0 127 0 0 0 0 20 0 1 0 884244894 2637824 282 1283457024 134512640 135304128 4287386272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/31353/statm: 644 282 241 194 0 30 0
[pid=31365] ppid=31353 vsize=871484 CPUtime=57.1
/proc/31365/stat : 31365 (java) S 31353 31351 4159 34819 4229 4202496 105138 0 150 0 5668 42 0 0 19 0 11 0 884244955 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/31365/statm: 217871 96674 1794 10 0 214961 0
[pid=31365/tid=31366] ppid=31353 vsize=871484 CPUtime=49.97
/proc/31365/task/31366/stat : 31366 (java) S 31353 31351 4159 34819 4229 4202560 50376 0 57 0 4976 21 0 0 25 0 11 0 884244983 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31367] ppid=31353 vsize=871484 CPUtime=3.11
/proc/31365/task/31367/stat : 31367 (java) S 31353 31351 4159 34819 4229 4202560 40956 0 0 0 300 11 0 0 16 0 11 0 884244996 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 0 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31368] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31368/stat : 31368 (java) S 31353 31351 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884244996 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31369] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31369/stat : 31369 (java) S 31353 31351 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884244996 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31370] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31370/stat : 31370 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884245008 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31371] ppid=31353 vsize=871484 CPUtime=0.03
/proc/31365/task/31371/stat : 31371 (java) S 31353 31351 4159 34819 4229 4202560 573 0 6 0 3 0 0 0 16 0 11 0 884245008 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31372] ppid=31353 vsize=871484 CPUtime=3.86
/proc/31365/task/31372/stat : 31372 (java) S 31353 31351 4159 34819 4229 4202560 12186 0 3 0 382 4 0 0 16 0 11 0 884245008 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31373] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31373/stat : 31373 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 22 0 11 0 884245008 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31374] ppid=31353 vsize=871484 CPUtime=0
/proc/31365/task/31374/stat : 31374 (java) S 31353 31351 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 884245008 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 18446612132964191040 0 0 -1 0 0 0 0
[pid=31365/tid=31403] ppid=31353 vsize=871484 CPUtime=0.1
/proc/31365/task/31403/stat : 31403 (java) R 31353 31351 4159 34819 4229 4202560 2 0 0 0 5 5 0 0 20 0 11 0 884250779 892399616 96674 1283457024 134512640 134550932 4290792576 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 57.1
Current children cumulated vsize (KiB) 876632

Child status: 0
Real time (s): 59.6077
CPU time (s): 57.1676
CPU user time (s): 56.7075
CPU system time (s): 0.460028
CPU usage (%): 95.9064
Max. virtual memory (cumulated for all children) (KiB): 881696

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 56.7075
system time used= 0.460028
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 108715
page faults= 277
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 3640
involuntary context switches= 3331

runsolver used 0.012 second user time and 0 second system time

The end