Amanda-Users
[Top] [All Lists]
<prev [Date] next>
[Advanced]
 <prev [Thread] next>

planner: estimate of level 1 failed.

2007-05-21 13:20:11
Subject: planner: estimate of level 1 failed.
From: Bruce.Skinner AT norsteadfarm DOT ca (Bruce S. Skinner)
To: amanda-users AT amanda DOT org
Date: Mon, 21 May 2007 13:04:47 -0300 
I'm only getting level 0 dumps for all my disks as estimates for level
1 are failing.  I'm using gnu tar to do the dumps.  I've included some
excerpts pertaining to an example disk (/boot) from two days consecutive amdump 
log
files.  Does anyone have a suggestion why estimates may be failing or
where I should look next?

regards :-)
BruceS

============================================================
amdump: start at Sun May 20 00:05:01 ADT 2007
amdump: datestamp 20070520
============================================================

planner: pid 14881 executable /usr/lib/amanda/planner version 2.5.0p2
SETTING UP FOR ESTIMATES...
planner: time 0.001: setting up estimates for gw.n.xatl.drdc-rddc.gc.ca:/boot
driver: started dumper1 pid 14886
driver: started dumper2 pid 14887
driver: started dumper3 pid 14888
driver: started dumper4 pid 14889
driver: started dumper5 pid 14890
driver: start time 0.033 inparallel 6 bandwidth 50 diskspace 75846828 dir 
OBSOLETE datestamp 20070520 driver: drain-ends tapeq FIRST big-dumpers ttt
dumper: pid 14885 executable dumper0 version 2.5.0p2
dumper: pid 14886 executable dumper1 version 2.5.0p2
dumper: pid 14887 executable dumper2 version 2.5.0p2
dumper: pid 14888 executable dumper3 version 2.5.0p2
dumper: pid 14889 executable dumper4 version 2.5.0p2
dumper: pid 14890 executable dumper5 version 2.5.0p2
setup_estimate: gw.n.xatl.drdc-rddc.gc.ca:/boot: command 0, options: none    
last_level 0 next_level0 28 level_days 0    getting estimates 0 (-2) 1 (-2) -1 
(-2)
planner: time 0.150: setting up estimates took 0.148 secs

GETTING ESTIMATES...
planner: time 0.158: bind_portrange2: trying port=842
planner: time 0.158: dgram_bind: socket bound to 0.0.0.0.842
planner: time 0.806: got partial result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 0.935: got partial result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> 31910K, 1 -> -2K, -1 -> -2K
planner: time 0.953: got partial result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 933.988: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1000.273: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1004.592: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1007.623: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1090.864: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1092.452: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1092.838: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1092.887: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1094.993: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1095.045: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1101.443: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1101.626: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1101.638: got result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1101.639: getting estimates took 1101.488 secs

ANALYZING ESTIMATES...
pondering gw.n.xatl.drdc-rddc.gc.ca:/boot... next_level0 28 last_level 0 (not 
due for a full dump, picking an incr level)
   picklev: last night 0, so tonight level 1
  curr level 0 size 28897 total size 44214235 total_lev0 0 balanced-lev0size 
1579056
INITIAL SCHEDULE (size 44214235):
  gw.n.xatl.drdc-rddc.gc.ca /boot pri 1 lev 0 size 28897

GENERATING SCHEDULE:
--------
DUMP gw.n.xatl.drdc-rddc.gc.ca fffffeff9ffeffff07 /boot 20070520 1 0 
1970:1:1:0:0:0 28897 4 0 1970:1:1:0:0:0 28897 28
--------
driver: flush size 0
driver: send-cmd time 1101.729 to chunker0: PORT-WRITE 00-00001 
/var/spool/amanda/20070520000501/gw.n.xatl.drdc-rddc.gc.ca._boot.0 
gw.n.xatl.drdc-rddc.gc.ca fffffeff9ffeffff07 /boot 0 1970:1:1:0:0:0 1048576 
GNUTAR 28992 |;auth=BSD;srvcomp-fast;index;
driver: finished-cmd time 1105.720 dumper0 dumped 
gw.n.xatl.drdc-rddc.gc.ca:/boot
driver: finished-cmd time 1105.721 chunker0 chunked 
gw.n.xatl.drdc-rddc.gc.ca:/boot
driver: startaflush: FIRST gw.n.xatl.drdc-rddc.gc.ca /boot 28924 35840000
driver: finished-cmd time 1114.230 taper wrote gw.n.xatl.drdc-rddc.gc.ca:/boot

amdump: start at Mon May 21 00:05:01 ADT 2007
amdump: datestamp 20070521

