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/688250e8-4bb4-11df-a561-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/debian-dudf//688250e8-4bb4-11df-a561-00163e7a6f5e.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/688250e8-4bb4-11df-a561-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: 1.05 1.03 1.00 3/45 29065
/proc/meminfo: memFree=524288/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) R 29063 29064 4159 34819 4229 4202496 359 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2572 CPUtime=0
/proc/29065/stat : 29065 (p2cudf-trendy-1) R 29064 29064 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 40 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/29065/statm: 643 40 0 194 0 29 0

[startup+0.125142 s]
/proc/loadavg: 1.05 1.03 1.00 3/45 29065
/proc/meminfo: memFree=524288/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.205156 s]
/proc/loadavg: 1.05 1.03 1.00 3/45 29065
/proc/meminfo: memFree=524288/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.30518 s]
/proc/loadavg: 1.05 1.03 1.00 3/45 29065
/proc/meminfo: memFree=524288/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.705232 s]
/proc/loadavg: 1.05 1.03 1.00 3/45 29065
/proc/meminfo: memFree=524288/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+1.50534 s]
/proc/loadavg: 1.05 1.03 1.00 3/56 29087
/proc/meminfo: memFree=482704/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=878336 CPUtime=1.45
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 19046 0 1 0 132 13 0 0 25 0 10 0 883617842 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219584 16160 1729 10 0 216674 0
[pid=29078/tid=29079] ppid=29065 vsize=878336 CPUtime=0.79
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 9708 0 1 0 69 10 0 0 25 0 10 0 883617842 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4095486531 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=878336 CPUtime=0
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 18 0 10 0 883617844 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=878336 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 883617844 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=878336 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 883617844 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=878336 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 883617847 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=878336 CPUtime=0.47
/proc/29078/task/29084/stat : 29084 (java) R 29065 29064 4159 34819 4229 4202560 6570 0 0 0 46 1 0 0 25 0 10 0 883617847 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=878336 CPUtime=0.18
/proc/29078/task/29085/stat : 29085 (java) R 29065 29064 4159 34819 4229 4202560 1655 0 0 0 17 1 0 0 21 0 10 0 883617847 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4151826886 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=878336 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 883617847 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=878336 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 883617847 899416064 16160 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 883484

[startup+3.10572 s]
/proc/loadavg: 1.05 1.03 1.00 3/56 29087
/proc/meminfo: memFree=415496/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=878640 CPUtime=3.05
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 55701 0 1 0 278 27 0 0 25 0 10 0 883617842 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219660 50037 1732 10 0 216750 0
[pid=29078/tid=29079] ppid=29065 vsize=878640 CPUtime=1.66
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 42972 0 1 0 142 24 0 0 25 0 10 0 883617842 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4095851296 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 18 0 10 0 883617844 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 883617844 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 883617844 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 883617847 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=878640 CPUtime=0.89
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 8713 0 0 0 88 1 0 0 25 0 10 0 883617847 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=878640 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 10 0 883617847 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 883617847 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 883617847 899727360 50037 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 883788

[startup+6.30698 s]
/proc/loadavg: 1.04 1.03 1.00 3/56 29087
/proc/meminfo: memFree=211640/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=878640 CPUtime=6.25
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 91208 0 1 0 587 38 0 0 25 0 10 0 883617842 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219660 83395 1766 10 0 216750 0
[pid=29078/tid=29079] ppid=29065 vsize=878640 CPUtime=2.88
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 50412 0 1 0 260 28 0 0 25 0 10 0 883617842 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4096426018 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=878640 CPUtime=0.59
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 25794 0 0 0 54 5 0 0 16 0 10 0 883617844 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 10 0 883617844 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 10 0 883617844 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 883617847 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=878640 CPUtime=2.26
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 10988 0 0 0 224 2 0 0 15 0 10 0 883617847 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=878640 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 10 0 883617847 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 883617847 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=878640 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 883617847 899727360 83395 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.28
Current children cumulated vsize (KiB) 883788

