amdump: start at Mon Jan 7 00:43:52 CET 2008 amdump: datestamp 20080107 amdump: starttime 20080107004352 planner: pid 2005 executable /usr/local/libexec/planner version 2.5.2p1 planner: build: VERSION="Amanda-2.5.2p1" planner: BUILT_DATE="Mon Jan 7 00:35:31 CET 2008" planner: BUILT_MACH="FreeBSD backup.system.lan 6.2-RELEASE FreeBSD 6.2-RELEASE #3: Tue Nov 6 15:40:07 CET 2007 root@:/usr/obj/u sr/src/sys/CUSTOM i386" planner: CC="gcc" CONFIGURE_COMMAND="'./configure' 'freebsd6.2'" planner: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin" planner: libexecdir="/usr/local/libexec" planner: mandir="${prefix}/share/man" AMANDA_TMPDIR="/tmp/amanda" planner: 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" VDUMP=UNDEF VRESTORE=UNDEF planner: XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF planner: SAMBA_CLIENT="/usr/local/bin/smbclient" planner: GNUTAR="/usr/local/bin/gtar" COMPRESS_PATH="/usr/bin/gzip" planner: UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr" planner: MAILER="/usr/bin/Mail" planner: listed_incr_dir="/usr/local/var/amanda/gnutar-lists" planner: defs: DEFAULT_SERVER="backup.system.lan" planner: DEFAULT_CONFIG="DailySet1" planner: DEFAULT_TAPE_SERVER="backup.system.lan" HAVE_MMAP planner: NEED_STRSTR HAVE_SYSVSHM LOCKING=**NONE** DEBUG_CODE planner: AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS planner: CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP planner: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast" planner: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc" READING CONF FILES... planner: timestamp 20080107004352 planner: time 0.001: startup took 0.001 secs SENDING FLUSHES... ENDFLUSH SETTING UP FOR ESTIMATES... amdump: start at Mon Jan 7 00:43:52 CET 2008 amdump: datestamp 20080107 amdump: starttime 20080107004352 planner: pid 2005 executable /usr/local/libexec/planner version 2.5.2p1 planner: build: VERSION="Amanda-2.5.2p1" planner: BUILT_DATE="Mon Jan 7 00:35:31 CET 2008" planner: BUILT_MACH="FreeBSD backup.system.lan 6.2-RELEASE FreeBSD 6.2-RELEASE #3: Tue Nov 6 15:40:07 CET 2007 root@:/usr/obj/u sr/src/sys/CUSTOM i386" planner: CC="gcc" CONFIGURE_COMMAND="'./configure' 'freebsd6.2'" planner: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin" planner: libexecdir="/usr/local/libexec" planner: mandir="${prefix}/share/man" AMANDA_TMPDIR="/tmp/amanda" planner: 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" VDUMP=UNDEF VRESTORE=UNDEF planner: XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF planner: SAMBA_CLIENT="/usr/local/bin/smbclient" planner: GNUTAR="/usr/local/bin/gtar" COMPRESS_PATH="/usr/bin/gzip" planner: UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr" planner: MAILER="/usr/bin/Mail" planner: listed_incr_dir="/usr/local/var/amanda/gnutar-lists" planner: defs: DEFAULT_SERVER="backup.system.lan" planner: DEFAULT_CONFIG="DailySet1" planner: DEFAULT_TAPE_SERVER="backup.system.lan" HAVE_MMAP planner: NEED_STRSTR HAVE_SYSVSHM LOCKING=**NONE** DEBUG_CODE planner: AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS planner: CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP planner: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast" planner: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc" READING CONF FILES... planner: timestamp 20080107004352 planner: time 0.001: startup took 0.001 secs SENDING FLUSHES... ENDFLUSH SETTING UP FOR ESTIMATES... planner: time 0.001: setting up estimates for backup.system.lan:/data/test SETTING UP FOR ESTIMATES... planner: time 0.001: setting up estimates for backup.system.lan:/data/test backup.system.lan:/data/test overdue 13885 days for level 0 setup_estimate: backup.system.lan:/data/test: command 0, options: none last_level -1 next_level0 -13885 level_days 0 getting estimates 0 (-2) -1 (-2) -1 (-2) planner: time 0.001: setting up estimates took 0.000 secs GETTING ESTIMATES... driver: pid 2006 executable /usr/local/libexec/driver version 2.5.2p1 driver: tape size 81920000 reserving 0 out of 0 for degraded-mode dumps driver: send-cmd time 0.001 to taper: START-TAPER 20080107004352 driver: started dumper0 pid 2008 driver: send-cmd time 0.001 to dumper0: START 20080107004352 driver: started dumper1 pid 2009 driver: send-cmd time 0.002 to dumper1: START 20080107004352 driver: started dumper2 pid 2010 driver: send-cmd time 0.002 to dumper2: START 20080107004352 driver: started dumper3 pid 2011 driver: send-cmd time 0.002 to dumper3: START 20080107004352 driver: start time 0.002 inparallel 4 bandwidth 2000 diskspace 0 dir OBSOLETE datestamp 20080107004352 driver: drain-ends tapeq FIRST bi g-dumpers sssS taper: pid 2007 executable taper version 2.5.2p1 taper: page size = 4096 taper: buffer size is 32768 dumper: pid 2009 executable dumper1 version 2.5.2p1 dumper: pid 2010 executable dumper2 version 2.5.2p1 dumper: pid 2011 executable dumper3 version 2.5.2p1 dumper: pid 2008 executable dumper0 version 2.5.2p1 planner: time 0.035: got partial result for host backup.system.lan disk /data/test: 0 -> -2K, -1 -> -2K, -1 -> -2K planner: time 0.137: got partial result for host backup.system.lan disk /data/test: 0 -> 5084690K, -1 -> -2K, -1 -> -2K planner: time 0.138: got result for host backup.system.lan disk /data/test: 0 -> 5084690K, -1 -> -2K, -1 -> -2K planner: time 0.138: getting estimates took 0.136 secs FAILED QUEUE: empty DONE QUEUE: 0: backup.system.lan /data/test ANALYZING ESTIMATES... pondering backup.system.lan:/data/test... next_level0 -13885 last_level -1 (due for level 0) (new disk, can't switch to d egraded mode) curr level 0 nsize 5084690 csize 2542345 total size 2542444 total_lev0 2542345 balanced-lev0size 363192 INITIAL SCHEDULE (size 2542444): backup.system.lan /data/test pri 13887 lev 0 nsize 5084690 csize 2542345 DELAYING DUMPS IF NEEDED, total_size 2542444, tape length 81920000 mark 1 delay: Total size now 2542444. PROMOTING DUMPS IF NEEDED, total_lev0 2542345, balanced_size 363192... planner: time 0.138: analysis took 0.000 secs GENERATING SCHEDULE: -------- DUMP backup.system.lan ffffffff9ffeffffffff00 /data/test 20080107004352 13887 0 1970:1:1:0:0:0 5084690 2542345 2482 1024 -------- driver: result time 0.185 from taper: (eof) driver: state time 0.185 free kps: 2000 space: 0 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-id le driver: interface-state time 0.185 if default: free 600 if local: free 1000 if le0: free 400 driver: hdisk-state time 0.185 driver: flush size 0 dump of driver schedule before start degraded mode: -------- backup.system.lan /data/test lv 0 t 2482 s 2542400 p 13887 -------- dump of driver schedule after start degraded mode: -------- -------- driver: state time 0.186 free kps: 2000 space: 0 taper: DOWN idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dum pers driver: interface-state time 0.186 if default: free 600 if local: free 1000 if le0: free 400 driver: hdisk-state time 0.186 driver: QUITTING time 0.186 telling children to quit driver: send-cmd time 0.186 to dumper0: QUIT driver: send-cmd time 0.186 to dumper1: QUIT driver: send-cmd time 0.186 to dumper2: QUIT driver: send-cmd time 0.186 to dumper3: QUIT driver: send-cmd time 0.186 to taper: QUIT writing taper command 'QUIT ' failed: Broken pipe driver: taper pid 2007 exited with signal 12 driver: FINISHED time 0.186 amdump: end at Mon Jan 7 00:43:52 CET 2008