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/201103031013/p2cudf-trendy-1.12/56ae4afa-0b33-11df-8a2b-00163e1d94dc.cudf.debian.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/debian//56ae4afa-0b33-11df-8a2b-00163e1d94dc.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/56ae4afa-0b33-11df-8a2b-00163e1d94dc.cudf.debian.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.10 1.05 1.05 5/37 32141
/proc/meminfo: memFree=504524/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2564 CPUtime=0
/proc/32140/stat : 32140 (p2cudf) R 32139 32139 29493 34817 29493 4202496 345 0 0 0 0 0 0 0 25 0 1 0 75082574 2625536 270 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/32140/statm: 641 270 231 194 0 28 0
[pid=32141] ppid=32140 vsize=2564 CPUtime=0
/proc/32141/stat : 32141 (p2cudf) R 32140 32139 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 75082574 2625536 39 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/32141/statm: 641 39 0 194 0 28 0

[startup+0.187693 s]
/proc/loadavg: 1.10 1.05 1.05 5/37 32141
/proc/meminfo: memFree=504524/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+0.207691 s]
/proc/loadavg: 1.10 1.05 1.05 5/37 32141
/proc/meminfo: memFree=504524/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+0.307717 s]
/proc/loadavg: 1.10 1.05 1.05 5/37 32141
/proc/meminfo: memFree=504524/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+0.707852 s]
/proc/loadavg: 1.10 1.05 1.05 5/37 32141
/proc/meminfo: memFree=504524/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+1.5081 s]
/proc/loadavg: 1.10 1.05 1.05 3/47 32162
/proc/meminfo: memFree=455764/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
[pid=32153] ppid=32140 vsize=902752 CPUtime=1.46
/proc/32153/stat : 32153 (java) S 32140 32139 29493 34817 29493 4202496 25762 0 1 0 132 14 0 0 25 0 10 0 75082578 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32153/statm: 225688 21813 1721 10 0 222779 0
[pid=32153/tid=32154] ppid=32140 vsize=902752 CPUtime=0.8
/proc/32153/task/32154/stat : 32154 (java) R 32140 32139 29493 34817 29493 4202560 10301 0 1 0 71 9 0 0 25 0 10 0 75082578 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4095056608 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32153/tid=32155] ppid=32140 vsize=902752 CPUtime=0
/proc/32153/task/32155/stat : 32155 (java) S 32140 32139 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75082580 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32156] ppid=32140 vsize=902752 CPUtime=0
/proc/32153/task/32156/stat : 32156 (java) S 32140 32139 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75082580 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32157] ppid=32140 vsize=902752 CPUtime=0
/proc/32153/task/32157/stat : 32157 (java) S 32140 32139 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75082580 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32158] ppid=32140 vsize=902752 CPUtime=0
/proc/32153/task/32158/stat : 32158 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75082583 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32159] ppid=32140 vsize=902752 CPUtime=0.41
/proc/32153/task/32159/stat : 32159 (java) R 32140 32139 29493 34817 29493 4202560 7699 0 0 0 37 4 0 0 25 0 10 0 75082583 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32153/tid=32160] ppid=32140 vsize=902752 CPUtime=0.24
/proc/32153/task/32160/stat : 32160 (java) R 32140 32139 29493 34817 29493 4202560 6683 0 0 0 24 0 0 0 18 0 10 0 75082583 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4147459767 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32153/tid=32161] ppid=32140 vsize=902752 CPUtime=0
/proc/32153/task/32161/stat : 32161 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75082583 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32162] ppid=32140 vsize=902752 CPUtime=0
/proc/32153/task/32162/stat : 32162 (java) S 32140 32139 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 75082583 924418048 21813 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 907888

