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/150.runsolver.aptitude aptitude -s -y --without-recommends remove grep portmap libncurses5-dev kopete kiconedit 

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.86 0.46 2/55 20694
/proc/meminfo: memFree=240808/1022884 swapFree=0/0
[pid=20694] ppid=20693 vsize=3152 CPUtime=0
/proc/20694/stat : 20694 (runsolver) R 20693 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28223528 3227648 32 18446744073709551615 134512640 134586868 4292065504 4292063552 4151858224 0 0 0 24578 0 0 0 17 0 0 0 0 0 0
/proc/20694/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.203217 s]
/proc/loadavg: 0.99 0.86 0.46 2/55 20694
/proc/meminfo: memFree=240808/1022884 swapFree=0/0
[pid=20694] ppid=20693 vsize=89348 CPUtime=0.19
/proc/20694/stat : 20694 (aptitude) R 20693 20694 17863 34816 17863 4202496 8317 1707 0 0 17 2 0 0 20 0 1 0 28223528 91492352 7361 18446744073709551615 140625618956288 140625623263640 140736005838656 140736005828168 140625621895071 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/20694/statm: 22337 7361 4721 1052 0 2928 0
Current children cumulated CPU time (s) 0.19
Current children cumulated vsize (KiB) 89348

[startup+0.300284 s]
/proc/loadavg: 0.99 0.86 0.46 2/55 20694
/proc/meminfo: memFree=240808/1022884 swapFree=0/0
[pid=20694] ppid=20693 vsize=95900 CPUtime=0.29
/proc/20694/stat : 20694 (aptitude) R 20693 20694 17863 34816 17863 4202496 8809 1707 0 0 26 3 0 0 20 0 2 0 28223528 98201600 7443 18446744073709551615 140625618956288 140625623263640 140736005838656 140736005830640 140625576466765 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20694/statm: 23975 7443 4868 1052 0 4566 0
Current children cumulated CPU time (s) 0.29
Current children cumulated vsize (KiB) 95900

[startup+0.40026 s]
/proc/loadavg: 0.99 0.86 0.46 2/55 20694
/proc/meminfo: memFree=240808/1022884 swapFree=0/0
[pid=20694] ppid=20693 vsize=100336 CPUtime=0.38
/proc/20694/stat : 20694 (aptitude) R 20693 20694 17863 34816 17863 4202496 9984 1707 0 0 35 3 0 0 20 0 2 0 28223528 102744064 8615 18446744073709551615 140625618956288 140625623263640 140736005838656 140736005829720 140625576466759 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20694/statm: 25084 8615 4939 1052 0 5675 0
Current children cumulated CPU time (s) 0.38
Current children cumulated vsize (KiB) 100336

[startup+0.700204 s]
/proc/loadavg: 0.99 0.86 0.46 2/55 20694
/proc/meminfo: memFree=240808/1022884 swapFree=0/0
[pid=20694] ppid=20693 vsize=100468 CPUtime=0.69
/proc/20694/stat : 20694 (aptitude) R 20693 20694 17863 34816 17863 4202496 10066 1707 0 0 65 4 0 0 20 0 2 0 28223528 102879232 8695 18446744073709551615 140625618956288 140625623263640 140736005838656 140736005829120 140625577108691 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20694/statm: 25117 8695 4978 1052 0 5708 0
Current children cumulated CPU time (s) 0.69
Current children cumulated vsize (KiB) 100468

[startup+1.50025 s]
/proc/loadavg: 0.99 0.86 0.47 2/57 20699
/proc/meminfo: memFree=221332/1022884 swapFree=0/0
[pid=20694] ppid=20693 vsize=107956 CPUtime=1.48
/proc/20694/stat : 20694 (aptitude) S 20693 20694 17863 34816 17863 4202496 12048 1707 0 0 144 4 0 0 20 0 2 0 28223528 110546944 10676 18446744073709551615 140625618956288 140625623263640 140736005838656 140736005830416 140625587701323 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20694/statm: 26989 10676 5068 1052 0 7580 0
[pid=20694/tid=20699] ppid=20693 vsize=107956 CPUtime=0.37
/proc/20694/task/20699/stat : 20699 (aptitude) R 20693 20694 17863 34816 17863 4202560 1890 1707 0 0 37 0 0 0 20 0 2 0 28223558 110546944 10676 18446744073709551615 140625618956288 140625623263640 140736005838656 140625535526776 140625621632537 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 107956

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

[startup+1.60034 s]
/proc/loadavg: 0.99 0.86 0.47 2/57 20699
/proc/meminfo: memFree=221332/1022884 swapFree=0/0
[pid=20694] ppid=20693 vsize=108248 CPUtime=1.58
/proc/20694/stat : 20694 (aptitude) R 20693 20694 17863 34816 17863 4202496 12161 1707 0 0 154 4 0 0 20 0 2 0 28223528 110845952 10787 18446744073709551615 140625618956288 140625623263640 140736005838656 140736005826520 140625621163254 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20694/statm: 27062 10787 5118 1052 0 7646 0
[pid=20694/tid=20699] ppid=20693 vsize=108248 CPUtime=0.4
/proc/20694/task/20699/stat : 20699 (aptitude) S 20693 20694 17863 34816 17863 4202560 1946 1707 0 0 40 0 0 0 20 0 2 0 28223558 110845952 10787 18446744073709551615 140625618956288 140625623263640 140736005838656 140625535535104 140625587700436 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.58
Current children cumulated vsize (KiB) 108248

Child status: 255
Real time (s): 1.62958
CPU time (s): 1.6281
CPU user time (s): 1.5641
CPU system time (s): 0.064004
CPU usage (%): 99.9094
Max. virtual memory (cumulated for all children) (KiB): 108248

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 1.5641
system time used= 0.064004
maximum resident set size= 43216
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 13949
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= 74

runsolver used 0.004 second user time and 0.008 second system time

The end