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/148.runsolver.aptitude aptitude -s -y --without-recommends remove kdeartwork-theme-icon menu pkg-config epiphany-browser klettres-data 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.15 1.04 1.01 2/55 22544 /proc/meminfo: memFree=903348/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=3152 CPUtime=0 /proc/22544/stat : 22544 (runsolver) R 22543 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28700141 3227648 32 18446744073709551615 134512640 134586868 4293230112 4293228160 4151370800 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.114588 s] /proc/loadavg: 1.15 1.04 1.01 2/55 22544 /proc/meminfo: memFree=903348/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=89152 CPUtime=0.11 /proc/22544/stat : 22544 (aptitude) R 22543 22544 17863 34816 17863 4202496 7767 1708 0 0 7 3 1 0 20 0 1 0 28700141 91291648 7567 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263416672 140333754002177 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 22288 7567 6656 1052 0 858 0 Current children cumulated CPU time (s) 0.11 Current children cumulated vsize (KiB) 89152 [startup+0.200269 s] /proc/loadavg: 1.15 1.04 1.01 2/55 22544 /proc/meminfo: memFree=903348/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=96860 CPUtime=0.19 /proc/22544/stat : 22544 (aptitude) R 22543 22544 17863 34816 17863 4202496 9802 1708 0 0 15 3 1 0 20 0 1 0 28700141 99184640 9594 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263417056 140333761327680 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 24215 9594 6731 1052 0 2785 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 96860 [startup+0.300254 s] /proc/loadavg: 1.15 1.04 1.01 2/55 22544 /proc/meminfo: memFree=903348/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=101864 CPUtime=0.3 /proc/22544/stat : 22544 (aptitude) R 22543 22544 17863 34816 17863 4202496 11709 1708 0 0 25 4 1 0 20 0 1 0 28700141 104308736 10756 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263414840 140333754120083 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 25466 10756 6746 1052 0 4036 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 101864 [startup+0.700191 s] /proc/loadavg: 1.15 1.04 1.01 2/55 22544 /proc/meminfo: memFree=903348/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=113868 CPUtime=0.7 /proc/22544/stat : 22544 (aptitude) R 22543 22544 17863 34816 17863 4202496 13698 1708 0 0 64 5 1 0 20 0 2 0 28700141 116600832 12068 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263415760 140333716687175 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 28467 12068 6997 1052 0 7037 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 113868 [startup+1.50023 s] /proc/loadavg: 1.15 1.04 1.01 2/57 22549 /proc/meminfo: memFree=882632/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=115240 CPUtime=1.48 /proc/22544/stat : 22544 (aptitude) R 22543 22544 17863 34816 17863 4202496 13836 1708 0 0 142 5 1 0 20 0 2 0 28700141 118005760 12206 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263412112 140333754000505 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 28810 12206 7034 1052 0 7380 0 [pid=22544/tid=22549] ppid=22543 vsize=115240 CPUtime=0.01 /proc/22544/task/22549/stat : 22549 (aptitude) S 22543 22544 17863 34816 17863 4202560 4 1708 0 0 0 0 1 0 20 0 2 0 28700181 118005760 12206 18446744073709551615 140333759176704 140333763484056 140733263425408 140333662582784 140333727920852 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.48 Current children cumulated vsize (KiB) 115240 Solver just ended. Dumping a history of the last processes samples [startup+1.60032 s] /proc/loadavg: 1.15 1.04 1.01 2/57 22549 /proc/meminfo: memFree=882632/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=115240 CPUtime=1.58 /proc/22544/stat : 22544 (aptitude) R 22543 22544 17863 34816 17863 4202496 13836 1708 0 0 152 5 1 0 20 0 2 0 28700141 118005760 12206 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263412112 140333762231570 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 28810 12206 7034 1052 0 7380 0 [pid=22544/tid=22549] ppid=22543 vsize=115240 CPUtime=0.01 /proc/22544/task/22549/stat : 22549 (aptitude) S 22543 22544 17863 34816 17863 4202560 4 1708 0 0 0 0 1 0 20 0 2 0 28700181 118005760 12206 18446744073709551615 140333759176704 140333763484056 140733263425408 140333662582784 140333727920852 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.58 Current children cumulated vsize (KiB) 115240 [startup+2.40024 s] /proc/loadavg: 1.15 1.04 1.01 2/57 22549 /proc/meminfo: memFree=867256/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=131616 CPUtime=2.38 /proc/22544/stat : 22544 (aptitude) S 22543 22544 17863 34816 17863 4202496 18219 1708 0 0 231 6 1 0 20 0 2 0 28700141 134774784 16588 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263417168 140333727921739 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 32904 16588 7091 1052 0 11474 0 [pid=22544/tid=22549] ppid=22543 vsize=131616 CPUtime=0.76 /proc/22544/task/22549/stat : 22549 (aptitude) R 22543 22544 17863 34816 17863 4202560 4382 1708 0 0 74 1 1 0 20 0 2 0 28700181 134774784 16588 18446744073709551615 140333759176704 140333763484056 140733263425408 140333662578280 140333725084704 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.38 Current children cumulated vsize (KiB) 131616 [startup+2.60048 s] /proc/loadavg: 1.15 1.04 1.01 2/57 22549 /proc/meminfo: memFree=867256/1022884 swapFree=0/0 [pid=22544] ppid=22543 vsize=89172 CPUtime=2.58 /proc/22544/stat : 22544 (aptitude) R 22543 22544 17863 34816 17863 4202496 18594 1708 0 0 249 8 1 0 20 0 1 0 28700141 91312128 4008 18446744073709551615 140333759176704 140333763484056 140733263425408 140733263424968 140333717042666 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22544/statm: 22293 4008 1470 1052 0 6549 0 Current children cumulated CPU time (s) 2.58 Current children cumulated vsize (KiB) 89172 Child status: 0 Real time (s): 2.60394 CPU time (s): 2.59616 CPU user time (s): 2.50416 CPU system time (s): 0.092005 CPU usage (%): 99.7014 Max. virtual memory (cumulated for all children) (KiB): 132832 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.50416 system time used= 0.092005 maximum resident set size= 67808 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 20358 page faults= 0 swaps= 0 block input operations= 0 block output operations= 8 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 55 involuntary context switches= 95 runsolver used 0.016001 second user time and 0 second system time The end