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/151.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove gnome-applets-data cervisia libgimpprint1 groff-base ifupdown 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.98 0.92 2/55 29220 /proc/meminfo: memFree=312064/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=3152 CPUtime=0 /proc/29220/stat : 29220 (runsolver) R 29219 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28662589 3227648 33 18446744073709551615 134512640 134586868 4290947040 4290945088 4151432240 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.147056 s] /proc/loadavg: 0.99 0.98 0.92 2/55 29220 /proc/meminfo: memFree=312064/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=34168 CPUtime=0.16 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 2004 7204 0 0 8 1 4 3 20 0 1 0 28662589 34988032 1821 18446744073709551615 4194304 5184042 140734246764960 140734246762152 140728040513047 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 8542 1821 729 242 0 1104 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 34168 [startup+0.200273 s] /proc/loadavg: 0.99 0.98 0.92 2/55 29220 /proc/meminfo: memFree=312064/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=35360 CPUtime=0.22 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 2317 7204 0 0 13 2 4 3 20 0 1 0 28662589 36208640 2133 18446744073709551615 4194304 5184042 140734246764960 140734246762152 140728040818416 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 8840 2133 729 242 0 1402 0 Current children cumulated CPU time (s) 0.22 Current children cumulated vsize (KiB) 35360 [startup+0.300262 s] /proc/loadavg: 0.99 0.98 0.92 2/55 29220 /proc/meminfo: memFree=312064/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=46844 CPUtime=0.31 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 5288 7204 0 0 20 4 4 3 20 0 1 0 28662589 47968256 5095 18446744073709551615 4194304 5184042 140734246764960 140734246759448 140728056590464 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 11711 5095 835 242 0 4273 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 46844 [startup+0.700201 s] /proc/loadavg: 0.99 0.98 0.92 2/55 29220 /proc/meminfo: memFree=312064/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=58176 CPUtime=0.72 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 8187 7204 0 0 59 6 4 3 20 0 1 0 28662589 59572224 7892 18446744073709551615 4194304 5184042 140734246764960 140734246762264 140728056816636 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 14544 7892 842 242 0 7106 0 Current children cumulated CPU time (s) 0.72 Current children cumulated vsize (KiB) 58176 [startup+1.50021 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=282180/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=59876 CPUtime=1.51 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 8766 7204 0 0 138 6 4 3 20 0 1 0 28662589 61313024 8342 18446744073709551615 4194304 5184042 140734246764960 140734246762264 140728056817244 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 14969 8342 842 242 0 7531 0 Current children cumulated CPU time (s) 1.51 Current children cumulated vsize (KiB) 59876 [startup+3.10022 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=281312/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=62948 CPUtime=3.11 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 9855 7204 0 0 297 7 4 3 20 0 1 0 28662589 64458752 8661 18446744073709551615 4194304 5184042 140734246764960 140734246762392 140728056825499 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 15737 8661 842 242 0 8299 0 Current children cumulated CPU time (s) 3.11 Current children cumulated vsize (KiB) 62948 [startup+6.30021 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=274492/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=72976 CPUtime=6.3 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 13023 7204 0 0 615 8 4 3 20 0 1 0 28662589 74727424 10804 18446744073709551615 4194304 5184042 140734246764960 140734246759224 140728056817086 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 18244 10804 842 242 0 10806 0 Current children cumulated CPU time (s) 6.3 Current children cumulated vsize (KiB) 72976 [startup+12.7002 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=258496/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=84088 CPUtime=12.67 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 16552 7204 0 0 1250 10 4 3 20 0 1 0 28662589 86106112 14333 18446744073709551615 4194304 5184042 140734246764960 140734246762264 140728056817253 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 21022 14333 842 242 0 13584 0 Current children cumulated CPU time (s) 12.67 Current children cumulated vsize (KiB) 84088 Solver just ended. Dumping a history of the last processes samples [startup+12.8003 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=258496/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=84088 CPUtime=12.77 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 16589 7204 0 0 1260 10 4 3 20 0 1 0 28662589 86106112 14370 18446744073709551615 4194304 5184042 140734246764960 140734246762264 140728056817260 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 21022 14370 842 242 0 13584 0 Current children cumulated CPU time (s) 12.77 Current children cumulated vsize (KiB) 84088 [startup+13.6002 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=257628/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=92280 CPUtime=13.56 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 18723 7204 0 0 1339 10 4 3 20 0 1 0 28662589 94494720 14455 18446744073709551615 4194304 5184042 140734246764960 140734246759256 140728056824200 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 23070 14455 842 242 0 15632 0 Current children cumulated CPU time (s) 13.56 Current children cumulated vsize (KiB) 92280 [startup+14.0002 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=257628/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=92280 CPUtime=13.96 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 18758 7204 0 0 1379 10 4 3 20 0 1 0 28662589 94494720 14490 18446744073709551615 4194304 5184042 140734246764960 140734246759256 140728056817160 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 23070 14490 842 242 0 15632 0 Current children cumulated CPU time (s) 13.96 Current children cumulated vsize (KiB) 92280 [startup+14.1002 s] /proc/loadavg: 0.99 0.98 0.92 2/56 29240 /proc/meminfo: memFree=257628/1022884 swapFree=0/0 [pid=29220] ppid=29219 vsize=75892 CPUtime=14.06 /proc/29220/stat : 29220 (cupt) R 29219 29220 17872 34816 17872 4202496 18778 7204 0 0 1388 11 4 3 20 0 1 0 28662589 77713408 12391 18446744073709551615 4194304 5184042 140734246764960 140734246763336 140728040463934 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/29220/statm: 18973 12391 853 242 0 11535 0 Current children cumulated CPU time (s) 14.06 Current children cumulated vsize (KiB) 75892 Child status: 1 Real time (s): 14.1544 CPU time (s): 14.1289 CPU user time (s): 13.9809 CPU system time (s): 0.148009 CPU usage (%): 99.8197 Max. virtual memory (cumulated for all children) (KiB): 92280 getrusage(RUSAGE_CHILDREN,...) data: user time used= 13.9809 system time used= 0.148009 maximum resident set size= 65912 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 26002 page faults= 0 swaps= 0 block input operations= 0 block output operations= 112 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 52 involuntary context switches= 230 runsolver used 0.012 second user time and 0.052003 second system time The end