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/112.runsolver.aptitude aptitude -s -y --without-recommends remove xlibmesa-glu-dev kcron libgphoto2-port0 kuser kwin4 

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.08 0.84 0.42 2/55 20427
/proc/meminfo: memFree=242268/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=54860 CPUtime=0
/proc/20427/stat : 20427 (aptitude) R 20426 20427 17863 34816 17863 4202496 411 0 0 0 0 0 0 0 20 0 1 0 28215112 56176640 311 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940785992 139837310607483 0 0 0 0 0 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 13715 311 238 1052 0 73 0

[startup+0.116668 s]
/proc/loadavg: 1.08 0.84 0.42 2/55 20427
/proc/meminfo: memFree=242268/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=80316 CPUtime=0.09
/proc/20427/stat : 20427 (aptitude) R 20426 20427 17863 34816 17863 4202496 5556 1709 0 0 8 1 0 0 20 0 1 0 28215112 82243584 5355 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940778048 139837307650533 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 20079 5355 4632 1052 0 670 0
Current children cumulated CPU time (s) 0.09
Current children cumulated vsize (KiB) 80316

[startup+0.200364 s]
/proc/loadavg: 1.08 0.84 0.42 2/55 20427
/proc/meminfo: memFree=242268/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=86100 CPUtime=0.18
/proc/20427/stat : 20427 (aptitude) R 20426 20427 17863 34816 17863 4202496 7108 1709 0 0 16 2 0 0 20 0 1 0 28215112 88166400 6899 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940778432 139837314957888 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 21525 6899 4711 1052 0 2116 0
Current children cumulated CPU time (s) 0.18
Current children cumulated vsize (KiB) 86100

[startup+0.300289 s]
/proc/loadavg: 1.08 0.84 0.42 2/55 20427
/proc/meminfo: memFree=242268/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=90632 CPUtime=0.27
/proc/20427/stat : 20427 (aptitude) R 20426 20427 17863 34816 17863 4202496 8613 1709 0 0 25 2 0 0 20 0 1 0 28215112 92807168 7658 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940776216 139837270347231 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 22658 7658 4725 1052 0 3249 0
Current children cumulated CPU time (s) 0.27
Current children cumulated vsize (KiB) 90632

[startup+0.70021 s]
/proc/loadavg: 1.08 0.84 0.42 2/55 20427
/proc/meminfo: memFree=242268/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=100336 CPUtime=0.68
/proc/20427/stat : 20427 (aptitude) R 20426 20427 17863 34816 17863 4202496 10054 1709 0 0 64 4 0 0 20 0 2 0 28215112 102744064 8684 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940777136 139837314435298 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 25084 8684 4977 1052 0 5675 0
Current children cumulated CPU time (s) 0.68
Current children cumulated vsize (KiB) 100336

[startup+1.50025 s]
/proc/loadavg: 1.08 0.84 0.42 2/57 20432
/proc/meminfo: memFree=227132/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=103596 CPUtime=1.47
/proc/20427/stat : 20427 (aptitude) S 20426 20427 17863 34816 17863 4202496 10967 1709 0 0 143 4 0 0 20 0 2 0 28215112 106082304 9596 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940778544 139837281551947 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 25899 9596 5069 1052 0 6490 0
[pid=20427/tid=20432] ppid=20426 vsize=103596 CPUtime=0.21
/proc/20427/task/20432/stat : 20432 (aptitude) R 20426 20427 17863 34816 17863 4202560 809 1709 0 0 21 0 0 0 20 0 2 0 28215153 106082304 9596 18446744073709551615 139837312806912 139837317114264 140733940786784 139837229381224 139837314441992 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) 103596

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

[startup+1.60034 s]
/proc/loadavg: 1.08 0.84 0.42 2/57 20432
/proc/meminfo: memFree=227132/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=104652 CPUtime=1.57
/proc/20427/stat : 20427 (aptitude) S 20426 20427 17863 34816 17863 4202496 11227 1709 0 0 153 4 0 0 20 0 2 0 28215112 107163648 9856 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940778544 139837281551947 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 26163 9856 5069 1052 0 6754 0
[pid=20427/tid=20432] ppid=20426 vsize=104652 CPUtime=0.31
/proc/20427/task/20432/stat : 20432 (aptitude) R 20426 20427 17863 34816 17863 4202560 1069 1709 0 0 31 0 0 0 20 0 2 0 28215153 107163648 9856 18446744073709551615 139837312806912 139837317114264 140733940786784 139837229377400 139837307630713 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) 104652

[startup+1.80026 s]
/proc/loadavg: 1.08 0.84 0.42 2/57 20432
/proc/meminfo: memFree=227132/1022884 swapFree=0/0
[pid=20427] ppid=20426 vsize=108376 CPUtime=1.77
/proc/20427/stat : 20427 (aptitude) R 20426 20427 17863 34816 17863 4202496 12187 1709 0 0 172 5 0 0 20 0 2 0 28215112 110977024 10814 18446744073709551615 139837312806912 139837317114264 140733940786784 140733940774648 139837313635962 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/20427/statm: 27094 10814 5119 1052 0 7678 0
[pid=20427/tid=20432] ppid=20426 vsize=108376 CPUtime=0.46
/proc/20427/task/20432/stat : 20432 (aptitude) S 20426 20427 17863 34816 17863 4202560 1972 1709 0 0 45 1 0 0 20 0 2 0 28215153 110977024 10814 18446744073709551615 139837312806912 139837317114264 140733940786784 139837229385728 139837281551060 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.77
Current children cumulated vsize (KiB) 108376

Child status: 0
Real time (s): 1.8538
CPU time (s): 1.84411
CPU user time (s): 1.77211
CPU system time (s): 0.072004
CPU usage (%): 99.4773
Max. virtual memory (cumulated for all children) (KiB): 108376

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 1.77211
system time used= 0.072004
maximum resident set size= 43272
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 13964
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= 78

runsolver used 0.008 second user time and 0.008 second system time

The end