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/30.runsolver.aptitude aptitude -s -y --without-recommends install openslp-doc honeyd-common planetpenguin-racer abcmidi-yaps forutil 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.93 0.44 0.17 2/55 19486 /proc/meminfo: memFree=248072/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=3152 CPUtime=0 /proc/19486/stat : 19486 (runsolver) R 19485 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28179316 3227648 33 18446744073709551615 134512640 134586868 4288407328 4288405376 4152046640 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.194789 s] /proc/loadavg: 0.93 0.44 0.17 2/55 19486 /proc/meminfo: memFree=248072/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=87812 CPUtime=0.18 /proc/19486/stat : 19486 (aptitude) R 19485 19486 17863 34816 17863 4202496 7742 1709 0 0 17 1 0 0 20 0 1 0 28179316 89919488 7170 18446744073709551615 139809437339648 139809441647000 140736610623920 140736610613432 139809440264309 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 21953 7170 4721 1052 0 2544 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 87812 [startup+0.200275 s] /proc/loadavg: 0.93 0.44 0.17 2/55 19486 /proc/meminfo: memFree=248072/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=89348 CPUtime=0.18 /proc/19486/stat : 19486 (aptitude) R 19485 19486 17863 34816 17863 4202496 8318 1709 0 0 17 1 0 0 20 0 1 0 28179316 91492352 7361 18446744073709551615 139809437339648 139809441647000 140736610623920 140736610613432 139809440278431 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 22337 7361 4721 1052 0 2928 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 89348 [startup+0.300269 s] /proc/loadavg: 0.93 0.44 0.17 2/55 19486 /proc/meminfo: memFree=248072/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=96684 CPUtime=0.29 /proc/19486/stat : 19486 (aptitude) R 19485 19486 17863 34816 17863 4202496 9023 1709 0 0 27 2 0 0 20 0 2 0 28179316 99004416 7655 18446744073709551615 139809437339648 139809441647000 140736610623920 140736610619704 139809432184963 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 24171 7655 4890 1052 0 4762 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 96684 [startup+0.700216 s] /proc/loadavg: 0.93 0.44 0.17 2/55 19486 /proc/meminfo: memFree=248072/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=100624 CPUtime=0.68 /proc/19486/stat : 19486 (aptitude) R 19485 19486 17863 34816 17863 4202496 10123 1709 0 0 66 2 0 0 20 0 2 0 28179316 103038976 8751 18446744073709551615 139809437339648 139809441647000 140736610623920 140736610613704 139809432038161 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 25156 8751 4995 1052 0 5747 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 100624 Solver just ended. Dumping a history of the last processes samples [startup+0.80028 s] /proc/loadavg: 0.93 0.44 0.17 2/55 19486 /proc/meminfo: memFree=248072/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=101520 CPUtime=0.78 /proc/19486/stat : 19486 (aptitude) R 19485 19486 17863 34816 17863 4202496 10208 1709 0 0 76 2 0 0 20 0 2 0 28179316 103956480 8836 18446744073709551615 139809437339648 139809441647000 140736610623920 140736610614824 139809395183685 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 25380 8836 5022 1052 0 5971 0 Current children cumulated CPU time (s) 0.78 Current children cumulated vsize (KiB) 101520 [startup+1.20329 s] /proc/loadavg: 0.93 0.44 0.17 2/57 19492 /proc/meminfo: memFree=232432/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=101520 CPUtime=1.18 /proc/19486/stat : 19486 (aptitude) S 19485 19486 17863 34816 17863 4202496 11028 2208 0 0 115 3 0 0 20 0 2 0 28179316 103956480 8985 18446744073709551615 139809437339648 139809441647000 140736610623920 140736610615680 139809406084683 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 25380 8985 5097 1052 0 5971 0 [pid=19486/tid=19491] ppid=19485 vsize=101520 CPUtime=0.02 /proc/19486/task/19491/stat : 19491 (aptitude) R 19485 19486 17863 34816 17863 4202560 367 2208 0 0 2 0 0 0 20 0 2 0 28179346 103956480 8985 18446744073709551615 139809437339648 139809441647000 140736610623920 139809353918344 139809438168691 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) 101520 [startup+1.40025 s] /proc/loadavg: 0.93 0.44 0.17 2/57 19492 /proc/meminfo: memFree=232432/1022884 swapFree=0/0 [pid=19486] ppid=19485 vsize=105112 CPUtime=1.38 /proc/19486/stat : 19486 (aptitude) R 19485 19486 17863 34816 17863 4202496 12628 2208 0 0 134 4 0 0 20 0 2 0 28179316 107634688 10032 18446744073709551615 139809437339648 139809441647000 140736610623920 140736610611784 139809395556962 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19486/statm: 26278 10032 5151 1052 0 6862 0 [pid=19486/tid=19491] ppid=19485 vsize=105112 CPUtime=0.17 /proc/19486/task/19491/stat : 19491 (aptitude) S 19485 19486 17863 34816 17863 4202560 1394 2208 0 0 16 1 0 0 20 0 2 0 28179346 107634688 10032 18446744073709551615 139809437339648 139809441647000 140736610623920 139809353918464 139809406083796 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.38 Current children cumulated vsize (KiB) 105112 Child status: 0 Real time (s): 1.48026 CPU time (s): 1.48009 CPU user time (s): 1.40409 CPU system time (s): 0.076004 CPU usage (%): 99.9887 Max. virtual memory (cumulated for all children) (KiB): 105112 getrusage(RUSAGE_CHILDREN,...) data: user time used= 1.40409 system time used= 0.076004 maximum resident set size= 40140 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 16398 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= 73 runsolver used 0 second user time and 0.012 second system time The end