amdump: start at Mon Jan 13 13:39:11 EST 2003 amdump: datestamp 20030113 planner: pid 7102 executable /usr/local/libexec/planner version 2.4.3 planner: build: VERSION="Amanda-2.4.3" planner: BUILT_DATE="Thu Jan 9 11:25:19 EST 2003" planner: BUILT_MACH="Linux emailbkup 2.4.18-14 #1 Wed Sep 4 13:35:50 EDT 2002 i686 i686 i386 GNU/Linux" planner: CC="gcc" planner: CONFIGURE_COMMAND="'./configure' '--with-user=root' '--with-group=root'" planner: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin" planner: libexecdir="/usr/local/libexec" mandir="/usr/local/man" planner: AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda" planner: CONFIG_DIR="/usr/local/etc/amanda" DEV_PREFIX="/dev/" planner: RDEV_PREFIX="/dev/" DUMP="/sbin/dump" planner: RESTORE="/sbin/restore" SAMBA_CLIENT="/usr/bin/smbclient" planner: GNUTAR="/bin/gtar" COMPRESS_PATH="/bin/gzip" planner: UNCOMPRESS_PATH="/bin/gzip" MAILER="/usr/bin/Mail" planner: listed_incr_dir="/usr/local/var/amanda/gnutar-lists" planner: defs: DEFAULT_SERVER="emailbkup" DEFAULT_CONFIG="DailySet1" planner: DEFAULT_TAPE_SERVER="emailbkup" planner: DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM planner: LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE planner: AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS planner: CLIENT_LOGIN="root" FORCE_USERID HAVE_GZIP planner: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast" planner: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc" planner: time 0.000: dgram_bind: socket bound to 0.0.0.0.909 READING CONF FILES... planner: time 0.029: startup took 0.029 secs SETTING UP FOR ESTIMATES... planner: time 0.029: setting up estimates for emailbkup:/home emailbkup:/home overdue 12066 days for level 0 setup_estimate: emailbkup:/home: command 0, options: last_level -1 next_level0 -12066 level_days 0 getting estimates 0 (0) -1 (-1) -1 (-1) planner: time 0.029: setting up estimates took 0.000 secs GETTING ESTIMATES... driver: pid 7103 executable /usr/local/libexec/driver version 2.4.3 driver: send-cmd time 0.036 to taper: START-TAPER 20030113 driver: started dumper0 pid 7105 driver: started dumper1 pid 7106 driver: started dumper2 pid 7107 driver: started dumper3 pid 7108 dumper: dgram_bind: socket bound to 0.0.0.0.915 dumper: pid 7108 executable dumper3 version 2.4.3, using port 915 dumper: dgram_bind: socket bound to 0.0.0.0.914 dumper: pid 7107 executable dumper2 version 2.4.3, using port 914 dumper: dgram_bind: socket bound to 0.0.0.0.913 dumper: pid 7106 executable dumper1 version 2.4.3, using port 913 dumper: dgram_bind: socket bound to 0.0.0.0.912 dumper: pid 7105 executable dumper0 version 2.4.3, using port 912 taper: pid 7104 executable taper version 2.4.3 taper: page size is 4096 taper: buffer size is 32768 taper: buffer[00] at 0x4008c000 taper: buffer[01] at 0x40094000 taper: buffer[02] at 0x4009c000 taper: buffer[03] at 0x400a4000 taper: buffer[04] at 0x400ac000 taper: buffer[05] at 0x400b4000 taper: buffer[06] at 0x400bc000 taper: buffer[07] at 0x400c4000 taper: buffer[08] at 0x400cc000 taper: buffer[09] at 0x400d4000 taper: buffer[10] at 0x400dc000 taper: buffer[11] at 0x400e4000 taper: buffer[12] at 0x400ec000 taper: buffer[13] at 0x400f4000 taper: buffer[14] at 0x400fc000 taper: buffer[15] at 0x40104000 taper: buffer[16] at 0x4010c000 taper: buffer[17] at 0x40114000 taper: buffer[18] at 0x4011c000 taper: buffer[19] at 0x40124000 taper: buffer structures at 0x4012c000 for 240 bytes changer: opening pipe to: /usr/local/libexec/chg-multi -info changer: got exit: 0 str: 1 20 1 changer: opening pipe to: /usr/local/libexec/chg-multi -slot current planner: time 1.589: got result for host emailbkup disk /home: 0 -> 335K, -1 -> -1K, -1 -> -1K planner: time 1.589: getting estimates took 1.560 secs FAILED QUEUE: empty DONE QUEUE: 0: emailbkup /home ANALYZING ESTIMATES... pondering emailbkup:/home... next_level0 -12066 last_level -1 (due for level 0) (new disk, can't switch to degraded mode) curr level 0 size 167 total size 2345 total_lev0 167 balanced-lev0size 33 INITIAL SCHEDULE (size 2345): emailbkup /home pri 12068 lev 0 size 167 DELAYING DUMPS IF NEEDED, total_size 2345, tape length 19374080 mark 694 delay: Total size now 2345. PROMOTING DUMPS IF NEEDED, total_lev0 167, balanced_size 33... planner: time 1.590: analysis took 0.000 secs GENERATING SCHEDULE: -------- ENDFLUSH DUMP emailbkup fffffeff9f00 /home 20030113 12068 0 1970:1:1:0:0:0 167 5 -------- driver: adding holding disk 0 dir /backups size 3145728 reserving 3145728 out of 3145728 for degraded-mode dumps driver: start time 1.594 inparallel 4 bandwidth 7400 diskspace 3145728 dir OBSOLETE datestamp 20030113 driver: drain-ends tapeq LFFO big-dumpers ttt changer: got exit: 1 str: 1 chg-multi: slot is empty taper: slot 1: chg-multi: slot is empty changer: opening pipe to: /usr/local/libexec/chg-multi -slot next changer: got exit: 2 str: 2 chg-multi: /dev/nst0: unable to change to slot 1 taper: fatal slot 2: chg-multi: /dev/nst0: unable to change to slot 1 driver: result time 60.859 from taper: TAPE-ERROR [new tape not found in rack] dump of driver schedule before start degraded mode: -------- emailbkup /home lv 0 t 5 s 199 p 12068 -------- dump of driver schedule after start degraded mode: -------- -------- driver: state time 60.860 free kps: 7400 space: 3145728 taper: DOWN idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: not-idle driver: interface-state time 60.860 if : free 6000 if LE0: free 400 if LOCAL: free 1000 driver: hdisk-state time 60.860 hdisk 0: free 3145728 dumpers 0 driver: QUITTING time 60.860 telling children to quit driver: send-cmd time 60.860 to dumper0: QUIT driver: send-cmd time 60.860 to dumper1: QUIT driver: send-cmd time 60.860 to dumper2: QUIT driver: send-cmd time 60.860 to dumper3: QUIT driver: send-cmd time 60.860 to taper: QUIT taper: DONE [idle wait: 0.000 secs] driver: FINISHED time 60.863 amdump: end at Mon Jan 13 13:40:12 EST 2003