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/138.runsolver.aptitude aptitude -s -y --without-recommends remove psutils libtiff4 libxtst6 libkrb53 synaptic 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.00 1.01 1.00 2/55 22474 /proc/meminfo: memFree=903976/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=3152 CPUtime=0 /proc/22474/stat : 22474 (runsolver) R 22473 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28696267 3227648 32 18446744073709551615 134512640 134586868 4286832656 4286830704 4152124464 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.120992 s] /proc/loadavg: 1.00 1.01 1.00 2/55 22474 /proc/meminfo: memFree=903976/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=89152 CPUtime=0.1 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 7766 1707 0 0 8 2 0 0 20 0 1 0 28696267 91291648 7567 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081957984 139927236700052 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 22288 7567 6656 1052 0 858 0 Current children cumulated CPU time (s) 0.1 Current children cumulated vsize (KiB) 89152 [startup+0.200298 s] /proc/loadavg: 1.00 1.01 1.00 2/55 22474 /proc/meminfo: memFree=903976/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=89152 CPUtime=0.18 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 7869 1707 0 0 16 2 0 0 20 0 1 0 28696267 91291648 7662 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081956496 139927236708322 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 22288 7662 6726 1052 0 858 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 89152 [startup+0.300289 s] /proc/loadavg: 1.00 1.01 1.00 2/55 22474 /proc/meminfo: memFree=903976/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=101864 CPUtime=0.29 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 11708 1707 0 0 25 4 0 0 20 0 1 0 28696267 104308736 10756 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081956152 139927210611056 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 25466 10756 6746 1052 0 4036 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 101864 [startup+0.700204 s] /proc/loadavg: 1.00 1.01 1.00 2/55 22474 /proc/meminfo: memFree=903976/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=113868 CPUtime=0.68 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 13628 1707 0 0 62 6 0 0 20 0 2 0 28696267 116600832 12000 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081957992 139927200007379 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 28467 12000 6961 1052 0 7037 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 113868 [startup+1.50025 s] /proc/loadavg: 1.00 1.01 1.00 2/57 22479 /proc/meminfo: memFree=883508/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=115240 CPUtime=1.47 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 13835 1707 0 0 141 6 0 0 20 0 2 0 28696267 118005760 12205 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081953424 139927243264975 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 28810 12205 7033 1052 0 7380 0 [pid=22474/tid=22479] ppid=22473 vsize=115240 CPUtime=0 /proc/22474/task/22479/stat : 22479 (aptitude) S 22473 22474 17863 34816 17863 4202560 4 1707 0 0 0 0 0 0 20 0 2 0 28696312 118005760 12205 18446744073709551615 139927241854976 139927246162328 140735081966720 139927145261056 139927210599124 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.47 Current children cumulated vsize (KiB) 115240 [startup+3.10026 s] /proc/loadavg: 1.00 1.01 1.00 2/57 22479 /proc/meminfo: memFree=861808/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=163084 CPUtime=3.07 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 26130 1707 0 0 296 11 0 0 20 0 2 0 28696267 166998016 24497 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081956856 139927244481179 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 40771 24497 7139 1052 0 19334 0 [pid=22474/tid=22479] ppid=22473 vsize=163084 CPUtime=1.3 /proc/22474/task/22479/stat : 22479 (aptitude) S 22473 22474 17863 34816 17863 4202560 12096 1707 0 0 126 4 0 0 20 0 2 0 28696312 166998016 24497 18446744073709551615 139927241854976 139927246162328 140735081966720 139927145261056 139927210599124 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.07 Current children cumulated vsize (KiB) 163084 Solver just ended. Dumping a history of the last processes samples [startup+3.20034 s] /proc/loadavg: 1.00 1.01 1.00 2/57 22479 /proc/meminfo: memFree=861808/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=163108 CPUtime=3.17 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 26149 1707 0 0 306 11 0 0 20 0 2 0 28696267 167022592 24516 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081956808 139927236697989 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 40777 24516 7140 1052 0 19340 0 [pid=22474/tid=22479] ppid=22473 vsize=163108 CPUtime=1.3 /proc/22474/task/22479/stat : 22479 (aptitude) S 22473 22474 17863 34816 17863 4202560 12096 1707 0 0 126 4 0 0 20 0 2 0 28696312 167022592 24516 18446744073709551615 139927241854976 139927246162328 140735081966720 139927145261056 139927210599124 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.17 Current children cumulated vsize (KiB) 163108 [startup+3.30032 s] /proc/loadavg: 1.00 1.01 1.00 2/57 22479 /proc/meminfo: memFree=834280/1022884 swapFree=0/0 [pid=22474] ppid=22473 vsize=163108 CPUtime=3.27 /proc/22474/stat : 22474 (aptitude) R 22473 22474 17863 34816 17863 4202496 26149 1707 0 0 316 11 0 0 20 0 2 0 28696267 167022592 24516 18446744073709551615 139927241854976 139927246162328 140735081966720 140735081954584 139927242683952 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22474/statm: 40777 24516 7140 1052 0 19340 0 [pid=22474/tid=22479] ppid=22473 vsize=163108 CPUtime=1.3 /proc/22474/task/22479/stat : 22479 (aptitude) S 22473 22474 17863 34816 17863 4202560 12096 1707 0 0 126 4 0 0 20 0 2 0 28696312 167022592 24516 18446744073709551615 139927241854976 139927246162328 140735081966720 139927145261056 139927210599124 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.27 Current children cumulated vsize (KiB) 163108 Child status: 0 Real time (s): 3.39859 CPU time (s): 3.38421 CPU user time (s): 3.2442 CPU system time (s): 0.140008 CPU usage (%): 99.5768 Max. virtual memory (cumulated for all children) (KiB): 163108 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.2442 system time used= 0.140008 maximum resident set size= 98108 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 27932 page faults= 0 swaps= 0 block input operations= 0 block output operations= 56 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 58 involuntary context switches= 103 runsolver used 0.004 second user time and 0.016001 second system time The end