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/133.runsolver.aptitude aptitude -s -y --without-recommends remove libogg0 libgsf-1-dev libpcsclite1 kdelibs4 kfilereplace 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.84 0.44 2/55 20575 /proc/meminfo: memFree=241600/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=3152 CPUtime=0 /proc/20575/stat : 20575 (runsolver) R 20574 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28219471 3227648 32 18446744073709551615 134512640 134586868 4293735424 4293733472 4151825456 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.159994 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20575 /proc/meminfo: memFree=241600/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=86100 CPUtime=0.14 /proc/20575/stat : 20575 (aptitude) R 20574 20575 17863 34816 17863 4202496 7109 1709 0 0 14 0 0 0 20 0 1 0 28219471 88166400 6899 18446744073709551615 140413575557120 140413579864472 140736063988304 140736063979952 140413577708125 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 21525 6899 4711 1052 0 2116 0 Current children cumulated CPU time (s) 0.14 Current children cumulated vsize (KiB) 86100 [startup+0.200302 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20575 /proc/meminfo: memFree=241600/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=88584 CPUtime=0.19 /proc/20575/stat : 20575 (aptitude) R 20574 20575 17863 34816 17863 4202496 7539 1709 0 0 18 1 0 0 20 0 1 0 28219471 90710016 7160 18446744073709551615 140413575557120 140413579864472 140736063988304 140736063977624 140413578494512 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 22146 7160 4721 1052 0 2737 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 88584 [startup+0.300278 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20575 /proc/meminfo: memFree=241600/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=90632 CPUtime=0.29 /proc/20575/stat : 20575 (aptitude) R 20574 20575 17863 34816 17863 4202496 8631 1709 0 0 27 2 0 0 20 0 1 0 28219471 92807168 7674 18446744073709551615 140413575557120 140413579864472 140736063988304 140736063977736 140413544313200 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 22658 7674 4725 1052 0 3249 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 90632 [startup+0.700224 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20575 /proc/meminfo: memFree=241600/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=100484 CPUtime=0.69 /proc/20575/stat : 20575 (aptitude) R 20574 20575 17863 34816 17863 4202496 10075 1709 0 0 67 2 0 0 20 0 2 0 28219471 102895616 8703 18446744073709551615 140413575557120 140413579864472 140736063988304 140736063975416 140413533709523 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 25121 8703 4977 1052 0 5712 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 100484 [startup+1.50024 s] /proc/loadavg: 0.98 0.84 0.45 2/57 20580 /proc/meminfo: memFree=226340/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=112872 CPUtime=1.47 /proc/20575/stat : 20575 (aptitude) S 20574 20575 17863 34816 17863 4202496 13260 1709 0 0 145 2 0 0 20 0 2 0 28219471 115580928 11887 18446744073709551615 140413575557120 140413579864472 140736063988304 140736063980064 140413544302155 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 28218 11887 5068 1052 0 8809 0 [pid=20575/tid=20580] ppid=20574 vsize=112872 CPUtime=0.36 /proc/20575/task/20580/stat : 20580 (aptitude) R 20574 20575 17863 34816 17863 4202560 3092 1709 0 0 35 1 0 0 20 0 2 0 28219501 115580928 11887 18446744073709551615 140413575557120 140413579864472 140736063988304 140413492126824 140413533060660 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.47 Current children cumulated vsize (KiB) 112872 Solver just ended. Dumping a history of the last processes samples [startup+1.60033 s] /proc/loadavg: 0.98 0.84 0.45 2/57 20580 /proc/meminfo: memFree=226340/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=114192 CPUtime=1.57 /proc/20575/stat : 20575 (aptitude) S 20574 20575 17863 34816 17863 4202496 13600 1709 0 0 155 2 0 0 20 0 2 0 28219471 116932608 12227 18446744073709551615 140413575557120 140413579864472 140736063988304 140736063980064 140413544302155 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 28548 12227 5069 1052 0 9139 0 [pid=20575/tid=20580] ppid=20574 vsize=114192 CPUtime=0.46 /proc/20575/task/20580/stat : 20580 (aptitude) R 20574 20575 17863 34816 17863 4202560 3432 1709 0 0 45 1 0 0 20 0 2 0 28219501 116932608 12227 18446744073709551615 140413575557120 140413579864472 140736063988304 140413492125704 140413533067527 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.57 Current children cumulated vsize (KiB) 114192 [startup+1.70026 s] /proc/loadavg: 0.98 0.84 0.45 2/57 20580 /proc/meminfo: memFree=226340/1022884 swapFree=0/0 [pid=20575] ppid=20574 vsize=115148 CPUtime=1.67 /proc/20575/stat : 20575 (aptitude) R 20574 20575 17863 34816 17863 4202496 13881 1709 0 0 165 2 0 0 20 0 2 0 28219471 117911552 12506 18446744073709551615 140413575557120 140413579864472 140736063988304 140736063976168 140413577391782 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20575/statm: 28787 12506 5119 1052 0 9371 0 [pid=20575/tid=20580] ppid=20574 vsize=115148 CPUtime=0.48 /proc/20575/task/20580/stat : 20580 (aptitude) S 20574 20575 17863 34816 17863 4202560 3555 1709 0 0 47 1 0 0 20 0 2 0 28219501 117911552 12506 18446744073709551615 140413575557120 140413579864472 140736063988304 140413492135936 140413544301268 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.67 Current children cumulated vsize (KiB) 115148 Child status: 0 Real time (s): 1.7575 CPU time (s): 1.75211 CPU user time (s): 1.70411 CPU system time (s): 0.048003 CPU usage (%): 99.6931 Max. virtual memory (cumulated for all children) (KiB): 115148 getrusage(RUSAGE_CHILDREN,...) data: user time used= 1.70411 system time used= 0.048003 maximum resident set size= 50068 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 15666 page faults= 0 swaps= 0 block input operations= 0 block output operations= 72 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 56 involuntary context switches= 75 runsolver used 0 second user time and 0.012 second system time The end