[startup+12.7089 s]
/proc/loadavg: 1.12 1.04 1.01 2/57 29089
/proc/meminfo: memFree=172572/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=878964 CPUtime=12.64
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 97400 0 1 0 1224 40 0 0 25 0 11 0 883617842 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219741 89587 1792 10 0 216831 0
[pid=29078/tid=29079] ppid=29065 vsize=878964 CPUtime=7.99
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 50473 0 1 0 771 28 0 0 25 0 11 0 883617842 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4096380640 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=878964 CPUtime=0.82
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 31773 0 0 0 75 7 0 0 15 0 11 0 883617844 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=878964 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 883617844 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=878964 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 883617844 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=878964 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=878964 CPUtime=3.32
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 11136 0 0 0 330 2 0 0 15 0 11 0 883617847 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=878964 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 11 0 883617847 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=878964 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=878964 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 883617847 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29089] ppid=29065 vsize=878964 CPUtime=0
/proc/29078/task/29089/stat : 29089 (java) S 29065 29064 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 883618917 900059136 89587 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.67
Current children cumulated vsize (KiB) 884112

[startup+25.513 s]
/proc/loadavg: 1.25 1.07 1.02 2/57 29099
/proc/meminfo: memFree=137976/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=879288 CPUtime=25.44
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 106001 0 1 0 2500 44 0 0 25 0 11 0 883617842 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219822 98188 1793 10 0 216912 0
[pid=29078/tid=29079] ppid=29065 vsize=879288 CPUtime=20.01
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 50475 0 1 0 1972 29 0 0 25 0 11 0 883617842 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4096923046 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=879288 CPUtime=1.34
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 40358 0 0 0 124 10 0 0 15 0 11 0 883617844 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=879288 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 883617844 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=879288 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 883617844 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=879288 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=879288 CPUtime=3.58
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 11146 0 0 0 356 2 0 0 15 0 11 0 883617847 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=879288 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 11 0 883617847 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=879288 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=879288 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 883617847 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29099] ppid=29065 vsize=879288 CPUtime=0
/proc/29078/task/29099/stat : 29099 (java) S 29065 29064 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 23 0 11 0 883620327 900390912 98188 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.47
Current children cumulated vsize (KiB) 884436

[startup+51.1101 s]
/proc/loadavg: 1.16 1.06 1.01 2/57 29117
/proc/meminfo: memFree=135868/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=879612 CPUtime=50.99
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 106557 0 1 0 5053 46 0 0 25 0 11 0 883617842 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219903 98744 1793 10 0 216993 0
[pid=29078/tid=29079] ppid=29065 vsize=879612 CPUtime=43.81
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 50477 0 1 0 4351 30 0 0 25 0 11 0 883617842 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4096863273 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=879612 CPUtime=2.76
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 40905 0 0 0 266 10 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=879612 CPUtime=3.89
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 11149 0 0 0 387 2 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=879612 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29117] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29117/stat : 29117 (java) S 29065 29064 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 883622117 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.02
Current children cumulated vsize (KiB) 884760

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

[startup+52.7106 s]
/proc/loadavg: 1.16 1.06 1.01 3/57 29117
/proc/meminfo: memFree=135868/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=879612 CPUtime=52.59
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 106557 0 1 0 5213 46 0 0 25 0 11 0 883617842 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219903 98744 1793 10 0 216993 0
[pid=29078/tid=29079] ppid=29065 vsize=879612 CPUtime=45.41
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 50477 0 1 0 4511 30 0 0 25 0 11 0 883617842 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4097004380 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=879612 CPUtime=2.76
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 40905 0 0 0 266 10 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=879612 CPUtime=3.89
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 11149 0 0 0 387 2 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=879612 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) R 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29117] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29117/stat : 29117 (java) S 29065 29064 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 883622117 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 52.62
Current children cumulated vsize (KiB) 884760

[startup+54.311 s]
/proc/loadavg: 1.16 1.06 1.01 2/57 29117
/proc/meminfo: memFree=135868/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=879612 CPUtime=54.19
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 106557 0 1 0 5373 46 0 0 25 0 11 0 883617842 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219903 98744 1793 10 0 216993 0
[pid=29078/tid=29079] ppid=29065 vsize=879612 CPUtime=47.01
/proc/29078/task/29079/stat : 29079 (java) R 29065 29064 4159 34819 4229 4202560 50477 0 1 0 4671 30 0 0 25 0 11 0 883617842 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4096934389 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=879612 CPUtime=2.76
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 40905 0 0 0 266 10 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=879612 CPUtime=3.89
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 11149 0 0 0 387 2 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=879612 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 883617847 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29117] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29117/stat : 29117 (java) S 29065 29064 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 883622117 900722688 98744 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 54.22
Current children cumulated vsize (KiB) 884760

