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/80.runsolver.aptitude aptitude -s -y --without-recommends install xscreensaver-nognome amanda-server libtemplate-plugin-calendar-simple-perl libmm13 gap-core 

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.73 0.34 2/55 19935
/proc/meminfo: memFree=244332/1022884 swapFree=0/0
[pid=19935] ppid=19934 vsize=3152 CPUtime=0
/proc/19935/stat : 19935 (runsolver) R 19934 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28202278 3227648 32 18446744073709551615 134512640 134586868 4290376432 4290374480 4152140848 0 0 0 24578 0 0 0 17 0 0 0 0 0 0
/proc/19935/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.116299 s]
/proc/loadavg: 0.99 0.73 0.34 2/55 19935
/proc/meminfo: memFree=244332/1022884 swapFree=0/0
[pid=19935] ppid=19934 vsize=80316 CPUtime=0.1
/proc/19935/stat : 19935 (aptitude) R 19934 19935 17863 34816 17863 4202496 5568 1708 0 0 9 1 0 0 20 0 1 0 28202278 82243584 5367 18446744073709551615 139942619537408 139942623844760 140736499144864 140736499136128 139942614937463 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/19935/statm: 20079 5367 4644 1052 0 670 0
Current children cumulated CPU time (s) 0.1
Current children cumulated vsize (KiB) 80316

[startup+0.200279 s]
/proc/loadavg: 0.99 0.73 0.34 2/55 19935
/proc/meminfo: memFree=244332/1022884 swapFree=0/0
[pid=19935] ppid=19934 vsize=89348 CPUtime=0.18
/proc/19935/stat : 19935 (aptitude) R 19934 19935 17863 34816 17863 4202496 8317 1708 0 0 17 1 0 0 20 0 1 0 28202278 91492352 7362 18446744073709551615 139942619537408 139942623844760 140736499144864 140736499134376 139942622476191 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0
/proc/19935/statm: 22337 7362 4721 1052 0 2928 0
Current children cumulated CPU time (s) 0.18
Current children cumulated vsize (KiB) 89348

[startup+0.300258 s]
/proc/loadavg: 0.99 0.73 0.34 2/55 19935
/proc/meminfo: memFree=244332/1022884 swapFree=0/0
[pid=19935] ppid=19934 vsize=96684 CPUtime=0.29
/proc/19935/stat : 19935 (aptitude) R 19934 19935 17863 34816 17863 4202496 9022 1708 0 0 27 2 0 0 20 0 2 0 28202278 99004416 7656 18446744073709551615 139942619537408 139942623844760 140736499144864 140736499140648 139942614361622 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19935/statm: 24171 7656 4890 1052 0 4762 0
Current children cumulated CPU time (s) 0.29
Current children cumulated vsize (KiB) 96684

[startup+0.700195 s]
/proc/loadavg: 0.99 0.73 0.34 2/55 19935
/proc/meminfo: memFree=244332/1022884 swapFree=0/0
[pid=19935] ppid=19934 vsize=100608 CPUtime=0.68
/proc/19935/stat : 19935 (aptitude) R 19934 19935 17863 34816 17863 4202496 10120 1708 0 0 66 2 0 0 20 0 2 0 28202278 103022592 8749 18446744073709551615 139942619537408 139942623844760 140736499144864 140736499132984 139942620949208 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19935/statm: 25152 8749 4995 1052 0 5743 0
Current children cumulated CPU time (s) 0.68
Current children cumulated vsize (KiB) 100608

[startup+1.50024 s]
/proc/loadavg: 0.99 0.73 0.34 2/57 19941
/proc/meminfo: memFree=228576/1022884 swapFree=0/0
[pid=19935] ppid=19934 vsize=109948 CPUtime=1.49
/proc/19935/stat : 19935 (aptitude) S 19934 19935 17863 34816 17863 4202496 13564 2206 0 0 144 4 1 0 20 0 2 0 28202278 112586752 11206 18446744073709551615 139942619537408 139942623844760 140736499144864 140736499136624 139942588282443 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19935/statm: 27487 11206 5102 1052 0 8078 0
[pid=19935/tid=19940] ppid=19934 vsize=109948 CPUtime=0.36
/proc/19935/task/19940/stat : 19940 (aptitude) R 19934 19935 17863 34816 17863 4202560 2915 2206 0 0 34 1 1 0 20 0 2 0 28202307 112586752 11206 18446744073709551615 139942619537408 139942623844760 140736499144864 139942536107544 139942577037675 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 109948

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

[startup+1.60032 s]
/proc/loadavg: 0.99 0.73 0.34 2/57 19941
/proc/meminfo: memFree=228576/1022884 swapFree=0/0
[pid=19935] ppid=19934 vsize=110240 CPUtime=1.59
/proc/19935/stat : 19935 (aptitude) R 19934 19935 17863 34816 17863 4202496 15027 2206 0 0 154 4 1 0 20 0 2 0 28202278 112885760 11331 18446744073709551615 139942619537408 139942623844760 140736499144864 140736499132728 139942621743783 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/19935/statm: 27560 11331 5151 1052 0 8144 0
[pid=19935/tid=19940] ppid=19934 vsize=110240 CPUtime=0.37
/proc/19935/task/19940/stat : 19940 (aptitude) S 19934 19935 17863 34816 17863 4202560 2963 2206 0 0 35 1 1 0 20 0 2 0 28202307 112885760 11331 18446744073709551615 139942619537408 139942623844760 140736499144864 139942536116224 139942588281556 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.59
Current children cumulated vsize (KiB) 110240

Child status: 0
Real time (s): 1.64492
CPU time (s): 1.6441
CPU user time (s): 1.5721
CPU system time (s): 0.072004
CPU usage (%): 99.9505
Max. virtual memory (cumulated for all children) (KiB): 110240

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 1.5721
system time used= 0.072004
maximum resident set size= 45336
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 17944
page faults= 0
swaps= 0
block input operations= 0
block output operations= 8
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 60
involuntary context switches= 79

runsolver used 0.012 second user time and 0 second system time

The end