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/89.runsolver.aptitude aptitude -s -y --without-recommends install libmail-imapclient-perl smarty jigdo-file rpm cl-binary-types 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.37 2/55 20016 /proc/meminfo: memFree=243696/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=3152 CPUtime=0 /proc/20016/stat : 20016 (runsolver) R 20015 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28206062 3227648 33 18446744073709551615 134512640 134586868 4290269600 4290267648 4152136752 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.160668 s] /proc/loadavg: 0.99 0.76 0.37 2/55 20016 /proc/meminfo: memFree=243696/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=86100 CPUtime=0.15 /proc/20016/stat : 20016 (aptitude) R 20015 20016 17863 34816 17863 4202496 7107 1709 0 0 14 1 0 0 20 0 1 0 28206062 88166400 6898 18446744073709551615 140335815008256 140335819315608 140733724606480 140733724598128 140335783751168 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 21525 6898 4711 1052 0 2116 0 Current children cumulated CPU time (s) 0.15 Current children cumulated vsize (KiB) 86100 [startup+0.200301 s] /proc/loadavg: 0.99 0.76 0.37 2/55 20016 /proc/meminfo: memFree=243696/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=89348 CPUtime=0.18 /proc/20016/stat : 20016 (aptitude) R 20015 20016 17863 34816 17863 4202496 8316 1709 0 0 17 1 0 0 20 0 1 0 28206062 91492352 7361 18446744073709551615 140335815008256 140335819315608 140733724606480 140733724595992 140335817947017 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 22337 7361 4721 1052 0 2928 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 89348 [startup+0.300287 s] /proc/loadavg: 0.99 0.76 0.37 2/55 20016 /proc/meminfo: memFree=243696/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=96684 CPUtime=0.28 /proc/20016/stat : 20016 (aptitude) R 20015 20016 17863 34816 17863 4202496 9022 1709 0 0 26 2 0 0 20 0 2 0 28206062 99004416 7655 18446744073709551615 140335815008256 140335819315608 140733724606480 140733724602264 140335810408581 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 24171 7655 4890 1052 0 4762 0 Current children cumulated CPU time (s) 0.28 Current children cumulated vsize (KiB) 96684 [startup+0.70021 s] /proc/loadavg: 0.99 0.76 0.37 2/55 20016 /proc/meminfo: memFree=243696/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=100652 CPUtime=0.68 /proc/20016/stat : 20016 (aptitude) R 20015 20016 17863 34816 17863 4202496 10130 1709 0 0 65 3 0 0 20 0 2 0 28206062 103067648 8759 18446744073709551615 140335815008256 140335819315608 140733724606480 140733724596776 140335817998385 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 25163 8759 4994 1052 0 5754 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 100652 Solver just ended. Dumping a history of the last processes samples [startup+0.800275 s] /proc/loadavg: 0.99 0.76 0.37 2/55 20016 /proc/meminfo: memFree=243696/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=101552 CPUtime=0.78 /proc/20016/stat : 20016 (aptitude) R 20015 20016 17863 34816 17863 4202496 10211 1709 0 0 75 3 0 0 20 0 2 0 28206062 103989248 8840 18446744073709551615 140335815008256 140335819315608 140733724606480 140733724598136 140335810408677 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 25388 8840 5016 1052 0 5979 0 Current children cumulated CPU time (s) 0.78 Current children cumulated vsize (KiB) 101552 [startup+1.20326 s] /proc/loadavg: 0.99 0.76 0.37 2/57 20022 /proc/meminfo: memFree=227924/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=101552 CPUtime=1.18 /proc/20016/stat : 20016 (aptitude) S 20015 20016 17863 34816 17863 4202496 10965 2211 0 0 114 4 0 0 20 0 2 0 28206062 103989248 8923 18446744073709551615 140335815008256 140335819315608 140733724606480 140733724598240 140335783753291 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 25388 8923 5095 1052 0 5979 0 [pid=20016/tid=20021] ppid=20015 vsize=101552 CPUtime=0.02 /proc/20016/task/20021/stat : 20021 (aptitude) R 20015 20016 17863 34816 17863 4202560 285 2211 0 0 2 0 0 0 20 0 2 0 28206091 103989248 8923 18446744073709551615 140335815008256 140335819315608 140733724606480 140335731586952 140335817219162 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) 101552 [startup+1.40025 s] /proc/loadavg: 0.99 0.76 0.37 2/57 20022 /proc/meminfo: memFree=227924/1022884 swapFree=0/0 [pid=20016] ppid=20015 vsize=106992 CPUtime=1.37 /proc/20016/stat : 20016 (aptitude) R 20015 20016 17863 34816 17863 4202496 14046 2211 0 0 132 5 0 0 20 0 2 0 28206062 109559808 10520 18446744073709551615 140335815008256 140335819315608 140733724606480 140733724594344 140335817214631 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20016/statm: 26748 10520 5151 1052 0 7332 0 [pid=20016/tid=20021] ppid=20015 vsize=106992 CPUtime=0.15 /proc/20016/task/20021/stat : 20021 (aptitude) S 20015 20016 17863 34816 17863 4202560 1945 2211 0 0 14 1 0 0 20 0 2 0 28206091 109559808 10520 18446744073709551615 140335815008256 140335819315608 140733724606480 140335731587072 140335783752404 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) 106992 Child status: 0 Real time (s): 1.47441 CPU time (s): 1.47209 CPU user time (s): 1.38409 CPU system time (s): 0.088005 CPU usage (%): 99.8424 Max. virtual memory (cumulated for all children) (KiB): 106992 getrusage(RUSAGE_CHILDREN,...) data: user time used= 1.38409 system time used= 0.088005 maximum resident set size= 42120 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 16989 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= 75 runsolver used 0 second user time and 0.012 second system time The end