[startup+3.10857 s]
/proc/loadavg: 1.17 1.07 1.06 3/47 32162
/proc/meminfo: memFree=390292/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
[pid=32153] ppid=32140 vsize=898764 CPUtime=3.06
/proc/32153/stat : 32153 (java) S 32140 32139 29493 34817 29493 4202496 75707 0 1 0 273 33 0 0 25 0 10 0 75082578 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32153/statm: 224691 70075 1730 10 0 221782 0
[pid=32153/tid=32154] ppid=32140 vsize=898764 CPUtime=1.58
/proc/32153/task/32154/stat : 32154 (java) R 32140 32139 29493 34817 29493 4202560 50008 0 1 0 132 26 0 0 25 0 10 0 75082578 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32153/tid=32155] ppid=32140 vsize=898764 CPUtime=0.18
/proc/32153/task/32155/stat : 32155 (java) R 32140 32139 29493 34817 29493 4202560 8124 0 0 0 17 1 0 0 18 0 10 0 75082580 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4149072248 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=32153/tid=32156] ppid=32140 vsize=898764 CPUtime=0
/proc/32153/task/32156/stat : 32156 (java) S 32140 32139 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75082580 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32157] ppid=32140 vsize=898764 CPUtime=0
/proc/32153/task/32157/stat : 32157 (java) S 32140 32139 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75082580 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32158] ppid=32140 vsize=898764 CPUtime=0
/proc/32153/task/32158/stat : 32158 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75082583 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32159] ppid=32140 vsize=898764 CPUtime=0.51
/proc/32153/task/32159/stat : 32159 (java) S 32140 32139 29493 34817 29493 4202560 7727 0 0 0 47 4 0 0 23 0 10 0 75082583 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32160] ppid=32140 vsize=898764 CPUtime=0.76
/proc/32153/task/32160/stat : 32160 (java) S 32140 32139 29493 34817 29493 4202560 8775 0 0 0 76 0 0 0 16 0 10 0 75082583 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32161] ppid=32140 vsize=898764 CPUtime=0
/proc/32153/task/32161/stat : 32161 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75082583 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32162] ppid=32140 vsize=898764 CPUtime=0
/proc/32153/task/32162/stat : 32162 (java) S 32140 32139 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 75082583 920334336 70075 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 903900

[startup+6.30954 s]
/proc/loadavg: 1.17 1.07 1.06 2/47 32163
/proc/meminfo: memFree=154196/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
[pid=32153] ppid=32140 vsize=898668 CPUtime=6.26
/proc/32153/stat : 32153 (java) S 32140 32139 29493 34817 29493 4202496 96475 0 1 0 580 46 0 0 25 0 11 0 75082578 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32153/statm: 224667 89193 1772 10 0 221758 0
[pid=32153/tid=32154] ppid=32140 vsize=898668 CPUtime=2.85
/proc/32153/task/32154/stat : 32154 (java) R 32140 32139 29493 34817 29493 4202560 50258 0 1 0 257 28 0 0 25 0 11 0 75082578 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4096037237 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32153/tid=32155] ppid=32140 vsize=898668 CPUtime=0.61
/proc/32153/task/32155/stat : 32155 (java) S 32140 32139 29493 34817 29493 4202560 26827 0 0 0 54 7 0 0 16 0 11 0 75082580 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32156] ppid=32140 vsize=898668 CPUtime=0
/proc/32153/task/32156/stat : 32156 (java) S 32140 32139 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75082580 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32157] ppid=32140 vsize=898668 CPUtime=0
/proc/32153/task/32157/stat : 32157 (java) S 32140 32139 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75082580 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32158] ppid=32140 vsize=898668 CPUtime=0
/proc/32153/task/32158/stat : 32158 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75082583 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32159] ppid=32140 vsize=898668 CPUtime=2.01
/proc/32153/task/32159/stat : 32159 (java) S 32140 32139 29493 34817 29493 4202560 9536 0 0 0 192 9 0 0 15 0 11 0 75082583 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32160] ppid=32140 vsize=898668 CPUtime=0.76
/proc/32153/task/32160/stat : 32160 (java) S 32140 32139 29493 34817 29493 4202560 8775 0 0 0 76 0 0 0 16 0 11 0 75082583 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32161] ppid=32140 vsize=898668 CPUtime=0
/proc/32153/task/32161/stat : 32161 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75082583 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32162] ppid=32140 vsize=898668 CPUtime=0
/proc/32153/task/32162/stat : 32162 (java) S 32140 32139 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 75082583 920236032 89193 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.29
Current children cumulated vsize (KiB) 903804

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

