root@backup DailySet1]# cat amdump
amdump: start at Fri Jul 13 15:03:48 CDT 2007
amdump: datestamp 20070713
planner: pid 18842 executable /usr/lib/amanda/planner version 2.5.0p2
planner: build: VERSION="Amanda-2.5.0p2"
planner:        BUILT_DATE="Fri May 12 11:30:10 PDT 2006"
planner:        BUILT_MACH="Linux SLES92-VM-2 2.6.12.6-xen3_2.1_sles9sp2 #1 SMP Sun Dec 4 11:14:10 PST 2005 i686 i686 i386 GNU/Linux"
planner:        CC="gcc"
planner:        CONFIGURE_COMMAND="'./configure' '--build=i586-suse-linux' '--prefix=/usr' '--bindir=/usr/bin' '--sbindir=/usr/sbin' '--libexecdir=/usr/lib/amanda' '--datadir=/usr/share' '--sysconfdir=/etc' '--sharedstatedir=/var/lib/amanda' '--localstatedir=/var/lib/amanda' '--libdir=/usr/lib' '--includedir=/usr/include' '--infodir=/usr/info' '--mandir=/usr/share/man' '--with-gnutar-listdir=/var/lib/amanda/gnutar-lists' '--with-dumperdir=/usr/lib/amanda' '--with-index-server=localhost' '--with-tape-server=localhost' '--with-user=amandabackup' '--with-group=disk' '--with-owner=paddy' '--with-fqdn'"
planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
planner:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
planner:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
planner:        CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
planner:        RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
planner:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
planner:        XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF
planner:        SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
planner:        COMPRESS_PATH="/usr/bin/gzip"
planner:        UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr"
planner:        MAILER="/usr/bin/Mail"
planner:        listed_incr_dir="/var/lib/amanda/gnutar-lists"
planner: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
planner:        DEFAULT_TAPE_SERVER="localhost"
planner:        DEFAULT_TAPE_DEVICE="null:" HAVE_MMAP HAVE_SYSVSHM
planner:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
planner:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS
planner:        CLIENT_LOGIN="amandabackup" FORCE_USERID HAVE_GZIP
planner:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
planner:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
READING CONF FILES...
planner: time 0.001: startup took 0.001 secs

SENDING FLUSHES...
ENDFLUSH

SETTING UP FOR ESTIMATES...
planner: time 0.001: setting up estimates for fw.fnsi.com.mx:/etc/xinetd.d
fw.fnsi.com.mx:/etc/xinetd.d overdue 13708 days for level 0
setup_estimate: fw.fnsi.com.mx:/etc/xinetd.d: command 0, options: none    last_level -1 next_level0 -13708 level_days 0    getting estimates 0 (-2) -1 (-2) -1 (-2)
planner: time 0.001: setting up estimates took 0.000 secs

