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/109.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove ktuberling libedata-cal5 libattr1 artsbuilder kdemultimedia-kfile-plugins 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 0.99 0.91 2/55 28060 /proc/meminfo: memFree=305532/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=3152 CPUtime=0 /proc/28060/stat : 28060 (runsolver) R 28059 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28515520 3227648 32 18446744073709551615 134512640 134586868 4290840528 4290838576 4151837744 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.149795 s] /proc/loadavg: 1.00 0.99 0.91 2/55 28060 /proc/meminfo: memFree=305532/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=34168 CPUtime=0.16 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 2021 7194 0 0 8 2 4 2 20 0 1 0 28515520 34988032 1839 18446744073709551615 4194304 5184042 140734272036080 140734272033272 140479015476291 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 8542 1839 729 242 0 1104 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 34168 [startup+0.200267 s] /proc/loadavg: 1.00 0.99 0.91 2/55 28060 /proc/meminfo: memFree=305532/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=35360 CPUtime=0.22 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 2318 7194 0 0 12 4 4 2 20 0 1 0 28515520 36208640 2135 18446744073709551615 4194304 5184042 140734272036080 140734272033272 140478998986271 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 8840 2135 729 242 0 1402 0 Current children cumulated CPU time (s) 0.22 Current children cumulated vsize (KiB) 35360 [startup+0.300259 s] /proc/loadavg: 1.00 0.99 0.91 2/55 28060 /proc/meminfo: memFree=305532/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=46976 CPUtime=0.31 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 5309 7194 0 0 20 5 4 2 20 0 1 0 28515520 48103424 5118 18446744073709551615 4194304 5184042 140734272036080 140734272030568 140479015068587 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 11744 5118 835 242 0 4306 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 46976 [startup+0.700207 s] /proc/loadavg: 1.00 0.99 0.91 2/55 28060 /proc/meminfo: memFree=305532/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=59760 CPUtime=0.7 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 8568 7194 0 0 58 6 4 2 20 0 1 0 28515520 61194240 8344 18446744073709551615 4194304 5184042 140734272036080 140734272033384 140479015290435 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 14940 8344 842 242 0 7502 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 59760 [startup+1.50021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28080 /proc/meminfo: memFree=273788/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=62172 CPUtime=1.5 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 9300 7194 0 0 138 6 4 2 20 0 1 0 28515520 63664128 8882 18446744073709551615 4194304 5184042 140734272036080 140734272033384 140479015303874 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 15543 8882 842 242 0 8105 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 62172 [startup+3.10021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28080 /proc/meminfo: memFree=268704/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=73652 CPUtime=3.1 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 11706 7194 0 0 297 7 4 2 20 0 1 0 28515520 75419648 11031 18446744073709551615 4194304 5184042 140734272036080 140734272033336 140479015309264 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 18413 11031 842 242 0 10975 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 73652 Solver just ended. Dumping a history of the last processes samples [startup+3.20028 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28080 /proc/meminfo: memFree=268704/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=71600 CPUtime=3.2 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 11997 7194 0 0 307 7 4 2 20 0 1 0 28515520 73318400 10809 18446744073709551615 4194304 5184042 140734272036080 140734272033528 140478998946584 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 17900 10809 842 242 0 10462 0 Current children cumulated CPU time (s) 3.2 Current children cumulated vsize (KiB) 71600 [startup+4.00021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28080 /proc/meminfo: memFree=265108/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=71600 CPUtime=3.99 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 12130 7194 0 0 386 7 4 2 20 0 1 0 28515520 73318400 10942 18446744073709551615 4194304 5184042 140734272036080 140734272030344 140479007371933 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 17900 10942 842 242 0 10462 0 Current children cumulated CPU time (s) 3.99 Current children cumulated vsize (KiB) 71600 [startup+4.40021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28080 /proc/meminfo: memFree=264860/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=71600 CPUtime=4.39 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 12198 7194 0 0 426 7 4 2 20 0 1 0 28515520 73318400 11010 18446744073709551615 4194304 5184042 140734272036080 140734272030344 140478999678755 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 17900 11010 842 242 0 10462 0 Current children cumulated CPU time (s) 4.39 Current children cumulated vsize (KiB) 71600 [startup+4.6002 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28080 /proc/meminfo: memFree=264860/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=71600 CPUtime=4.59 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 12225 7194 0 0 446 7 4 2 20 0 1 0 28515520 73318400 11037 18446744073709551615 4194304 5184042 140734272036080 140734272030296 140479015298751 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 17900 11037 842 242 0 10462 0 Current children cumulated CPU time (s) 4.59 Current children cumulated vsize (KiB) 71600 [startup+4.70081 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28080 /proc/meminfo: memFree=264860/1022884 swapFree=0/0 [pid=28060] ppid=28059 vsize=43360 CPUtime=4.69 /proc/28060/stat : 28060 (cupt) R 28059 28060 17872 34816 17872 4202496 12241 7194 0 0 455 8 4 2 20 0 1 0 28515520 44400640 3242 18446744073709551615 4194304 5184042 140734272036080 140734272035560 140478999311850 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/28060/statm: 10840 3242 853 242 0 3402 0 Current children cumulated CPU time (s) 4.69 Current children cumulated vsize (KiB) 43360 Child status: 1 Real time (s): 4.70391 CPU time (s): 4.71629 CPU user time (s): 4.60429 CPU system time (s): 0.112007 CPU usage (%): 100.263 Max. virtual memory (cumulated for all children) (KiB): 73652 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.60429 system time used= 0.112007 maximum resident set size= 45224 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 19454 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= 52 involuntary context switches= 83 runsolver used 0.004 second user time and 0.020001 second system time The end