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-lenny/logs/99.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends install filter njplot kgpg screem python2.2-musicbrainz 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: 1.00 0.99 0.91 2/55 27840 /proc/meminfo: memFree=306292/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=3152 CPUtime=0 /proc/27840/stat : 27840 (runsolver) R 27839 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28488106 3227648 32 18446744073709551615 134512640 134586868 4289311888 4289309936 4152149040 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.202513 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27840 /proc/meminfo: memFree=306292/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=35756 CPUtime=0.23 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 2404 7212 0 0 12 4 6 1 20 0 1 0 28488106 36614144 2220 18446744073709551615 4194304 5184042 140736063485968 140736063483160 139909629552946 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 8939 2220 729 242 0 1501 0 Current children cumulated CPU time (s) 0.23 Current children cumulated vsize (KiB) 35756 [startup+0.300255 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27840 /proc/meminfo: memFree=306292/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=48484 CPUtime=0.32 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 5717 7212 0 0 18 7 6 1 20 0 1 0 28488106 49647616 5525 18446744073709551615 4194304 5184042 140736063485968 140736063480408 139909638355437 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 12121 5525 835 242 0 4683 0 Current children cumulated CPU time (s) 0.32 Current children cumulated vsize (KiB) 48484 [startup+0.400248 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27840 /proc/meminfo: memFree=306292/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=57188 CPUtime=0.42 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 7849 7212 0 0 27 8 6 1 20 0 1 0 28488106 58560512 7657 18446744073709551615 4194304 5184042 140736063485968 140736063483272 139909645886464 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 14297 7657 842 242 0 6859 0 Current children cumulated CPU time (s) 0.42 Current children cumulated vsize (KiB) 57188 [startup+0.700179 s] /proc/loadavg: 1.00 0.99 0.91 2/55 27840 /proc/meminfo: memFree=306292/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=59612 CPUtime=0.72 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 8559 7212 0 0 57 8 6 1 20 0 1 0 28488106 61042688 8315 18446744073709551615 4194304 5184042 140736063485968 140736063483256 139909645657776 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 14903 8315 842 242 0 7465 0 Current children cumulated CPU time (s) 0.72 Current children cumulated vsize (KiB) 59612 [startup+1.50019 s] /proc/loadavg: 1.00 0.99 0.91 2/56 27860 /proc/meminfo: memFree=274548/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=61624 CPUtime=1.52 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 9290 7212 0 0 135 10 6 1 20 0 1 0 28488106 63102976 8750 18446744073709551615 4194304 5184042 140736063485968 140736063483416 139909645833230 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 15406 8750 842 242 0 7968 0 Current children cumulated CPU time (s) 1.52 Current children cumulated vsize (KiB) 61624 [startup+3.10022 s] /proc/loadavg: 1.00 0.99 0.91 2/56 27860 /proc/meminfo: memFree=268968/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=69900 CPUtime=3.12 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 11687 7212 0 0 294 11 6 1 20 0 1 0 28488106 71577600 10377 18446744073709551615 4194304 5184042 140736063485968 140736063483416 139909629544080 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 17475 10377 842 242 0 10037 0 Current children cumulated CPU time (s) 3.12 Current children cumulated vsize (KiB) 69900 Solver just ended. Dumping a history of the last processes samples [startup+3.30019 s] /proc/loadavg: 1.00 0.99 0.91 2/56 27860 /proc/meminfo: memFree=268968/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=69900 CPUtime=3.32 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 11722 7212 0 0 314 11 6 1 20 0 1 0 28488106 71577600 10412 18446744073709551615 4194304 5184042 140736063485968 140736063483416 139909645886464 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 17475 10412 842 242 0 10037 0 Current children cumulated CPU time (s) 3.32 Current children cumulated vsize (KiB) 69900 [startup+3.70021 s] /proc/loadavg: 1.00 0.99 0.91 2/56 27860 /proc/meminfo: memFree=267480/1022884 swapFree=0/0 [pid=27840] ppid=27839 vsize=69900 CPUtime=3.71 /proc/27840/stat : 27840 (cupt) R 27839 27840 17872 34816 17872 4202496 11785 7212 0 0 353 11 6 1 20 0 1 0 28488106 71577600 10475 18446744073709551615 4194304 5184042 140736063485968 140736063480232 139909645904788 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/27840/statm: 17475 10475 842 242 0 10037 0 Current children cumulated CPU time (s) 3.71 Current children cumulated vsize (KiB) 69900 Child status: 1 Real time (s): 3.79963 CPU time (s): 3.82024 CPU user time (s): 3.68423 CPU system time (s): 0.136008 CPU usage (%): 100.542 Max. virtual memory (cumulated for all children) (KiB): 69900 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.68423 system time used= 0.136008 maximum resident set size= 43560 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 19032 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= 52 involuntary context switches= 72 runsolver used 0.004 second user time and 0.016001 second system time The end