[startup+7.91122 s]
/proc/loadavg: 1.24 1.08 1.06 2/48 32164
/proc/meminfo: memFree=154940/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
[pid=32153] ppid=32140 vsize=896732 CPUtime=7.86
/proc/32153/stat : 32153 (java) S 32140 32139 29493 34817 29493 4202496 96508 0 1 0 739 47 0 0 25 0 11 0 75082578 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32153/statm: 224183 88770 1779 10 0 221274 0
[pid=32153/tid=32154] ppid=32140 vsize=896732 CPUtime=4.2
/proc/32153/task/32154/stat : 32154 (java) R 32140 32139 29493 34817 29493 4202560 50261 0 1 0 390 30 0 0 25 0 11 0 75082578 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=32153/tid=32155] ppid=32140 vsize=896732 CPUtime=0.61
/proc/32153/task/32155/stat : 32155 (java) S 32140 32139 29493 34817 29493 4202560 26827 0 0 0 54 7 0 0 15 0 11 0 75082580 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32156] ppid=32140 vsize=896732 CPUtime=0
/proc/32153/task/32156/stat : 32156 (java) S 32140 32139 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75082580 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32157] ppid=32140 vsize=896732 CPUtime=0
/proc/32153/task/32157/stat : 32157 (java) S 32140 32139 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75082580 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32158] ppid=32140 vsize=896732 CPUtime=0
/proc/32153/task/32158/stat : 32158 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75082583 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32159] ppid=32140 vsize=896732 CPUtime=2.27
/proc/32153/task/32159/stat : 32159 (java) S 32140 32139 29493 34817 29493 4202560 9566 0 0 0 218 9 0 0 15 0 11 0 75082583 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32160] ppid=32140 vsize=896732 CPUtime=0.76
/proc/32153/task/32160/stat : 32160 (java) S 32140 32139 29493 34817 29493 4202560 8775 0 0 0 76 0 0 0 16 0 11 0 75082583 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32161] ppid=32140 vsize=896732 CPUtime=0
/proc/32153/task/32161/stat : 32161 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75082583 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32162] ppid=32140 vsize=896732 CPUtime=0
/proc/32153/task/32162/stat : 32162 (java) S 32140 32139 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 75082583 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32164] ppid=32140 vsize=896732 CPUtime=0
/proc/32153/task/32164/stat : 32164 (java) S 32140 32139 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75083151 918253568 88770 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.89
Current children cumulated vsize (KiB) 901868

[startup+8.30615 s]
/proc/loadavg: 1.24 1.08 1.06 2/48 32164
/proc/meminfo: memFree=154940/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
[pid=32153] ppid=32140 vsize=897056 CPUtime=8.04
/proc/32153/stat : 32153 (java) S 32140 32139 29493 34817 29493 4202496 96533 0 1 0 757 47 0 0 25 0 12 0 75082578 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32153/statm: 224264 88795 1781 10 0 221355 0
[pid=32153/tid=32154] ppid=32140 vsize=897056 CPUtime=4.28
/proc/32153/task/32154/stat : 32154 (java) S 32140 32139 29493 34817 29493 4202560 50269 0 1 0 398 30 0 0 25 0 12 0 75082578 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32155] ppid=32140 vsize=897056 CPUtime=0.61
/proc/32153/task/32155/stat : 32155 (java) S 32140 32139 29493 34817 29493 4202560 26827 0 0 0 54 7 0 0 16 0 12 0 75082580 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32156] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32156/stat : 32156 (java) S 32140 32139 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 75082580 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32157] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32157/stat : 32157 (java) S 32140 32139 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 75082580 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32158] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32158/stat : 32158 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32159] ppid=32140 vsize=897056 CPUtime=2.37
/proc/32153/task/32159/stat : 32159 (java) S 32140 32139 29493 34817 29493 4202560 9578 0 0 0 228 9 0 0 15 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32160] ppid=32140 vsize=897056 CPUtime=0.76
/proc/32153/task/32160/stat : 32160 (java) S 32140 32139 29493 34817 29493 4202560 8775 0 0 0 76 0 0 0 16 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32161] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32161/stat : 32161 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32162] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32162/stat : 32162 (java) S 32140 32139 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32164] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32164/stat : 32164 (java) S 32140 32139 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 75083151 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.07
Current children cumulated vsize (KiB) 902192

