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/logs/158.runsolver.aptitude aptitude -s -y --without-recommends remove libxcursor-dev libapr0 freeglut3 libgstreamer-plugins0.8-0 libsasl2 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: 1.14 1.05 1.01 2/55 22615 /proc/meminfo: memFree=909388/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=3152 CPUtime=0 /proc/22615/stat : 22615 (runsolver) R 22614 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28715814 3227648 32 18446744073709551615 134512640 134586868 4289682560 4289680608 4151514160 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.107265 s] /proc/loadavg: 1.14 1.05 1.01 2/55 22615 /proc/meminfo: memFree=909388/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=89152 CPUtime=0.09 /proc/22615/stat : 22615 (aptitude) R 22614 22615 17863 34816 17863 4202496 7760 1709 0 0 8 0 0 1 20 0 1 0 28715814 91291648 7560 18446744073709551615 140676836438016 140676840745368 140735158280224 140735158271488 140676831279598 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 22288 7560 6649 1052 0 858 0 Current children cumulated CPU time (s) 0.09 Current children cumulated vsize (KiB) 89152 [startup+0.200287 s] /proc/loadavg: 1.14 1.05 1.01 2/55 22615 /proc/meminfo: memFree=909388/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=89152 CPUtime=0.19 /proc/22615/stat : 22615 (aptitude) R 22614 22615 17863 34816 17863 4202496 7870 1709 0 0 18 0 0 1 20 0 1 0 28715814 91291648 7662 18446744073709551615 140676836438016 140676840745368 140735158280224 140735158270000 140676831291362 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 22288 7662 6726 1052 0 858 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 89152 [startup+0.300333 s] /proc/loadavg: 1.14 1.05 1.01 2/55 22615 /proc/meminfo: memFree=909388/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=101864 CPUtime=0.3 /proc/22615/stat : 22615 (aptitude) R 22614 22615 17863 34816 17863 4202496 11709 1709 0 0 27 2 0 1 20 0 1 0 28715814 104308736 10756 18446744073709551615 140676836438016 140676840745368 140735158280224 140735158269656 140676793978399 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 25466 10756 6746 1052 0 4036 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 101864 [startup+0.700206 s] /proc/loadavg: 1.14 1.05 1.01 2/55 22615 /proc/meminfo: memFree=909388/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=113868 CPUtime=0.69 /proc/22615/stat : 22615 (aptitude) R 22614 22615 17863 34816 17863 4202496 13632 1709 0 0 64 4 0 1 20 0 2 0 28715814 116600832 12003 18446744073709551615 140676836438016 140676840745368 140735158280224 140735158271496 140676839007822 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 28467 12003 6964 1052 0 7037 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 113868 [startup+1.50024 s] /proc/loadavg: 1.14 1.05 1.01 2/57 22620 /proc/meminfo: memFree=888920/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=115240 CPUtime=1.49 /proc/22615/stat : 22615 (aptitude) R 22614 22615 17863 34816 17863 4202496 13835 1709 0 0 144 4 0 1 20 0 2 0 28715814 118005760 12205 18446744073709551615 140676836438016 140676840745368 140735158280224 140735158266928 140676831265776 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 28810 12205 7033 1052 0 7380 0 [pid=22615/tid=22620] ppid=22614 vsize=115240 CPUtime=0.01 /proc/22615/task/22620/stat : 22620 (aptitude) S 22614 22615 17863 34816 17863 4202560 4 1709 0 0 0 0 0 1 20 0 2 0 28715859 118005760 12205 18446744073709551615 140676836438016 140676840745368 140735158280224 140676739844096 140676805182164 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 115240 [startup+3.10026 s] /proc/loadavg: 1.12 1.05 1.01 2/57 22620 /proc/meminfo: memFree=865360/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=142076 CPUtime=3.08 /proc/22615/stat : 22615 (aptitude) R 22614 22615 17863 34816 17863 4202496 20890 1709 0 0 300 7 0 1 20 0 2 0 28715814 145485824 19257 18446744073709551615 140676836438016 140676840745368 140735158280224 140735158268088 140676831292327 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 35519 19257 7140 1052 0 14082 0 [pid=22615/tid=22620] ppid=22614 vsize=142076 CPUtime=1.25 /proc/22615/task/22620/stat : 22620 (aptitude) S 22614 22615 17863 34816 17863 4202560 6952 1709 0 0 121 3 0 1 20 0 2 0 28715859 145485824 19257 18446744073709551615 140676836438016 140676840745368 140735158280224 140676739844096 140676805182164 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.08 Current children cumulated vsize (KiB) 142076 Solver just ended. Dumping a history of the last processes samples [startup+3.2011 s] /proc/loadavg: 1.12 1.05 1.01 2/57 22620 /proc/meminfo: memFree=865360/1022884 swapFree=0/0 [pid=22615] ppid=22614 vsize=95524 CPUtime=3.18 /proc/22615/stat : 22615 (aptitude) R 22614 22615 17863 34816 17863 4202496 20910 1709 0 0 309 8 0 1 20 0 1 0 28715814 97816576 3559 18446744073709551615 140676836438016 140676840745368 140735158280224 140735158279784 140676794303978 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22615/statm: 23881 3559 1477 1052 0 8137 0 Current children cumulated CPU time (s) 3.18 Current children cumulated vsize (KiB) 95524 Child status: 0 Real time (s): 3.20421 CPU time (s): 3.1962 CPU user time (s): 3.09219 CPU system time (s): 0.104006 CPU usage (%): 99.75 Max. virtual memory (cumulated for all children) (KiB): 142076 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.09219 system time used= 0.104006 maximum resident set size= 77072 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 22675 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= 56 involuntary context switches= 103 runsolver used 0.008 second user time and 0.012 second system time The end