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/115.runsolver.aptitude aptitude -s -y --without-recommends remove libtunepimp2 kscreensaver-xsavers libfreetype6-dev kbackgammon python-gtk2 

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: 1.07 0.84 0.43 2/55 20448
/proc/meminfo: memFree=242260/1022884 swapFree=0/0
[pid=20448] ppid=20447 vsize=54860 CPUtime=0
/proc/20448/stat : 20448 (aptitude) R 20447 20448 17863 34816 17863 4202496 598 0 0 0 0 0 0 0 20 0 1 0 28215723 56176640 498 18446744073709551615 139977348083712 139977352391064 140733260385360 140733260384568 139977345894329 0 0 0 0 0 0 0 17 0 0 0 0 0 0
/proc/20448/statm: 13715 498 407 1052 0 73 0

[startup+0.184476 s]
/proc/loadavg: 1.07 0.84 0.43 2/55 20448
/proc/meminfo: memFree=242260/1022884 swapFree=0/0
[pid=20448] ppid=20447 vsize=86100 CPUtime=0.17
/proc/20448/stat : 20448 (aptitude) R 20447 20448 17863 34816 17863 4202496 7113 1709 0 0 15 2 0 0 20 0 1 0 28215723 88166400 6904 18446744073709551615 139977348083712 139977352391064 140733260385360 140733260374504 139977342937426 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/20448/statm: 21525 6904 4716 1052 0 2116 0
Current children cumulated CPU time (s) 0.17
Current children cumulated vsize (KiB) 86100

[startup+0.200298 s]
/proc/loadavg: 1.07 0.84 0.43 2/55 20448
/proc/meminfo: memFree=242260/1022884 swapFree=0/0
[pid=20448] ppid=20447 vsize=87812 CPUtime=0.18
/proc/20448/stat : 20448 (aptitude) R 20447 20448 17863 34816 17863 4202496 7781 1709 0 0 16 2 0 0 20 0 1 0 28215723 89919488 7212 18446744073709551615 139977348083712 139977352391064 140733260385360 140733260374872 139977349139604 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/20448/statm: 21953 7212 4721 1052 0 2544 0
Current children cumulated CPU time (s) 0.18
Current children cumulated vsize (KiB) 87812

[startup+0.300273 s]
/proc/loadavg: 1.07 0.84 0.43 2/55 20448
/proc/meminfo: memFree=242260/1022884 swapFree=0/0
[pid=20448] ppid=20447 vsize=90632 CPUtime=0.28
/proc/20448/stat : 20448 (aptitude) R 20447 20448 17863 34816 17863 4202496 8628 1709 0 0 26 2 0 0 20 0 1 0 28215723 92807168 7674 18446744073709551615 139977348083712 139977352391064 140733260385360 140733260374792 139977305624066 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/20448/statm: 22658 7674 4725 1052 0 3249 0
Current children cumulated CPU time (s) 0.28
Current children cumulated vsize (KiB) 90632

[startup+0.700215 s]
/proc/loadavg: 1.07 0.84 0.43 2/55 20448
/proc/meminfo: memFree=242260/1022884 swapFree=0/0
[pid=20448] ppid=20447 vsize=100468 CPUtime=0.68
/proc/20448/stat : 20448 (aptitude) R 20447 20448 17863 34816 17863 4202496 10065 1709 0 0 66 2 0 0 20 0 2 0 28215723 102879232 8695 18446744073709551615 139977348083712 139977352391064 140733260385360 140733260373272 139977351079179 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20448/statm: 25117 8695 4977 1052 0 5708 0
Current children cumulated CPU time (s) 0.68
Current children cumulated vsize (KiB) 100468

[startup+1.50026 s]
/proc/loadavg: 1.07 0.84 0.43 2/57 20453
/proc/meminfo: memFree=227124/1022884 swapFree=0/0
[pid=20448] ppid=20447 vsize=108352 CPUtime=1.47
/proc/20448/stat : 20448 (aptitude) S 20447 20448 17863 34816 17863 4202496 12148 1709 0 0 145 2 0 0 20 0 2 0 28215723 110952448 10776 18446744073709551615 139977348083712 139977352391064 140733260385360 140733260377120 139977316828747 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20448/statm: 27088 10776 5069 1052 0 7679 0
[pid=20448/tid=20453] ppid=20447 vsize=108352 CPUtime=0.37
/proc/20448/task/20453/stat : 20453 (aptitude) R 20447 20448 17863 34816 17863 4202560 1990 1709 0 0 37 0 0 0 20 0 2 0 28215753 110952448 10776 18446744073709551615 139977348083712 139977352391064 140733260385360 139977264653256 139977349712033 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.47
Current children cumulated vsize (KiB) 108352

Solver just ended. Dumping a history of the last processes samples

[startup+1.60035 s]
/proc/loadavg: 1.07 0.84 0.43 2/57 20453
/proc/meminfo: memFree=227124/1022884 swapFree=0/0
[pid=20448] ppid=20447 vsize=108776 CPUtime=1.57
/proc/20448/stat : 20448 (aptitude) R 20447 20448 17863 34816 17863 4202496 12278 1709 0 0 154 3 0 0 20 0 2 0 28215723 111386624 10904 18446744073709551615 139977348083712 139977352391064 140733260385360 140733260373224 139977350208642 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20448/statm: 27194 10904 5119 1052 0 7778 0
[pid=20448/tid=20453] ppid=20447 vsize=108776 CPUtime=0.41
/proc/20448/task/20453/stat : 20453 (aptitude) S 20447 20448 17863 34816 17863 4202560 2057 1709 0 0 41 0 0 0 20 0 2 0 28215753 111386624 10904 18446744073709551615 139977348083712 139977352391064 140733260385360 139977264662528 139977316827860 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.57
Current children cumulated vsize (KiB) 108776

Child status: 0
Real time (s): 1.6576
CPU time (s): 1.6561
CPU user time (s): 1.6041
CPU system time (s): 0.052003
CPU usage (%): 99.9097
Max. virtual memory (cumulated for all children) (KiB): 108776

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 1.6041
system time used= 0.052003
maximum resident set size= 43632
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 14054
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= 54
involuntary context switches= 75

runsolver used 0 second user time and 0.012 second system time

The end