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/logs/106.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove libxcursor1 kreversi libgal2.2-1 libgimp2.0 kdeadmin-kfile-plugins 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.00 0.99 0.91 2/55 27994 /proc/meminfo: memFree=306036/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=3152 CPUtime=0 /proc/27994/stat : 27994 (runsolver) R 27993 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28508499 3227648 32 18446744073709551615 134512640 134586868 4294462416 4294460464 4152038448 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.140712 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27994 /proc/meminfo: memFree=306036/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=34036 CPUtime=0.16 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 1968 7215 0 0 8 2 4 2 20 0 1 0 28508499 34852864 1787 18446744073709551615 4194304 5184042 140737396814464 140737396811656 140426546078040 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 8509 1787 729 242 0 1071 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 34036 [startup+0.200275 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27994 /proc/meminfo: memFree=306036/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=35360 CPUtime=0.21 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 2324 7215 0 0 13 2 4 2 20 0 1 0 28508499 36208640 2142 18446744073709551615 4194304 5184042 140737396814464 140737396811656 140426546209024 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 8840 2142 729 242 0 1402 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 35360 [startup+0.30026 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27994 /proc/meminfo: memFree=306036/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=47108 CPUtime=0.3 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 5356 7215 0 0 20 4 4 2 20 0 1 0 28508499 48238592 5165 18446744073709551615 4194304 5184042 140737396814464 140737396810664 140426554576303 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 11777 5165 835 242 0 4339 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 47108 [startup+0.70021 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27994 /proc/meminfo: memFree=306036/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=59996 CPUtime=0.7 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 8600 7215 0 0 58 6 4 2 20 0 1 0 28508499 61435904 8376 18446744073709551615 4194304 5184042 140737396814464 140737396811768 140426562545228 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 14999 8376 842 242 0 7561 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 59996 [startup+1.50021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28014 /proc/meminfo: memFree=274664/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=62104 CPUtime=1.51 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 9237 7215 0 0 138 7 4 2 20 0 1 0 28508499 63594496 8819 18446744073709551615 4194304 5184042 140737396814464 140737396811896 140426546153758 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 15526 8819 842 242 0 8088 0 Current children cumulated CPU time (s) 1.51 Current children cumulated vsize (KiB) 62104 [startup+3.10022 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28014 /proc/meminfo: memFree=272308/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=68524 CPUtime=3.1 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 11141 7215 0 0 296 8 4 2 20 0 1 0 28508499 70168576 10466 18446744073709551615 4194304 5184042 140737396814464 140737396808712 140426562545267 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 17131 10466 842 242 0 9693 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 68524 Solver just ended. Dumping a history of the last processes samples [startup+3.20028 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28014 /proc/meminfo: memFree=272308/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=68524 CPUtime=3.2 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 11155 7215 0 0 306 8 4 2 20 0 1 0 28508499 70168576 10480 18446744073709551615 4194304 5184042 140737396814464 140737396807880 140426562571480 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 17131 10480 842 242 0 9693 0 Current children cumulated CPU time (s) 3.2 Current children cumulated vsize (KiB) 68524 [startup+3.60021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28014 /proc/meminfo: memFree=267224/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=70572 CPUtime=3.6 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 11729 7215 0 0 345 9 4 2 20 0 1 0 28508499 72265728 10541 18446744073709551615 4194304 5184042 140737396814464 140737396811912 140426554664548 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 17643 10541 842 242 0 10205 0 Current children cumulated CPU time (s) 3.6 Current children cumulated vsize (KiB) 70572 [startup+3.70021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 28014 /proc/meminfo: memFree=267224/1022884 swapFree=0/0 [pid=27994] ppid=27993 vsize=70572 CPUtime=3.7 /proc/27994/stat : 27994 (cupt) R 27993 27994 17872 34816 17872 4202496 11742 7215 0 0 355 9 4 2 20 0 1 0 28508499 72265728 10554 18446744073709551615 4194304 5184042 140737396814464 140737396811912 140426562487392 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27994/statm: 17643 10554 842 242 0 10205 0 Current children cumulated CPU time (s) 3.7 Current children cumulated vsize (KiB) 70572 Child status: 1 Real time (s): 3.77562 CPU time (s): 3.79224 CPU user time (s): 3.66423 CPU system time (s): 0.128008 CPU usage (%): 100.44 Max. virtual memory (cumulated for all children) (KiB): 70572 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.66423 system time used= 0.128008 maximum resident set size= 44192 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 18988 page faults= 0 swaps= 0 block input operations= 0 block output operations= 208 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 52 involuntary context switches= 70 runsolver used 0.004 second user time and 0.016001 second system time The end