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/140.runsolver.aptitude aptitude -s -y --without-recommends remove libstartup-notification0 libxi6 libgtkhtml3.2-11 kspaceduel libice-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.98 0.85 0.45 2/55 20624 /proc/meminfo: memFree=241204/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=3152 CPUtime=0 /proc/20624/stat : 20624 (runsolver) R 20623 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28221034 3227648 32 18446744073709551615 134512640 134586868 4292681168 4292679216 4151542832 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.178625 s] /proc/loadavg: 0.98 0.85 0.45 2/55 20624 /proc/meminfo: memFree=241204/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=86100 CPUtime=0.17 /proc/20624/stat : 20624 (aptitude) R 20623 20624 17863 34816 17863 4202496 7109 1708 0 0 16 0 0 1 20 0 1 0 28221034 88166400 6899 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555774712 140129156938988 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 21525 6899 4712 1052 0 2116 0 Current children cumulated CPU time (s) 0.17 Current children cumulated vsize (KiB) 86100 [startup+0.200291 s] /proc/loadavg: 0.98 0.85 0.45 2/55 20624 /proc/meminfo: memFree=241204/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=87044 CPUtime=0.19 /proc/20624/stat : 20624 (aptitude) R 20623 20624 17863 34816 17863 4202496 7518 1708 0 0 18 0 0 1 20 0 1 0 28221034 89133056 7140 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555773112 140129165008753 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 21761 7140 4721 1052 0 2352 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 87044 [startup+0.30028 s] /proc/loadavg: 0.98 0.85 0.45 2/55 20624 /proc/meminfo: memFree=241204/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=90632 CPUtime=0.29 /proc/20624/stat : 20624 (aptitude) R 20623 20624 17863 34816 17863 4202496 8629 1708 0 0 28 0 0 1 20 0 1 0 28221034 92807168 7673 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555773032 140129156765472 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 22658 7673 4725 1052 0 3249 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 90632 [startup+0.700219 s] /proc/loadavg: 0.98 0.85 0.45 2/55 20624 /proc/meminfo: memFree=241204/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=100468 CPUtime=0.69 /proc/20624/stat : 20624 (aptitude) R 20623 20624 17863 34816 17863 4202496 10065 1708 0 0 67 1 0 1 20 0 2 0 28221034 102879232 8694 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555773384 140129128389612 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 25117 8694 4977 1052 0 5708 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 100468 [startup+1.50026 s] /proc/loadavg: 0.99 0.85 0.45 2/57 20629 /proc/meminfo: memFree=226192/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=114864 CPUtime=1.48 /proc/20624/stat : 20624 (aptitude) S 20623 20624 17863 34816 17863 4202496 13772 1708 0 0 145 2 0 1 20 0 2 0 28221034 117620736 12400 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555775360 140129130829387 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 28716 12400 5066 1052 0 9307 0 [pid=20624/tid=20629] ppid=20623 vsize=114864 CPUtime=0.37 /proc/20624/task/20629/stat : 20629 (aptitude) R 20623 20624 17863 34816 17863 4202560 3614 1708 0 0 35 1 0 1 20 0 2 0 28221064 117620736 12400 18446744073709551615 140129162084352 140129166391704 140736555783600 140129078656696 140129164937385 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) 114864 Solver just ended. Dumping a history of the last processes samples [startup+1.60034 s] /proc/loadavg: 0.99 0.85 0.45 2/57 20629 /proc/meminfo: memFree=226192/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=117108 CPUtime=1.59 /proc/20624/stat : 20624 (aptitude) S 20623 20624 17863 34816 17863 4202496 14338 1708 0 0 155 3 0 1 20 0 2 0 28221034 119918592 12966 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555775360 140129130829387 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 29277 12966 5066 1052 0 9868 0 [pid=20624/tid=20629] ppid=20623 vsize=117108 CPUtime=0.48 /proc/20624/task/20629/stat : 20629 (aptitude) R 20623 20624 17863 34816 17863 4202560 4180 1708 0 0 45 2 0 1 20 0 2 0 28221064 119918592 12966 18446744073709551615 140129162084352 140129166391704 140736555783600 140129078653992 140129119587892 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.59 Current children cumulated vsize (KiB) 117108 [startup+2.40025 s] /proc/loadavg: 0.99 0.85 0.45 2/57 20629 /proc/meminfo: memFree=203252/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=124500 CPUtime=2.39 /proc/20624/stat : 20624 (aptitude) S 20623 20624 17863 34816 17863 4202496 16179 1708 0 0 234 4 0 1 20 0 2 0 28221034 127488000 14807 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555775360 140129130829387 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 31125 14807 5072 1052 0 11716 0 [pid=20624/tid=20629] ppid=20623 vsize=124500 CPUtime=1.27 /proc/20624/task/20629/stat : 20629 (aptitude) R 20623 20624 17863 34816 17863 4202560 6020 1708 0 0 124 2 0 1 20 0 2 0 28221064 127488000 14807 18446744073709551615 140129162084352 140129166391704 140736555783600 140129078654488 140129164911153 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.39 Current children cumulated vsize (KiB) 124500 [startup+2.60027 s] /proc/loadavg: 0.99 0.85 0.45 2/57 20629 /proc/meminfo: memFree=203252/1022884 swapFree=0/0 [pid=20624] ppid=20623 vsize=125452 CPUtime=2.58 /proc/20624/stat : 20624 (aptitude) R 20623 20624 17863 34816 17863 4202496 16488 1708 0 0 253 4 0 1 20 0 2 0 28221034 128462848 15114 18446744073709551615 140129162084352 140129166391704 140736555783600 140736555775672 140129157001328 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20624/statm: 31363 15114 5121 1052 0 11947 0 [pid=20624/tid=20629] ppid=20623 vsize=125452 CPUtime=1.27 /proc/20624/task/20629/stat : 20629 (aptitude) S 20623 20624 17863 34816 17863 4202560 6117 1708 0 0 124 2 0 1 20 0 2 0 28221064 128462848 15114 18446744073709551615 140129162084352 140129166391704 140736555783600 140129078663168 140129130828500 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.58 Current children cumulated vsize (KiB) 125452 Child status: 0 Real time (s): 2.62743 CPU time (s): 2.62016 CPU user time (s): 2.54816 CPU system time (s): 0.072004 CPU usage (%): 99.7234 Max. virtual memory (cumulated for all children) (KiB): 125452 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.54816 system time used= 0.072004 maximum resident set size= 60500 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 18272 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= 59 involuntary context switches= 93 runsolver used 0 second user time and 0.016001 second system time The end