[startup+8.50625 s]
/proc/loadavg: 1.24 1.08 1.06 2/48 32164
/proc/meminfo: memFree=154940/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
[pid=32153] ppid=32140 vsize=897056 CPUtime=8.04
/proc/32153/stat : 32153 (java) S 32140 32139 29493 34817 29493 4202496 96533 0 1 0 757 47 0 0 25 0 12 0 75082578 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32153/statm: 224264 88795 1781 10 0 221355 0
[pid=32153/tid=32154] ppid=32140 vsize=897056 CPUtime=4.28
/proc/32153/task/32154/stat : 32154 (java) S 32140 32139 29493 34817 29493 4202560 50269 0 1 0 398 30 0 0 25 0 12 0 75082578 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32155] ppid=32140 vsize=897056 CPUtime=0.61
/proc/32153/task/32155/stat : 32155 (java) S 32140 32139 29493 34817 29493 4202560 26827 0 0 0 54 7 0 0 16 0 12 0 75082580 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32156] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32156/stat : 32156 (java) S 32140 32139 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 75082580 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32157] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32157/stat : 32157 (java) S 32140 32139 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 75082580 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32158] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32158/stat : 32158 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32159] ppid=32140 vsize=897056 CPUtime=2.37
/proc/32153/task/32159/stat : 32159 (java) S 32140 32139 29493 34817 29493 4202560 9578 0 0 0 228 9 0 0 15 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32160] ppid=32140 vsize=897056 CPUtime=0.76
/proc/32153/task/32160/stat : 32160 (java) S 32140 32139 29493 34817 29493 4202560 8775 0 0 0 76 0 0 0 16 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32161] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32161/stat : 32161 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32162] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32162/stat : 32162 (java) S 32140 32139 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 12 0 75082583 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32164] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32164/stat : 32164 (java) S 32140 32139 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 75083151 918585344 88795 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.07
Current children cumulated vsize (KiB) 902192

[startup+8.61884 s]
/proc/loadavg: 1.24 1.08 1.06 2/48 32164
/proc/meminfo: memFree=154940/1048576 swapFree=0/0
[pid=32139] ppid=32138 vsize=2568 CPUtime=0
/proc/32139/stat : 32139 (p2cudf-trendy-1) S 32138 32139 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75082574 2629632 271 1283457024 134512640 135304128 4289807616 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32139/statm: 642 271 230 194 0 29 0
[pid=32140] ppid=32139 vsize=2568 CPUtime=0.03
/proc/32140/stat : 32140 (p2cudf) S 32139 32139 29493 34817 29493 4202496 389 3012 0 1 0 0 0 3 25 0 1 0 75082574 2629632 279 1283457024 134512640 135304128 4290637248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/32140/statm: 642 279 239 194 0 29 0
[pid=32153] ppid=32140 vsize=897056 CPUtime=8.05
/proc/32153/stat : 32153 (java) S 32140 32139 29493 34817 29493 4202496 96536 0 1 0 758 47 0 0 25 0 12 0 75082578 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/32153/statm: 224264 88798 1781 10 0 221355 0
[pid=32153/tid=32154] ppid=32140 vsize=897056 CPUtime=4.28
/proc/32153/task/32154/stat : 32154 (java) S 32140 32139 29493 34817 29493 4202560 50269 0 1 0 398 30 0 0 25 0 12 0 75082578 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32155] ppid=32140 vsize=897056 CPUtime=0.61
/proc/32153/task/32155/stat : 32155 (java) S 32140 32139 29493 34817 29493 4202560 26827 0 0 0 54 7 0 0 16 0 12 0 75082580 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 0 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32156] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32156/stat : 32156 (java) S 32140 32139 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 75082580 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32157] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32157/stat : 32157 (java) S 32140 32139 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 75082580 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32158] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32158/stat : 32158 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75082583 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32159] ppid=32140 vsize=897056 CPUtime=2.39
/proc/32153/task/32159/stat : 32159 (java) S 32140 32139 29493 34817 29493 4202560 9581 0 0 0 230 9 0 0 15 0 12 0 75082583 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32160] ppid=32140 vsize=897056 CPUtime=0.76
/proc/32153/task/32160/stat : 32160 (java) S 32140 32139 29493 34817 29493 4202560 8775 0 0 0 76 0 0 0 16 0 12 0 75082583 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32161] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32161/stat : 32161 (java) S 32140 32139 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75082583 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32162] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32162/stat : 32162 (java) S 32140 32139 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 12 0 75082583 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
[pid=32153/tid=32164] ppid=32140 vsize=897056 CPUtime=0
/proc/32153/task/32164/stat : 32164 (java) S 32140 32139 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 75083151 918585344 88798 1283457024 134512640 134550932 4287928784 18446744073709551615 4294960130 0 4 0 16800975 18446612133396263168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.08
Current children cumulated vsize (KiB) 902192

Child status: 0
Real time (s): 8.69241
CPU time (s): 8.12851
CPU user time (s): 7.59247
CPU system time (s): 0.536033
CPU usage (%): 93.5127
Max. virtual memory (cumulated for all children) (KiB): 909556

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 7.59247
system time used= 0.536033
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 100338
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= 893
involuntary context switches= 897

runsolver used 0 second user time and 0 second system time

The end