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-squeeze-sid/logs/118.runsolver.aptitude aptitude -s -y --without-recommends remove kdesdk-misc libsvga1 console-data python xscreensaver 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.08 1.07 1.07 2/55 26706 /proc/meminfo: memFree=833260/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=3152 CPUtime=0 /proc/26706/stat : 26706 (runsolver) R 26705 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 29914523 3227648 32 18446744073709551615 134512640 134586868 4289784752 4289782800 4151243824 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.169621 s] /proc/loadavg: 1.08 1.07 1.07 2/55 26706 /proc/meminfo: memFree=833260/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=103288 CPUtime=0.16 /proc/26706/stat : 26706 (aptitude) R 26705 26706 17863 34816 17863 4202496 11292 1706 0 0 13 2 0 1 20 0 1 0 29914523 105766912 11091 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479506272 140659900249971 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 25822 11091 9879 1052 0 1159 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 103288 [startup+0.200302 s] /proc/loadavg: 1.08 1.07 1.07 2/55 26706 /proc/meminfo: memFree=833260/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=103288 CPUtime=0.19 /proc/26706/stat : 26706 (aptitude) R 26705 26706 17863 34816 17863 4202496 11302 1706 0 0 16 2 0 1 20 0 1 0 29914523 105766912 11101 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479506272 140659900249859 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 25822 11101 9889 1052 0 1159 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 103288 [startup+0.300267 s] /proc/loadavg: 1.08 1.07 1.07 2/55 26706 /proc/meminfo: memFree=833260/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=103288 CPUtime=0.29 /proc/26706/stat : 26706 (aptitude) R 26705 26706 17863 34816 17863 4202496 11313 1706 0 0 26 2 0 1 20 0 1 0 29914523 105766912 11111 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479506184 140659863254864 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 25822 11111 9899 1052 0 1159 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 103288 [startup+0.700207 s] /proc/loadavg: 1.08 1.07 1.07 2/55 26706 /proc/meminfo: memFree=833260/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=123068 CPUtime=0.69 /proc/26706/stat : 26706 (aptitude) R 26705 26706 17863 34816 17863 4202496 17690 1706 0 0 62 6 0 1 20 0 1 0 29914523 126021632 15965 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479504440 140659900309439 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 30767 15965 9976 1052 0 6104 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 123068 [startup+1.50025 s] /proc/loadavg: 1.08 1.07 1.07 2/57 26711 /proc/meminfo: memFree=804236/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=135860 CPUtime=1.49 /proc/26706/stat : 26706 (aptitude) R 26705 26706 17863 34816 17863 4202496 20629 1706 0 0 140 8 0 1 20 0 2 0 29914523 139120640 17545 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479502776 140659908116928 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 33965 17545 10227 1052 0 9302 0 [pid=26706/tid=26711] ppid=26705 vsize=135860 CPUtime=0.01 /proc/26706/task/26711/stat : 26711 (aptitude) S 26705 26706 17863 34816 17863 4202560 4 1706 0 0 0 0 0 1 20 0 2 0 29914601 139120640 17545 18446744073709551615 140659905409024 140659909716376 140733479515008 140659793086464 140659874153172 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 135860 [startup+3.10024 s] /proc/loadavg: 1.08 1.07 1.07 2/57 26711 /proc/meminfo: memFree=803368/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=150252 CPUtime=3.08 /proc/26706/stat : 26706 (aptitude) S 26705 26706 17863 34816 17863 4202496 24318 1706 0 0 297 10 0 1 20 0 2 0 29914523 153858048 21233 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479506768 140659874154059 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 37563 21233 10317 1052 0 12900 0 [pid=26706/tid=26711] ppid=26705 vsize=150252 CPUtime=0.27 /proc/26706/task/26711/stat : 26711 (aptitude) R 26705 26706 17863 34816 17863 4202560 3530 1706 0 0 24 2 0 1 20 0 2 0 29914601 153858048 21233 18446744073709551615 140659905409024 140659909716376 140733479515008 140659793078152 140659862909285 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.08 Current children cumulated vsize (KiB) 150252 [startup+6.30026 s] /proc/loadavg: 1.08 1.06 1.07 2/57 26711 /proc/meminfo: memFree=736284/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=269840 CPUtime=6.27 /proc/26706/stat : 26706 (aptitude) R 26705 26706 17863 34816 17863 4202496 38119 1706 0 0 608 18 0 1 20 0 2 0 29914523 276316160 34895 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479507112 140659862900147 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 67460 34895 10378 1052 0 42790 0 [pid=26706/tid=26711] ppid=26705 vsize=269840 CPUtime=3.09 /proc/26706/task/26711/stat : 26711 (aptitude) S 26705 26706 17863 34816 17863 4202560 17057 1706 0 0 298 10 0 1 20 0 2 0 29914601 276316160 34895 18446744073709551615 140659905409024 140659909716376 140733479515008 140659793086464 140659874153172 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.27 Current children cumulated vsize (KiB) 269840 Solver just ended. Dumping a history of the last processes samples [startup+6.30026 s] /proc/loadavg: 1.08 1.06 1.07 2/57 26711 /proc/meminfo: memFree=736284/1022884 swapFree=0/0 [pid=26706] ppid=26705 vsize=269840 CPUtime=6.27 /proc/26706/stat : 26706 (aptitude) R 26705 26706 17863 34816 17863 4202496 38119 1706 0 0 608 18 0 1 20 0 2 0 29914523 276316160 34895 18446744073709551615 140659905409024 140659909716376 140733479515008 140733479507112 140659862900147 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26706/statm: 67460 34895 10378 1052 0 42790 0 [pid=26706/tid=26711] ppid=26705 vsize=269840 CPUtime=3.09 /proc/26706/task/26711/stat : 26711 (aptitude) S 26705 26706 17863 34816 17863 4202560 17057 1706 0 0 298 10 0 1 20 0 2 0 29914601 276316160 34895 18446744073709551615 140659905409024 140659909716376 140733479515008 140659793086464 140659874153172 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.27 Current children cumulated vsize (KiB) 269840 Child status: 0 Real time (s): 6.38424 CPU time (s): 6.3604 CPU user time (s): 6.12838 CPU system time (s): 0.232014 CPU usage (%): 99.6266 Max. virtual memory (cumulated for all children) (KiB): 269840 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.12838 system time used= 0.232014 maximum resident set size= 139956 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 39893 page faults= 0 swaps= 0 block input operations= 0 block output operations= 32 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 60 involuntary context switches= 153 runsolver used 0.008 second user time and 0.024001 second system time The end