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/logs/87.runsolver.aptitude aptitude -s -y --without-recommends install sniffit libmath-round-perl lexmark7000linux kftgtd pike7.4-sdl 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.99 0.76 0.36 2/55 19998 /proc/meminfo: memFree=243828/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=3152 CPUtime=0 /proc/19998/stat : 19998 (runsolver) R 19997 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28205431 3227648 32 18446744073709551615 134512640 134586868 4292780384 4292778432 4151264304 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.125124 s] /proc/loadavg: 0.99 0.76 0.36 2/55 19998 /proc/meminfo: memFree=243828/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=80316 CPUtime=0.11 /proc/19998/stat : 19998 (aptitude) R 19997 19998 17863 34816 17863 4202496 5570 1710 0 0 9 2 0 0 20 0 1 0 28205431 82243584 5368 18446744073709551615 140180132147200 140180136454552 140736397632944 140736397624208 140180127547556 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 20079 5368 4645 1052 0 670 0 Current children cumulated CPU time (s) 0.11 Current children cumulated vsize (KiB) 80316 [startup+0.200305 s] /proc/loadavg: 0.99 0.76 0.36 2/55 19998 /proc/meminfo: memFree=243828/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=87044 CPUtime=0.18 /proc/19998/stat : 19998 (aptitude) R 19997 19998 17863 34816 17863 4202496 7517 1710 0 0 16 2 0 0 20 0 1 0 28205431 89133056 7139 18446744073709551615 140180132147200 140180136454552 140736397632944 140736397622456 140180135071601 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 21761 7139 4721 1052 0 2352 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 87044 [startup+0.300278 s] /proc/loadavg: 0.99 0.76 0.36 2/55 19998 /proc/meminfo: memFree=243828/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=90632 CPUtime=0.28 /proc/19998/stat : 19998 (aptitude) R 19997 19998 17863 34816 17863 4202496 8630 1710 0 0 25 3 0 0 20 0 1 0 28205431 92807168 7674 18446744073709551615 140180132147200 140180136454552 140736397632944 140736397622376 140180127047395 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 22658 7674 4725 1052 0 3249 0 Current children cumulated CPU time (s) 0.28 Current children cumulated vsize (KiB) 90632 [startup+0.700219 s] /proc/loadavg: 0.99 0.76 0.36 2/55 19998 /proc/meminfo: memFree=243828/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=100628 CPUtime=0.68 /proc/19998/stat : 19998 (aptitude) R 19997 19998 17863 34816 17863 4202496 10122 1710 0 0 65 3 0 0 20 0 2 0 28205431 103043072 8751 18446744073709551615 140180132147200 140180136454552 140736397632944 140736397621112 140180090299609 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 25157 8751 4994 1052 0 5748 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 100628 Solver just ended. Dumping a history of the last processes samples [startup+0.800284 s] /proc/loadavg: 0.99 0.76 0.36 2/55 19998 /proc/meminfo: memFree=243828/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=100628 CPUtime=0.78 /proc/19998/stat : 19998 (aptitude) R 19997 19998 17863 34816 17863 4202496 10133 1710 0 0 75 3 0 0 20 0 2 0 28205431 103043072 8762 18446744073709551615 140180132147200 140180136454552 140736397632944 140736397627192 140180134348571 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 25157 8762 5002 1052 0 5748 0 Current children cumulated CPU time (s) 0.78 Current children cumulated vsize (KiB) 100628 [startup+1.20326 s] /proc/loadavg: 0.99 0.76 0.36 2/57 20004 /proc/meminfo: memFree=228188/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=101524 CPUtime=1.18 /proc/19998/stat : 19998 (aptitude) S 19997 19998 17863 34816 17863 4202496 11007 2209 0 0 114 4 0 0 20 0 2 0 28205431 103960576 8971 18446744073709551615 140180132147200 140180136454552 140736397632944 140736397624704 140180100892235 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 25381 8971 5097 1052 0 5972 0 [pid=19998/tid=20003] ppid=19997 vsize=101524 CPUtime=0.01 /proc/19998/task/20003/stat : 20003 (aptitude) R 19997 19998 17863 34816 17863 4202560 340 2209 0 0 1 0 0 0 20 0 2 0 28205461 103960576 8971 18446744073709551615 140180132147200 140180136454552 140736397632944 140180048725896 140180133776160 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.18 Current children cumulated vsize (KiB) 101524 [startup+1.40025 s] /proc/loadavg: 0.99 0.76 0.36 2/57 20004 /proc/meminfo: memFree=228188/1022884 swapFree=0/0 [pid=19998] ppid=19997 vsize=105520 CPUtime=1.37 /proc/19998/stat : 19998 (aptitude) R 19997 19998 17863 34816 17863 4202496 12788 2209 0 0 132 5 0 0 20 0 2 0 28205431 108052480 10138 18446744073709551615 140180132147200 140180136454552 140736397632944 140736397620808 140180127000914 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19998/statm: 26380 10138 5151 1052 0 6964 0 [pid=19998/tid=20003] ppid=19997 vsize=105520 CPUtime=0.16 /proc/19998/task/20003/stat : 20003 (aptitude) S 19997 19998 17863 34816 17863 4202560 1608 2209 0 0 16 0 0 0 20 0 2 0 28205461 108052480 10138 18446744073709551615 140180132147200 140180136454552 140736397632944 140180048726016 140180100891348 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.37 Current children cumulated vsize (KiB) 105520 Child status: 0 Real time (s): 1.487 CPU time (s): 1.48409 CPU user time (s): 1.40009 CPU system time (s): 0.084005 CPU usage (%): 99.8044 Max. virtual memory (cumulated for all children) (KiB): 105520 getrusage(RUSAGE_CHILDREN,...) data: user time used= 1.40009 system time used= 0.084005 maximum resident set size= 40564 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 16632 page faults= 0 swaps= 0 block input operations= 0 block output operations= 16 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 60 involuntary context switches= 76 runsolver used 0.012 second user time and 0 second system time The end