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/154.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove libkleopatra0a kfind libsasl2 klines libtasn1-2 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.07 0.89 0.48 2/55 25180 /proc/meminfo: memFree=281684/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=3152 CPUtime=0 /proc/25180/stat : 25180 (runsolver) R 25179 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28219895 3227648 32 18446744073709551615 134512640 134586868 4288209200 4288207248 4151948336 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.194623 s] /proc/loadavg: 1.07 0.89 0.48 2/55 25180 /proc/meminfo: memFree=281684/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=39052 CPUtime=0.21 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 3251 7206 0 0 12 2 5 2 20 0 1 0 28219895 39989248 3065 18446744073709551615 4194304 5184042 140735571408960 140735571404904 140117649423584 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 9763 3065 758 242 0 2325 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 39052 [startup+0.200279 s] /proc/loadavg: 1.07 0.89 0.48 2/55 25180 /proc/meminfo: memFree=281684/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=39580 CPUtime=0.21 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 3445 7206 0 0 12 2 5 2 20 0 1 0 28219895 40529920 3256 18446744073709551615 4194304 5184042 140735571408960 140735571404952 140117649425141 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 9895 3256 792 242 0 2457 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 39580 [startup+0.300274 s] /proc/loadavg: 1.07 0.89 0.48 2/55 25180 /proc/meminfo: memFree=281684/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=51296 CPUtime=0.31 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 6383 7206 0 0 22 2 5 2 20 0 1 0 28219895 52527104 6193 18446744073709551615 4194304 5184042 140735571408960 140735571406152 140117648786291 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 12824 6193 842 242 0 5386 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 51296 [startup+0.700196 s] /proc/loadavg: 1.07 0.89 0.48 2/55 25180 /proc/meminfo: memFree=281684/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=59204 CPUtime=0.72 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 8499 7206 0 0 62 3 5 2 20 0 1 0 28219895 60624896 8091 18446744073709551615 4194304 5184042 140735571408960 140735571406264 140117632426508 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 14801 8091 842 242 0 7363 0 Current children cumulated CPU time (s) 0.72 Current children cumulated vsize (KiB) 59204 [startup+1.50022 s] /proc/loadavg: 1.07 0.89 0.48 2/56 25200 /proc/meminfo: memFree=249568/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=62812 CPUtime=1.52 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 9673 7206 0 0 141 4 5 2 20 0 1 0 28219895 64319488 9008 18446744073709551615 4194304 5184042 140735571408960 140735571403224 140117632428136 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 15703 9008 842 242 0 8265 0 Current children cumulated CPU time (s) 1.52 Current children cumulated vsize (KiB) 62812 Solver just ended. Dumping a history of the last processes samples [startup+1.6003 s] /proc/loadavg: 1.07 0.89 0.48 2/56 25200 /proc/meminfo: memFree=249568/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=62812 CPUtime=1.62 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 9697 7206 0 0 151 4 5 2 20 0 1 0 28219895 64319488 9032 18446744073709551615 4194304 5184042 140735571408960 140735571403256 140117632428112 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 15703 9032 842 242 0 8265 0 Current children cumulated CPU time (s) 1.62 Current children cumulated vsize (KiB) 62812 [startup+2.00022 s] /proc/loadavg: 1.07 0.89 0.48 2/56 25200 /proc/meminfo: memFree=249568/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=64860 CPUtime=2.02 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 10307 7206 0 0 191 4 5 2 20 0 1 0 28219895 66416640 9129 18446744073709551615 4194304 5184042 140735571408960 140735571406408 140117648770131 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 16215 9129 842 242 0 8777 0 Current children cumulated CPU time (s) 2.02 Current children cumulated vsize (KiB) 64860 [startup+2.40021 s] /proc/loadavg: 1.06 0.89 0.48 2/56 25200 /proc/meminfo: memFree=247956/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=64860 CPUtime=2.41 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 10415 7206 0 0 230 4 5 2 20 0 1 0 28219895 66416640 9237 18446744073709551615 4194304 5184042 140735571408960 140735571402376 140117648770048 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 16215 9237 842 242 0 8777 0 Current children cumulated CPU time (s) 2.41 Current children cumulated vsize (KiB) 64860 [startup+2.50054 s] /proc/loadavg: 1.06 0.89 0.48 2/56 25200 /proc/meminfo: memFree=247956/1022884 swapFree=0/0 [pid=25180] ppid=25179 vsize=60504 CPUtime=2.51 /proc/25180/stat : 25180 (cupt) R 25179 25180 17872 34816 17872 4202496 10434 7206 0 0 240 4 5 2 20 0 1 0 28219895 61956096 7518 18446744073709551615 4194304 5184042 140735571408960 140735571408440 140117632792042 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/25180/statm: 15126 7518 853 242 0 7688 0 Current children cumulated CPU time (s) 2.51 Current children cumulated vsize (KiB) 60504 Child status: 1 Real time (s): 2.50802 CPU time (s): 2.52816 CPU user time (s): 2.46015 CPU system time (s): 0.068004 CPU usage (%): 100.803 Max. virtual memory (cumulated for all children) (KiB): 64860 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.46015 system time used= 0.068004 maximum resident set size= 38484 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 17659 page faults= 0 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 52 involuntary context switches= 50 runsolver used 0 second user time and 0.016001 second system time The end