============================================================
amdump: start at Mon May 21 00:05:01 ADT 2007
amdump: datestamp 20070521
============================================================

planner: pid 16387 executable /usr/lib/amanda/planner version 2.5.0p2
SETTING UP FOR ESTIMATES...
planner: time 0.044: setting up estimates for gw.n.xatl.drdc-rddc.gc.ca:/boot
dumper: pid 16393 executable dumper3 version 2.5.0p2
dumper: pid 16391 executable dumper1 version 2.5.0p2
dumper: pid 16390 executable dumper0 version 2.5.0p2
dumper: pid 16392 executable dumper2 version 2.5.0p2
dumper: pid 16395 executable dumper5 version 2.5.0p2
dumper: pid 16394 executable dumper4 version 2.5.0p2
setup_estimate: gw.n.xatl.drdc-rddc.gc.ca:/boot: command 0, options: none    
last_level 0 next_level0 27 level_days 0    getting estimates 0 (-2) 1 (-2) -1 
(-2)
planner: time 1.066: got partial result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 1.167: got partial result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> 31910K, 1 -> -2K, -1 -> -2K
planner: time 1.185: got partial result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1017.759: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1075.904: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1080.166: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1083.078: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1162.401: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1164.066: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1166.117: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1166.160: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1168.360: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1168.407: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1174.240: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1174.415: got partial result for host gw.n.xatl.drdc-rddc.gc.ca 
disk /boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K
planner: time 1174.421: got result for host gw.n.xatl.drdc-rddc.gc.ca disk 
/boot: 0 -> 31910K, 1 -> -1K, -1 -> -2K

ANALYZING ESTIMATES...
pondering gw.n.xatl.drdc-rddc.gc.ca:/boot... next_level0 27 last_level 0 (not 
due for a full dump, picking an incr level)
   picklev: last night 0, so tonight level 1
  curr level 0 size 28893 total size 44297288 total_lev0 0 balanced-lev0size 
1582022
INITIAL SCHEDULE (size 44297288):
  gw.n.xatl.drdc-rddc.gc.ca /boot pri 1 lev 0 size 28893

GENERATING SCHEDULE:
--------
DUMP gw.n.xatl.drdc-rddc.gc.ca fffffeff9ffeffff07 /boot 20070521 1 0 
1970:1:1:0:0:0 28893 4 0 1970:1:1:0:0:0 28893 28
--------
driver: flush size 0
driver: send-cmd time 1174.452 to chunker0: PORT-WRITE 00-00001 
/var/spool/amanda/20070521000501/gw.n.xatl.drdc-rddc.gc.ca._boot.0 
gw.n.xatl.drdc-rddc.gc.ca fffffeff9ffeffff07 /boot 0 1970:1:1:0:0:0 1048576 
GNUTAR 28960 |;auth=BSD;srvcomp-fast;index;
driver: send-cmd time 1174.533 to dumper0: PORT-DUMP 00-00001 50012 
gw.n.xatl.drdc-rddc.gc.ca fffffeff9ffeffff07 /boot /boot 0 1970:1:1:0:0:0 
GNUTAR |;auth=BSD;srvcomp-fast;index;
driver: finished-cmd time 1178.298 dumper0 dumped 
gw.n.xatl.drdc-rddc.gc.ca:/boot
driver: finished-cmd time 1178.301 chunker0 chunked 
gw.n.xatl.drdc-rddc.gc.ca:/boot
driver: send-cmd time 1178.307 to taper: FILE-WRITE 00-00002 
/var/spool/amanda/20070521000501/gw.n.xatl.drdc-rddc.gc.ca._boot.0 
gw.n.xatl.drdc-rddc.gc.ca fffffeff9ffeffff07 /boot 0 20070521 0
driver: startaflush: FIRST gw.n.xatl.drdc-rddc.gc.ca /boot 28924 35840000
driver: finished-cmd time 1184.912 taper wrote gw.n.xatl.drdc-rddc.gc.ca:/boot

-- 

Bruce Skinner
Norstead Farm
1427 Prospect Rd
Waterville NS  B0P 1V0
CANADA

 Tel: 902-538-1765
Cell: 902-670-6456

<mailto:Bruce.Skinner AT norsteadfarm DOT ca>
[More with this subject...]
<Prev in Thread] Current Thread [Next in Thread>
Previous by Date:  Amflush Slow in 2.5.2, Steven Backus
Next by Date:  Tape Retention?, Jordan Desroches
Previous by Thread:  Amflush Slow in 2.5.2, Steven Backus
Next by Thread:  Re: planner: estimate of level 1 failed., Dustin J. Mitchell
Indexes:  [Date] [Thread] [Top] [All Lists]