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: runsolver -W 300 -M 1124 -w /home/cbse/results/sarge-etch-lenny-squeeze/logs/92.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends install libnet0-dev bnfc libgnome-ruby1.6 libstonith-dev pentanet-dev Enforcing wall clock limit (soft limit, will send signal-name then SIGKILL): 300 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 [startup+0 s] /proc/loadavg: 0.92 0.98 0.95 2/55 32251 /proc/meminfo: memFree=339628/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=3152 CPUtime=0 /proc/32251/stat : 32251 (runsolver) R 32250 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 29268065 3227648 33 18446744073709551615 134512640 134586868 4292445472 4292443520 4151981104 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.158449 s] /proc/loadavg: 0.92 0.98 0.95 2/55 32251 /proc/meminfo: memFree=339628/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=34568 CPUtime=0.18 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 2106 7225 0 0 9 2 5 2 20 0 1 0 29268065 35397632 1923 18446744073709551615 4194304 5184042 140734040085504 140734040082696 140185587898096 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 8642 1923 729 242 0 1204 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 34568 [startup+0.200262 s] /proc/loadavg: 0.92 0.98 0.95 2/55 32251 /proc/meminfo: memFree=339628/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=35624 CPUtime=0.22 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 2369 7225 0 0 12 3 5 2 20 0 1 0 29268065 36478976 2186 18446744073709551615 4194304 5184042 140734040085504 140734040082696 140185604049360 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 8906 2186 729 242 0 1468 0 Current children cumulated CPU time (s) 0.22 Current children cumulated vsize (KiB) 35624 [startup+0.300248 s] /proc/loadavg: 0.92 0.98 0.95 2/55 32251 /proc/meminfo: memFree=339628/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=38116 CPUtime=0.32 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 3069 7225 0 0 20 5 5 2 20 0 1 0 29268065 39030784 2823 18446744073709551615 4194304 5184042 140734040085504 140734040082696 140185587898096 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 9529 2823 739 242 0 2091 0 Current children cumulated CPU time (s) 0.32 Current children cumulated vsize (KiB) 38116 [startup+0.700198 s] /proc/loadavg: 0.92 0.98 0.95 2/55 32251 /proc/meminfo: memFree=339628/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=64016 CPUtime=0.72 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 9669 7225 0 0 56 9 5 2 20 0 1 0 29268065 65552384 9414 18446744073709551615 4194304 5184042 140734040085504 140734040082744 140185603980800 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 16004 9414 851 242 0 8566 0 Current children cumulated CPU time (s) 0.72 Current children cumulated vsize (KiB) 64016 [startup+1.5002 s] /proc/loadavg: 0.92 0.98 0.95 2/56 32271 /proc/meminfo: memFree=305024/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=64928 CPUtime=1.52 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 9976 7225 0 0 136 9 5 2 20 0 1 0 29268065 66486272 9527 18446744073709551615 4194304 5184042 140734040085504 140734040082808 140185603863644 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 16232 9527 851 242 0 8794 0 Current children cumulated CPU time (s) 1.52 Current children cumulated vsize (KiB) 64928 [startup+3.10021 s] /proc/loadavg: 0.92 0.98 0.95 2/56 32271 /proc/meminfo: memFree=304156/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=66376 CPUtime=3.11 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 10509 7225 0 0 294 10 5 2 20 0 1 0 29268065 67969024 9803 18446744073709551615 4194304 5184042 140734040085504 140734040082952 140185604044912 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 16594 9803 851 242 0 9156 0 Current children cumulated CPU time (s) 3.11 Current children cumulated vsize (KiB) 66376 Solver just ended. Dumping a history of the last processes samples [startup+3.20028 s] /proc/loadavg: 0.92 0.98 0.95 2/56 32271 /proc/meminfo: memFree=304156/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=66376 CPUtime=3.21 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 10520 7225 0 0 304 10 5 2 20 0 1 0 29268065 67969024 9814 18446744073709551615 4194304 5184042 140734040085504 140734040082952 140185595978206 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 16594 9814 851 242 0 9156 0 Current children cumulated CPU time (s) 3.21 Current children cumulated vsize (KiB) 66376 [startup+3.6002 s] /proc/loadavg: 0.92 0.98 0.95 2/56 32271 /proc/meminfo: memFree=303536/1022884 swapFree=0/0 [pid=32251] ppid=32250 vsize=66376 CPUtime=3.6 /proc/32251/stat : 32251 (cupt) R 32250 32251 17872 34816 17872 4202496 10588 7225 0 0 343 10 5 2 20 0 1 0 29268065 67969024 9882 18446744073709551615 4194304 5184042 140734040085504 140734040079800 140185604508734 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/32251/statm: 16594 9882 851 242 0 9156 0 Current children cumulated CPU time (s) 3.6 Current children cumulated vsize (KiB) 66376 Child status: 1 Real time (s): 3.68666 CPU time (s): 3.70423 CPU user time (s): 3.57222 CPU system time (s): 0.132008 CPU usage (%): 100.477 Max. virtual memory (cumulated for all children) (KiB): 66376 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.57222 system time used= 0.132008 maximum resident set size= 39952 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 17849 page faults= 0 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 52 involuntary context switches= 72 runsolver used 0 second user time and 0.020001 second system time The end