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/120.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove khangman libpt-plugins-oss gstreamer0.8-vorbis libgtk2.0-common libwmf0.2-7 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.69 0.31 2/55 24208 /proc/meminfo: memFree=282624/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=3152 CPUtime=0 /proc/24208/stat : 24208 (runsolver) R 24207 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28192730 3227648 32 18446744073709551615 134512640 134586868 4290385168 4290383216 4151469104 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.122377 s] /proc/loadavg: 0.99 0.69 0.31 2/55 24208 /proc/meminfo: memFree=282624/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=33508 CPUtime=0.13 /proc/24208/stat : 24208 (cupt) R 24207 24208 17872 34816 17872 4202496 1833 7207 0 0 7 0 4 2 20 0 1 0 28192730 34312192 1651 18446744073709551615 4194304 5184042 140734377255776 140734377252968 139715461972097 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 8377 1651 729 242 0 939 0 Current children cumulated CPU time (s) 0.13 Current children cumulated vsize (KiB) 33508 [startup+0.200268 s] /proc/loadavg: 0.99 0.69 0.31 2/55 24208 /proc/meminfo: memFree=282624/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=40240 CPUtime=0.21 /proc/24208/stat : 24208 (cupt) R 24207 24208 17872 34816 17872 4202496 3638 7207 0 0 13 2 4 2 20 0 1 0 28192730 41205760 3447 18446744073709551615 4194304 5184042 140734377255776 140734377252008 139715478306256 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 10060 3447 829 242 0 2622 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 40240 [startup+0.300257 s] /proc/loadavg: 0.99 0.69 0.31 2/55 24208 /proc/meminfo: memFree=282624/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=51276 CPUtime=0.32 /proc/24208/stat : 24208 (cupt) R 24207 24208 17872 34816 17872 4202496 6396 7207 0 0 22 4 4 2 20 0 1 0 28192730 52506624 6205 18446744073709551615 4194304 5184042 140734377255776 140734377249192 139715478231397 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 12819 6205 842 242 0 5381 0 Current children cumulated CPU time (s) 0.32 Current children cumulated vsize (KiB) 51276 [startup+0.700209 s] /proc/loadavg: 0.99 0.69 0.31 2/55 24208 /proc/meminfo: memFree=282624/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=59304 CPUtime=0.71 /proc/24208/stat : 24208 (cupt) R 24207 24208 17872 34816 17872 4202496 8618 7207 0 0 61 4 4 2 20 0 1 0 28192730 60727296 8130 18446744073709551615 4194304 5184042 140734377255776 140734377249992 139715478275743 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 14826 8130 842 242 0 7388 0 Current children cumulated CPU time (s) 0.71 Current children cumulated vsize (KiB) 59304 [startup+1.50021 s] /proc/loadavg: 0.99 0.69 0.31 2/56 24228 /proc/meminfo: memFree=251748/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=61168 CPUtime=1.5 /proc/24208/stat : 24208 (cupt) R 24207 24208 17872 34816 17872 4202496 9338 7207 0 0 140 4 4 2 20 0 1 0 28192730 62636032 8593 18446744073709551615 4194304 5184042 140734377255776 140734377250072 139715478308525 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 15292 8593 842 242 0 7854 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 61168 [startup+3.10022 s] /proc/loadavg: 0.99 0.69 0.31 2/56 24228 /proc/meminfo: memFree=250632/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=59104 CPUtime=3.1 /proc/24208/stat : 24208 (cupt) R 24207 24208 17872 34816 17872 4202496 10365 7207 0 0 299 5 4 2 20 0 1 0 28192730 60522496 8191 18446744073709551615 4194304 5184042 140734377255776 140734377254936 139715470715883 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 14776 8191 853 242 0 7338 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 59104 Solver just ended. Dumping a history of the last processes samples [startup+3.10022 s] /proc/loadavg: 0.99 0.69 0.31 2/56 24228 /proc/meminfo: memFree=250632/1022884 swapFree=0/0 [pid=24208] ppid=24207 vsize=59104 CPUtime=3.1 /proc/24208/stat : 24208 (cupt) R 24207 24208 17872 34816 17872 4202496 10365 7207 0 0 299 5 4 2 20 0 1 0 28192730 60522496 8191 18446744073709551615 4194304 5184042 140734377255776 140734377254936 139715470715883 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24208/statm: 14776 8191 853 242 0 7338 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 59104 Child status: 1 Real time (s): 3.13658 CPU time (s): 3.1562 CPU user time (s): 3.06819 CPU system time (s): 0.088005 CPU usage (%): 100.625 Max. virtual memory (cumulated for all children) (KiB): 63216 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.06819 system time used= 0.088005 maximum resident set size= 36836 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 17592 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= 57 runsolver used 0.012 second user time and 0.004 second system time The end