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/114.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove kwin librsvg2-common kmix xmms totem 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.99 0.67 0.29 2/55 24076 /proc/meminfo: memFree=282764/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=3152 CPUtime=0 /proc/24076/stat : 24076 (runsolver) R 24075 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28190747 3227648 33 18446744073709551615 134512640 134586868 4286646544 4286644592 4151845936 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.112977 s] /proc/loadavg: 0.99 0.67 0.29 2/55 24076 /proc/meminfo: memFree=282764/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=33112 CPUtime=0.12 /proc/24076/stat : 24076 (cupt) R 24075 24076 17872 34816 17872 4202496 1742 7209 0 0 5 1 4 2 20 0 1 0 28190747 33906688 1561 18446744073709551615 4194304 5184042 140733761561888 140733761559080 140364512021979 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 8278 1561 729 242 0 840 0 Current children cumulated CPU time (s) 0.12 Current children cumulated vsize (KiB) 33112 [startup+0.200282 s] /proc/loadavg: 0.99 0.67 0.29 2/55 24076 /proc/meminfo: memFree=282764/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=39844 CPUtime=0.21 /proc/24076/stat : 24076 (cupt) R 24075 24076 17872 34816 17872 4202496 3517 7209 0 0 13 2 4 2 20 0 1 0 28190747 40800256 3328 18446744073709551615 4194304 5184042 140733761561888 140733761557880 140364528274463 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 9961 3328 795 242 0 2523 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 39844 [startup+0.300279 s] /proc/loadavg: 0.99 0.67 0.29 2/55 24076 /proc/meminfo: memFree=282764/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=50412 CPUtime=0.31 /proc/24076/stat : 24076 (cupt) R 24075 24076 17872 34816 17872 4202496 6168 7209 0 0 22 3 4 2 20 0 1 0 28190747 51621888 5978 18446744073709551615 4194304 5184042 140733761561888 140733761559176 140364528271104 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 12603 5978 842 242 0 5165 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 50412 [startup+0.70022 s] /proc/loadavg: 0.99 0.67 0.29 2/55 24076 /proc/meminfo: memFree=282764/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=58020 CPUtime=0.71 /proc/24076/stat : 24076 (cupt) R 24075 24076 17872 34816 17872 4202496 8304 7209 0 0 62 3 4 2 20 0 1 0 28190747 59412480 7840 18446744073709551615 4194304 5184042 140733761561888 140733761556152 140364528348390 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 14505 7840 842 242 0 7067 0 Current children cumulated CPU time (s) 0.71 Current children cumulated vsize (KiB) 58020 Solver just ended. Dumping a history of the last processes samples [startup+0.800288 s] /proc/loadavg: 0.99 0.67 0.29 2/55 24076 /proc/meminfo: memFree=282764/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=58020 CPUtime=0.81 /proc/24076/stat : 24076 (cupt) R 24075 24076 17872 34816 17872 4202496 8340 7209 0 0 72 3 4 2 20 0 1 0 28190747 59412480 7876 18446744073709551615 4194304 5184042 140733761561888 140733761555304 140364528325792 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 14505 7876 842 242 0 7067 0 Current children cumulated CPU time (s) 0.81 Current children cumulated vsize (KiB) 58020 [startup+1.00021 s] /proc/loadavg: 0.99 0.67 0.29 2/55 24076 /proc/meminfo: memFree=282764/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=60148 CPUtime=1.01 /proc/24076/stat : 24076 (cupt) R 24075 24076 17872 34816 17872 4202496 8930 7209 0 0 92 3 4 2 20 0 1 0 28190747 61591552 8209 18446744073709551615 4194304 5184042 140733761561888 140733761559192 140364528300455 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 15037 8209 842 242 0 7599 0 Current children cumulated CPU time (s) 1.01 Current children cumulated vsize (KiB) 60148 [startup+1.10021 s] /proc/loadavg: 0.99 0.67 0.29 2/56 24096 /proc/meminfo: memFree=254120/1022884 swapFree=0/0 [pid=24076] ppid=24075 vsize=58704 CPUtime=1.11 /proc/24076/stat : 24076 (cupt) R 24075 24076 17872 34816 17872 4202496 9136 7209 0 0 102 3 4 2 20 0 1 0 28190747 60112896 7902 18446744073709551615 4194304 5184042 140733761561888 140733761559320 140364528300368 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24076/statm: 14676 7902 842 242 0 7238 0 Current children cumulated CPU time (s) 1.11 Current children cumulated vsize (KiB) 58704 Child status: 1 Real time (s): 1.16221 CPU time (s): 1.18407 CPU user time (s): 1.11607 CPU system time (s): 0.068004 CPU usage (%): 101.881 Max. virtual memory (cumulated for all children) (KiB): 60148 getrusage(RUSAGE_CHILDREN,...) data: user time used= 1.11607 system time used= 0.068004 maximum resident set size= 33660 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 16378 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= 52 involuntary context switches= 34 runsolver used 0 second user time and 0.012 second system time The end