GETTING ESTIMATES...
planner: time 0.015: bind_portrange2: trying port=591
planner: time 0.015: dgram_bind: socket bound to 0.0.0.0.591
driver: pid 18843 executable /usr/lib/amanda/driver version 2.5.0p2
driver: tape size 163840000
taper: pid 18844 executable taper version 2.5.0p2
taper: page size is 4096
taper: buffer size is 32768
taper: buffer[00] at 0xb7f20000
taper: buffer[01] at 0xb7f28000
taper: buffer[02] at 0xb7f30000
taper: buffer[03] at 0xb7f38000
taper: buffer[04] at 0xb7f40000
taper: buffer[05] at 0xb7f48000
taper: buffer[06] at 0xb7f50000
taper: buffer[07] at 0xb7f58000
taper: buffer[08] at 0xb7f60000
taper: buffer[09] at 0xb7f68000
taper: buffer[10] at 0xb7f70000
taper: buffer[11] at 0xb7f78000
taper: buffer[12] at 0xb7f80000
taper: buffer[13] at 0xb7f88000
taper: buffer[14] at 0xb7f90000
taper: buffer[15] at 0xb7f98000
taper: buffer[16] at 0xb7fa0000
taper: buffer[17] at 0xb7fa8000
taper: buffer[18] at 0xb7fb0000
taper: buffer[19] at 0xb7fb8000
taper: buffer structures at 0xb7fc0000 for 240 bytes
driver: send-cmd time 0.005 to taper: START-TAPER 20070713
driver: adding holding disk 0 dir /dumps/amanda size 194797896 chunksize 1048576
reserving 194797896 out of 194797896 for degraded-mode dumps
dumper: pid 18846 executable dumper0 version 2.5.0p2
driver: started dumper0 pid 18846
dumper: pid 18847 executable dumper1 version 2.5.0p2
driver: started dumper1 pid 18847
dumper: pid 18848 executable dumper2 version 2.5.0p2
driver: started dumper2 pid 18848
driver: started dumper3 pid 18849
driver: start time 0.018 inparallel 4 bandwidth 2000 diskspace 194797896 dir OBSOLETE datestamp 20070713 driver: drain-ends tapeq FIRST big-dumpers sssS
changer: opening pipe to: /usr/lib/amanda/chg-disk -info
planner: time 0.043: got partial result for host fw.fnsi.com.mx disk /etc/xinetd.d: 0 -> -2K, -1 -> -2K, -1 -> -2K
dumper: pid 18849 executable dumper3 version 2.5.0p2
changer: got exit: 0 str: 1 25 1
changer_query: changer return was 25 1
changer_query: searchable = 0
changer_find: looking for NULL changer is searchable = 0
changer: opening pipe to: /usr/lib/amanda/chg-disk -slot current
changer: got exit: 0 str: 1 file://space1/vtapes/DailySet1/slots
changer: opening pipe to: /usr/lib/amanda/chg-disk -slot next
changer: got exit: 0 str: 2 file://space1/vtapes/DailySet1/slots
taper: slot: 2 wrote label `DailySet1-02' date `20070713'
driver: result time 0.096 from taper: TAPER-OK 
driver: state time 0.096 free kps: 2000 space: 194797896 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 0.096 if : free 600 if LE0: free 400 if LOCAL: free 1000
driver: hdisk-state time 0.096 hdisk 0: free 194797896 dumpers 0
planner: time 0.159: got partial result for host fw.fnsi.com.mx disk /etc/xinetd.d: 0 -> 30K, -1 -> -2K, -1 -> -2K
planner: time 0.160: got result for host fw.fnsi.com.mx disk /etc/xinetd.d: 0 -> 30K, -1 -> -2K, -1 -> -2K
planner: time 0.160: getting estimates took 0.158 secs
FAILED QUEUE: empty
DONE QUEUE:
  0: fw.fnsi.com.mx /etc/xinetd.d

ANALYZING ESTIMATES...
pondering fw.fnsi.com.mx:/etc/xinetd.d... next_level0 -13708 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 30 total size 3126 total_lev0 30 balanced-lev0size 6
INITIAL SCHEDULE (size 3126):
  fw.fnsi.com.mx /etc/xinetd.d pri 13710 lev 0 size 30

DELAYING DUMPS IF NEEDED, total_size 3126, tape length 163840000 mark 1000
  delay: Total size now 3126.

PROMOTING DUMPS IF NEEDED, total_lev0 30, balanced_size 6...
planner: time 0.160: analysis took 0.000 secs

GENERATING SCHEDULE:
--------
DUMP fw.fnsi.com.mx fffffeff9ffeffff07 /etc/xinetd.d 20070713 13710 0 1970:1:1:0:0:0 30 0
--------
driver: flush size 0
driver: started chunker0 pid 18896
driver: send-cmd time 0.144 to chunker0: START 20070713
driver: send-cmd time 0.144 to chunker0: PORT-WRITE 00-00001 /dumps/amanda/20070713150348/fw.fnsi.com.mx._etc_xinetd.d.0 fw.fnsi.com.mx fffffeff9ffeffff07 /etc/xinetd.d 0 1970:1:1:0:0:0 1048576 GNUTAR 96 |;auth=bsd;index;
chunker: pid 18896 executable chunker0 version 2.5.0p2
chunker: try_socksize: receive buffer size is 65536
chunker: bind_portrange2: trying port=659
chunker: stream_server: waiting for connection: 0.0.0.0.44114
driver: result time 0.169 from chunker0: PORT 44114
driver: send-cmd time 0.169 to dumper0: PORT-DUMP 00-00001 44114 fw.fnsi.com.mx fffffeff9ffeffff07 /etc/xinetd.d NODEVICE 0 1970:1:1:0:0:0 GNUTAR |;auth=bsd;index;
dumper: stream_client: gethostbyname(localhost) failed
driver: state time 0.170 free kps: 1990 space: 194797800 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 0.170 if : free 600 if LE0: free 400 if LOCAL: free 990
driver: hdisk-state time 0.170 hdisk 0: free 194797800 dumpers 1
driver: result time 0.170 from dumper0: FAILED 00-00001 [taper port open: Resource temporarily unavailable]
driver: send-cmd time 0.170 to chunker0: FAILED 00-00001