[startup+55.1045 s]
/proc/loadavg: 1.23 1.08 1.02 1/57 29119
/proc/meminfo: memFree=135868/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=879612 CPUtime=54.73
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 106565 0 1 0 5426 47 0 0 25 0 11 0 883617842 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219903 98752 1793 10 0 216993 0
[pid=29078/tid=29079] ppid=29065 vsize=879612 CPUtime=47.5
/proc/29078/task/29079/stat : 29079 (java) S 29065 29064 4159 34819 4229 4202560 50483 0 1 0 4719 31 0 0 25 0 11 0 883617842 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=879612 CPUtime=2.76
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 40905 0 0 0 266 10 0 0 16 0 11 0 883617844 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=879612 CPUtime=3.94
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 11151 0 0 0 392 2 0 0 16 0 11 0 883617847 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=879612 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 11 0 883617847 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 883617847 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29119] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29119/stat : 29119 (java) S 29065 29064 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 883623323 900722688 98752 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 54.76
Current children cumulated vsize (KiB) 884760

[startup+55.5126 s]
/proc/loadavg: 1.23 1.08 1.02 1/57 29119
/proc/meminfo: memFree=135868/1048576 swapFree=2077628/2097144
[pid=29064] ppid=29063 vsize=2572 CPUtime=0
/proc/29064/stat : 29064 (p2cudf-trendy-1) S 29063 29064 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 883617838 2633728 273 1283457024 134512640 135304128 4287113840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29064/statm: 643 273 233 194 0 29 0
[pid=29065] ppid=29064 vsize=2576 CPUtime=0.03
/proc/29065/stat : 29065 (p2cudf) S 29064 29064 4159 34819 4229 4202496 392 2994 0 1 0 0 2 1 25 0 1 0 883617838 2637824 282 1283457024 134512640 135304128 4293529728 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29065/statm: 644 282 241 194 0 30 0
[pid=29078] ppid=29065 vsize=879612 CPUtime=54.89
/proc/29078/stat : 29078 (java) S 29065 29064 4159 34819 4229 4202496 106571 0 1 0 5439 50 0 0 25 0 11 0 883617842 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29078/statm: 219903 98758 1793 10 0 216993 0
[pid=29078/tid=29079] ppid=29065 vsize=879612 CPUtime=47.5
/proc/29078/task/29079/stat : 29079 (java) S 29065 29064 4159 34819 4229 4202560 50483 0 1 0 4719 31 0 0 25 0 11 0 883617842 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29080] ppid=29065 vsize=879612 CPUtime=2.76
/proc/29078/task/29080/stat : 29080 (java) S 29065 29064 4159 34819 4229 4202560 40905 0 0 0 266 10 0 0 16 0 11 0 883617844 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 0 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29081] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29081/stat : 29081 (java) S 29065 29064 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29082] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29082/stat : 29082 (java) S 29065 29064 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 883617844 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29083] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29083/stat : 29083 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29084] ppid=29065 vsize=879612 CPUtime=4.01
/proc/29078/task/29084/stat : 29084 (java) S 29065 29064 4159 34819 4229 4202560 11155 0 0 0 399 2 0 0 15 0 11 0 883617847 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29085] ppid=29065 vsize=879612 CPUtime=0.5
/proc/29078/task/29085/stat : 29085 (java) S 29065 29064 4159 34819 4229 4202560 2903 0 0 0 48 2 0 0 15 0 11 0 883617847 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29086] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29086/stat : 29086 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 883617847 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29087] ppid=29065 vsize=879612 CPUtime=0
/proc/29078/task/29087/stat : 29087 (java) S 29065 29064 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 883617847 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 18446612133055118400 0 0 -1 0 0 0 0
[pid=29078/tid=29119] ppid=29065 vsize=879612 CPUtime=0.09
/proc/29078/task/29119/stat : 29119 (java) R 29065 29064 4159 34819 4229 4202560 2 0 0 0 6 3 0 0 21 0 11 0 883623323 900722688 98758 1283457024 134512640 134550932 4293117632 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 54.92
Current children cumulated vsize (KiB) 884760

Child status: 0
Real time (s): 55.5762
CPU time (s): 54.9754
CPU user time (s): 54.4274
CPU system time (s): 0.548034
CPU usage (%): 98.9191
Max. virtual memory (cumulated for all children) (KiB): 886680

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 54.4274
system time used= 0.548034
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 110360
page faults= 2
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 2659
involuntary context switches= 3180

runsolver used 0 second user time and 0 second system time

The end