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/logs/105.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove libbonoboui2-common libtext-wrapi18n-perl gnome-desktop-data gsfonts libtextwrap1 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 Current StackSize limit: 8192 KiB [startup+0 s] /proc/loadavg: 0.99 0.99 0.96 2/55 303 /proc/meminfo: memFree=338876/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=3152 CPUtime=0 /proc/303/stat : 303 (runsolver) R 301 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 29355520 3227648 33 18446744073709551615 134512640 134586868 4291691632 4291689680 4151456816 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 788 33 0 19 0 73 0 [startup+0.138984 s] /proc/loadavg: 0.99 0.99 0.96 2/55 303 /proc/meminfo: memFree=338876/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=33904 CPUtime=0.15 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 1944 7211 0 0 7 2 5 1 20 0 1 0 29355520 34717696 1761 18446744073709551615 4194304 5184042 140734320738592 140734320735784 140507327361072 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 8476 1761 729 242 0 1038 0 Current children cumulated CPU time (s) 0.15 Current children cumulated vsize (KiB) 33904 [startup+0.200278 s] /proc/loadavg: 0.99 0.99 0.96 2/55 303 /proc/meminfo: memFree=338876/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=35360 CPUtime=0.21 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 2316 7211 0 0 12 3 5 1 20 0 1 0 29355520 36208640 2132 18446744073709551615 4194304 5184042 140734320738592 140734320735784 140507327772400 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 8840 2132 729 242 0 1402 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 35360 [startup+0.300251 s] /proc/loadavg: 0.99 0.99 0.96 2/55 303 /proc/meminfo: memFree=338876/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=37852 CPUtime=0.31 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 3023 7211 0 0 19 6 5 1 20 0 1 0 29355520 38760448 2776 18446744073709551615 4194304 5184042 140734320738592 140734320735784 140507335859564 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 9463 2776 739 242 0 2025 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 37852 [startup+0.700197 s] /proc/loadavg: 0.99 0.99 0.96 2/55 303 /proc/meminfo: memFree=338876/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=63788 CPUtime=0.7 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 9584 7211 0 0 56 8 5 1 20 0 1 0 29355520 65318912 9329 18446744073709551615 4194304 5184042 140734320738592 140734320735832 140507343770630 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 15947 9329 851 242 0 8509 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 63788 [startup+1.5002 s] /proc/loadavg: 0.99 0.99 0.96 2/56 323 /proc/meminfo: memFree=304032/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=65144 CPUtime=1.51 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 10033 7211 0 0 136 9 5 1 20 0 1 0 29355520 66707456 9584 18446744073709551615 4194304 5184042 140734320738592 140734320736024 140507343770630 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 16286 9584 851 242 0 8848 0 Current children cumulated CPU time (s) 1.51 Current children cumulated vsize (KiB) 65144 [startup+3.10021 s] /proc/loadavg: 0.99 0.99 0.96 2/56 323 /proc/meminfo: memFree=303288/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=66452 CPUtime=3.1 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 10525 7211 0 0 295 9 5 1 20 0 1 0 29355520 68046848 9819 18446744073709551615 4194304 5184042 140734320738592 140734320736024 140507343771058 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 16613 9819 851 242 0 9175 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 66452 Solver just ended. Dumping a history of the last processes samples [startup+3.20027 s] /proc/loadavg: 0.99 0.99 0.96 2/56 323 /proc/meminfo: memFree=303288/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=66452 CPUtime=3.2 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 10537 7211 0 0 305 9 5 1 20 0 1 0 29355520 68046848 9831 18446744073709551615 4194304 5184042 140734320738592 140734320736024 140507343783366 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 16613 9831 851 242 0 9175 0 Current children cumulated CPU time (s) 3.2 Current children cumulated vsize (KiB) 66452 [startup+3.6002 s] /proc/loadavg: 0.99 0.99 0.96 2/56 323 /proc/meminfo: memFree=302668/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=66452 CPUtime=3.59 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 10603 7211 0 0 344 9 5 1 20 0 1 0 29355520 68046848 9897 18446744073709551615 4194304 5184042 140734320738592 140734320732888 140507343786867 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 16613 9897 851 242 0 9175 0 Current children cumulated CPU time (s) 3.59 Current children cumulated vsize (KiB) 66452 [startup+3.8002 s] /proc/loadavg: 0.99 0.99 0.96 2/56 323 /proc/meminfo: memFree=302668/1022884 swapFree=0/0 [pid=303] ppid=301 vsize=66452 CPUtime=3.79 /proc/303/stat : 303 (cupt) R 301 303 17872 34816 17872 4202496 10624 7211 0 0 364 9 5 1 20 0 1 0 29355520 68046848 9918 18446744073709551615 4194304 5184042 140734320738592 140734320732888 140507343756021 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/303/statm: 16613 9918 851 242 0 9175 0 Current children cumulated CPU time (s) 3.79 Current children cumulated vsize (KiB) 66452 Child status: 1 Real time (s): 3.84373 CPU time (s): 3.86024 CPU user time (s): 3.74423 CPU system time (s): 0.116007 CPU usage (%): 100.43 Max. virtual memory (cumulated for all children) (KiB): 66452 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.74423 system time used= 0.116007 maximum resident set size= 40032 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 17867 page faults= 0 swaps= 0 block input operations= 0 block output operations= 48 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 52 involuntary context switches= 73 runsolver used 0.008 second user time and 0.012 second system time The end