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/logs/162.runsolver.aptitude aptitude -s -y --without-recommends remove mount kwordquiz libopencdk8 autoconf libxrender1 

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.65 0.21 0.07 2/55 19195
/proc/meminfo: memFree=284308/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=3152 CPUtime=0
/proc/19195/stat : 19195 (runsolver) R 19194 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28168184 3227648 32 18446744073709551615 134512640 134586868 4294297632 4294295680 4151358512 0 0 0 24578 0 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.162938 s]
/proc/loadavg: 0.65 0.21 0.07 2/55 19195
/proc/meminfo: memFree=284308/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=78400 CPUtime=0.14
/proc/19195/stat : 19195 (aptitude) R 19194 19195 17863 34816 17863 4202496 5371 1709 0 0 13 1 0 0 20 0 1 0 28168184 80281600 4993 18446744073709551615 140552717062144 140552721369496 140737390058704 140737390048136 140552685818224 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 19600 4993 2972 1052 0 1943 0
Current children cumulated CPU time (s) 0.14
Current children cumulated vsize (KiB) 78400

[startup+0.200293 s]
/proc/loadavg: 0.65 0.21 0.07 2/55 19195
/proc/meminfo: memFree=284308/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=88340 CPUtime=0.18
/proc/19195/stat : 19195 (aptitude) R 19194 19195 17863 34816 17863 4202496 6198 1709 0 0 16 2 0 0 20 0 2 0 28168184 90460160 5617 18446744073709551615 140552717062144 140552721369496 140737390058704 140737390049976 140552719264127 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 22085 5617 3186 1052 0 4428 0
Current children cumulated CPU time (s) 0.18
Current children cumulated vsize (KiB) 88340

[startup+0.300277 s]
/proc/loadavg: 0.65 0.21 0.07 2/55 19195
/proc/meminfo: memFree=284308/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=88340 CPUtime=0.28
/proc/19195/stat : 19195 (aptitude) R 19194 19195 17863 34816 17863 4202496 6255 1709 0 0 26 2 0 0 20 0 2 0 28168184 90460160 5673 18446744073709551615 140552717062144 140552721369496 140737390058704 140737390049056 140552718691120 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 22085 5673 3222 1052 0 4428 0
Current children cumulated CPU time (s) 0.28
Current children cumulated vsize (KiB) 88340

[startup+0.700204 s]
/proc/loadavg: 0.65 0.21 0.07 2/55 19195
/proc/meminfo: memFree=284308/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=92076 CPUtime=0.68
/proc/19195/stat : 19195 (aptitude) S 19194 19195 17863 34816 17863 4202496 7295 1709 0 0 66 2 0 0 20 0 2 0 28168184 94285824 6711 18446744073709551615 140552717062144 140552721369496 140737390058704 140737390050464 140552685807179 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 23019 6711 3312 1052 0 5362 0
Current children cumulated CPU time (s) 0.68
Current children cumulated vsize (KiB) 92076

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

[startup+0.800273 s]
/proc/loadavg: 0.65 0.21 0.07 2/55 19195
/proc/meminfo: memFree=284308/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=93928 CPUtime=0.78
/proc/19195/stat : 19195 (aptitude) S 19194 19195 17863 34816 17863 4202496 7749 1709 0 0 76 2 0 0 20 0 2 0 28168184 96182272 7165 18446744073709551615 140552717062144 140552721369496 140737390058704 140737390050464 140552685807179 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 23482 7165 3312 1052 0 5825 0
Current children cumulated CPU time (s) 0.78
Current children cumulated vsize (KiB) 93928

[startup+1.20323 s]
/proc/loadavg: 0.65 0.21 0.07 2/57 19200
/proc/meminfo: memFree=264336/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=99472 CPUtime=1.18
/proc/19195/stat : 19195 (aptitude) S 19194 19195 17863 34816 17863 4202496 9132 1709 0 0 115 3 0 0 20 0 2 0 28168184 101859328 8548 18446744073709551615 140552717062144 140552721369496 140737390058704 140737390050464 140552685807179 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 24868 8548 3316 1052 0 7211 0
[pid=19195/tid=19200] ppid=19194 vsize=99472 CPUtime=0.61
/proc/19195/task/19200/stat : 19200 (aptitude) R 19194 19195 17863 34816 17863 4202560 2793 1709 0 0 60 1 0 0 20 0 2 0 28168201 101859328 8548 18446744073709551615 140552717062144 140552721369496 140737390058704 140552641226616 140552719692447 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.18
Current children cumulated vsize (KiB) 99472

[startup+1.30026 s]
/proc/loadavg: 0.65 0.21 0.07 2/57 19200
/proc/meminfo: memFree=264336/1022884 swapFree=0/0
[pid=19195] ppid=19194 vsize=100028 CPUtime=1.28
/proc/19195/stat : 19195 (aptitude) R 19194 19195 17863 34816 17863 4202496 9310 1709 0 0 125 3 0 0 20 0 2 0 28168184 102428672 8724 18446744073709551615 140552717062144 140552721369496 140737390058704 140737390046568 140552719187101 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19195/statm: 25007 8724 3366 1052 0 7343 0
[pid=19195/tid=19200] ppid=19194 vsize=100028 CPUtime=0.68
/proc/19195/task/19200/stat : 19200 (aptitude) S 19194 19195 17863 34816 17863 4202560 2917 1709 0 0 67 1 0 0 20 0 2 0 28168201 102428672 8724 18446744073709551615 140552717062144 140552721369496 140737390058704 140552641230848 140552685806292 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.28
Current children cumulated vsize (KiB) 100028

Child status: 0
Real time (s): 1.33888
CPU time (s): 1.33608
CPU user time (s): 1.28808
CPU system time (s): 0.048003
CPU usage (%): 99.7914
Max. virtual memory (cumulated for all children) (KiB): 100028

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 1.28808
system time used= 0.048003
maximum resident set size= 34896
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 11085
page faults= 0
swaps= 0
block input operations= 0
block output operations= 24
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 56
involuntary context switches= 71

runsolver used 0 second user time and 0.012 second system time

The end