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/77.runsolver.aptitude aptitude -s -y --without-recommends install python2.1-iconvcodec prelude-lml fastlink dict-gcide tigr-glimmer 

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: 0.99 0.72 0.33 2/55 19908
/proc/meminfo: memFree=244472/1022884 swapFree=0/0
[pid=19908] ppid=19907 vsize=54860 CPUtime=0
/proc/19908/stat : 19908 (aptitude) R 19907 19908 17863 34816 17863 4202496 415 0 0 0 0 0 0 0 20 0 1 0 28201187 56176640 314 18446744073709551615 139999841181696 139999845489048 140735310963648 140735310962856 139999838991438 0 0 0 0 0 0 0 17 0 0 0 0 0 0
/proc/19908/statm: 13715 314 242 1052 0 73 0

[startup+0.160004 s]
/proc/loadavg: 0.99 0.72 0.33 2/55 19908
/proc/meminfo: memFree=244472/1022884 swapFree=0/0
[pid=19908] ppid=19907 vsize=86100 CPUtime=0.14
/proc/19908/stat : 19908 (aptitude) R 19907 19908 17863 34816 17863 4202496 7110 1708 0 0 12 2 0 0 20 0 1 0 28201187 88166400 6898 18446744073709551615 139999841181696 139999845489048 140735310963648 140735310955296 139999843332705 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/19908/statm: 21525 6898 4711 1052 0 2116 0
Current children cumulated CPU time (s) 0.14
Current children cumulated vsize (KiB) 86100

[startup+0.200283 s]
/proc/loadavg: 0.99 0.72 0.33 2/55 19908
/proc/meminfo: memFree=244472/1022884 swapFree=0/0
[pid=19908] ppid=19907 vsize=87812 CPUtime=0.18
/proc/19908/stat : 19908 (aptitude) R 19907 19908 17863 34816 17863 4202496 7836 1708 0 0 16 2 0 0 20 0 1 0 28201187 89919488 7263 18446744073709551615 139999841181696 139999845489048 140735310963648 140735310953160 139999844106097 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/19908/statm: 21953 7263 4721 1052 0 2544 0
Current children cumulated CPU time (s) 0.18
Current children cumulated vsize (KiB) 87812

[startup+0.300265 s]
/proc/loadavg: 0.99 0.72 0.33 2/55 19908
/proc/meminfo: memFree=244472/1022884 swapFree=0/0
[pid=19908] ppid=19907 vsize=95900 CPUtime=0.29
/proc/19908/stat : 19908 (aptitude) R 19907 19908 17863 34816 17863 4202496 8811 1708 0 0 25 4 0 0 20 0 2 0 28201187 98201600 7443 18446744073709551615 139999841181696 139999845489048 140735310963648 140735310955632 139999814759092 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19908/statm: 23975 7443 4868 1052 0 4566 0
Current children cumulated CPU time (s) 0.29
Current children cumulated vsize (KiB) 95900

[startup+0.700209 s]
/proc/loadavg: 0.99 0.72 0.33 2/55 19908
/proc/meminfo: memFree=244472/1022884 swapFree=0/0
[pid=19908] ppid=19907 vsize=100624 CPUtime=0.69
/proc/19908/stat : 19908 (aptitude) R 19907 19908 17863 34816 17863 4202496 10122 1708 0 0 65 4 0 0 20 0 2 0 28201187 103038976 8749 18446744073709551615 139999841181696 139999845489048 140735310963648 140735310950520 139999807491064 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19908/statm: 25156 8749 4994 1052 0 5747 0
Current children cumulated CPU time (s) 0.69
Current children cumulated vsize (KiB) 100624

[startup+1.50025 s]
/proc/loadavg: 0.99 0.72 0.33 2/57 19914
/proc/meminfo: memFree=228840/1022884 swapFree=0/0
[pid=19908] ppid=19907 vsize=106956 CPUtime=1.48
/proc/19908/stat : 19908 (aptitude) R 19907 19908 17863 34816 17863 4202496 14222 2206 0 0 143 4 1 0 20 0 2 0 28201187 109522944 10483 18446744073709551615 139999841181696 139999845489048 140735310963648 140735310951512 139999835998304 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19908/statm: 26739 10483 5151 1052 0 7323 0
[pid=19908/tid=19913] ppid=19907 vsize=106956 CPUtime=0.27
/proc/19908/task/19913/stat : 19913 (aptitude) S 19907 19908 17863 34816 17863 4202560 2144 2206 0 0 26 0 1 0 20 0 2 0 28201217 109522944 10483 18446744073709551615 139999841181696 139999845489048 140735310963648 139999757760512 139999809925844 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) 106956

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

[startup+1.50025 s]
/proc/loadavg: 0.99 0.72 0.33 2/57 19914
/proc/meminfo: memFree=228840/1022884 swapFree=0/0
[pid=19908] ppid=19907 vsize=106956 CPUtime=1.48
/proc/19908/stat : 19908 (aptitude) R 19907 19908 17863 34816 17863 4202496 14222 2206 0 0 143 4 1 0 20 0 2 0 28201187 109522944 10483 18446744073709551615 139999841181696 139999845489048 140735310963648 140735310951512 139999835998304 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19908/statm: 26739 10483 5151 1052 0 7323 0
[pid=19908/tid=19913] ppid=19907 vsize=106956 CPUtime=0.27
/proc/19908/task/19913/stat : 19913 (aptitude) S 19907 19908 17863 34816 17863 4202560 2144 2206 0 0 26 0 1 0 20 0 2 0 28201217 109522944 10483 18446744073709551615 139999841181696 139999845489048 140735310963648 139999757760512 139999809925844 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) 106956

Child status: 0
Real time (s): 1.57661
CPU time (s): 1.5721
CPU user time (s): 1.49609
CPU system time (s): 0.076004
CPU usage (%): 99.7135
Max. virtual memory (cumulated for all children) (KiB): 106956

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 1.49609
system time used= 0.076004
maximum resident set size= 41944
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 17141
page faults= 0
swaps= 0
block input operations= 0
block output operations= 16
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 60
involuntary context switches= 78

runsolver used 0.004 second user time and 0.008 second system time

The end