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/15.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends install gql-shell libarchive-tar-perl harden-servers xara-gtk-byte libgnome2-doc 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.71 0.22 0.07 2/55 21880 /proc/meminfo: memFree=286120/1022884 swapFree=0/0 [pid=21880] ppid=21879 vsize=3152 CPUtime=0 /proc/21880/stat : 21880 (runsolver) R 21879 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28163367 3227648 32 18446744073709551615 134512640 134586868 4290895296 4290893344 4151407664 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/21880/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.159901 s] /proc/loadavg: 0.71 0.22 0.07 2/55 21880 /proc/meminfo: memFree=286120/1022884 swapFree=0/0 [pid=21880] ppid=21879 vsize=35092 CPUtime=0.18 /proc/21880/stat : 21880 (cupt) R 21879 21880 17872 34816 17872 4202496 2272 7208 0 0 10 1 4 3 20 0 1 0 28163367 35934208 2086 18446744073709551615 4194304 5184042 140735751542640 140735751538632 140144331685428 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/21880/statm: 8773 2086 758 242 0 1335 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 35092 [startup+0.200408 s] /proc/loadavg: 0.71 0.22 0.07 2/55 21880 /proc/meminfo: memFree=286120/1022884 swapFree=0/0 [pid=21880] ppid=21879 vsize=39316 CPUtime=0.21 /proc/21880/stat : 21880 (cupt) D 21879 21880 17872 34816 17872 4202496 3319 7208 0 0 12 2 4 3 20 0 1 0 28163367 40259584 3133 18446744073709551615 4194304 5184042 140735751542640 140735751537896 140144332027728 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/21880/statm: 9829 3133 759 242 0 2391 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 39316 [startup+0.30876 s] /proc/loadavg: 0.71 0.22 0.07 2/55 21880 /proc/meminfo: memFree=286120/1022884 swapFree=0/0 [pid=21880] ppid=21879 vsize=50860 CPUtime=0.31 /proc/21880/stat : 21880 (cupt) R 21879 21880 17872 34816 17872 4202496 6294 7208 0 0 20 4 4 3 20 0 1 0 28163367 52080640 6104 18446744073709551615 4194304 5184042 140735751542640 140735751539944 140144348026352 0 0 0 0 0 0 0 17 0 0 0 1 0 0 /proc/21880/statm: 12715 6104 842 242 0 5277 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 50860 [startup+0.70018 s] /proc/loadavg: 0.71 0.22 0.07 2/55 21880 /proc/meminfo: memFree=286120/1022884 swapFree=0/0 [pid=21880] ppid=21879 vsize=60772 CPUtime=0.7 /proc/21880/stat : 21880 (cupt) R 21879 21880 17872 34816 17872 4202496 8914 7208 0 0 58 5 4 3 20 0 1 0 28163367 62230528 8498 18446744073709551615 4194304 5184042 140735751542640 140735751539944 140144332334303 0 0 0 0 0 0 0 17 0 0 0 1 0 0 /proc/21880/statm: 15193 8498 842 242 0 7755 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 60772 Solver just ended. Dumping a history of the last processes samples [startup+0.800284 s] /proc/loadavg: 0.71 0.22 0.07 2/55 21880 /proc/meminfo: memFree=286120/1022884 swapFree=0/0 [pid=21880] ppid=21879 vsize=61732 CPUtime=0.8 /proc/21880/stat : 21880 (cupt) R 21879 21880 17872 34816 17872 4202496 9210 7208 0 0 68 5 4 3 20 0 1 0 28163367 63213568 8794 18446744073709551615 4194304 5184042 140735751542640 140735751536056 140144348026248 0 0 0 0 0 0 0 17 0 0 0 1 0 0 /proc/21880/statm: 15433 8794 842 242 0 7995 0 Current children cumulated CPU time (s) 0.8 Current children cumulated vsize (KiB) 61732 [startup+0.900197 s] /proc/loadavg: 0.71 0.22 0.07 2/55 21880 /proc/meminfo: memFree=286120/1022884 swapFree=0/0 [pid=21880] ppid=21879 vsize=61732 CPUtime=0.9 /proc/21880/stat : 21880 (cupt) R 21879 21880 17872 34816 17872 4202496 9248 7208 0 0 78 5 4 3 20 0 1 0 28163367 63213568 8832 18446744073709551615 4194304 5184042 140735751542640 140735751536024 140144331692178 0 0 0 0 0 0 0 17 0 0 0 1 0 0 /proc/21880/statm: 15433 8832 842 242 0 7995 0 Current children cumulated CPU time (s) 0.9 Current children cumulated vsize (KiB) 61732 Child status: 1 Real time (s): 0.985451 CPU time (s): 0.996061 CPU user time (s): 0.904056 CPU system time (s): 0.092005 CPU usage (%): 101.077 Max. virtual memory (cumulated for all children) (KiB): 61732 getrusage(RUSAGE_CHILDREN,...) data: user time used= 0.904056 system time used= 0.092005 maximum resident set size= 35396 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 16493 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= 54 involuntary context switches= 32 runsolver used 0.008 second user time and 0 second system time The end