Using amanda 3.2.0 on everything. I have 2 hosts and a server currently.
1 host and the server are ubuntu 10.04 and the other host (that I'm
having issues with) is 8.04.
I can run amdump for each host individually with no problem. Running
amdump for the config (daily) will fail with the following. Talking to
djmitche in IRC, told me to post to the list. My tapes are 200GB, so
they shouldn't be filling up. I'm using vtapes and no holding disks.
FAILURE DUMP SUMMARY:
asterisk-it0.mydomain.com / RESULTS MISSING
asterisk-it0.mydomain.com / lev 1 FAILED [can't dump in degraded mode]
log:
INFO amdump amdump pid 3100
INFO driver driver pid 3102
INFO planner planner pid 3101
START planner date 20101105000001
START driver date 20101105000001
DISK planner asterisk-it0.mydomain.com /
STATS driver hostname styx-it0
DISK planner git.mydomain.com /
STATS driver startup time 0.004
INFO dumper dumper pid 3105
INFO dumper dumper pid 3104
INFO dumper dumper pid 3106
INFO dumper dumper pid 3107
INFO taper taper pid 3103
INFO planner Full dump of git.mydomain.com:/ promoted from 6 days ahead.
FINISH planner date 20101105000001 time 199.580
INFO planner pid-done 3101
INFO dumper gzip pid 3121
START taper datestamp 20101105000001 label daily-07 tape 1
PART taper daily-07 1 git.mydomain.com / 20101105000001 1/-1 0 [sec 78.771226
kb 435153 kps 5524.263398]
SUCCESS dumper git.mydomain.com / 20101105000001 0 [sec 88.620 kb 435154 kps
4910.3 orig-kb 1042750]
INFO dumper pid-done 3121
DONE taper git.mydomain.com / 20101105000001 1 0 [sec 84.000000 kb 435153 kps
5180.392857 orig-kb 1042750]
STATS driver estimate git.mydomain.com / 20101105000001 0 [sec 90 nkb 1042772
ckb 435360 kps 4828]
FAIL driver asterisk-it0.mydomain.com / 20101105000001 1 [can't dump in
degraded mode]
INFO dumper pid-done 3106
INFO dumper pid-done 3104
INFO dumper pid-done 3107
INFO dumper pid-done 3105
INFO taper tape daily-07 kb 435153 fm 1 [OK]
INFO taper pid-done 3103
FINISH driver date 20101105000001 time 289.614
INFO driver pid-done 3102
amdump.1
amdump: start at Fri Nov 5 11:51:36 CDT 2010
amdump: datestamp 20101105
amdump: starttime 20101105115136
amdump: starttime-locale-independent 2010-11-05 11:51:36 CDT
planner: pid 3659 executable /usr/libexec/amanda/planner version 3.2.0
planner: build: VERSION="Amanda-3.2.0"
planner: BUILT_DATE="Mon Oct 18 09:20:30 PDT 2010" BUILT_MACH=""
planner: BUILT_REV="3545" BUILT_BRANCH="3_2_0"
planner: CC="i486-linux-gnu-gcc"
planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
planner: libexecdir="/usr/libexec"
planner: amlibexecdir="/usr/libexec/amanda" mandir="/usr/share/man"
planner: AMANDA_TMPDIR="/tmp/amanda"
planner: AMANDA_DBGDIR="/var/log/amanda" CONFIG_DIR="/etc/amanda"
planner: DEV_PREFIX="/dev/" RDEV_PREFIX="/dev/r"
planner: DUMP="/sbin/dump" RESTORE="/sbin/restore" VDUMP=UNDEF
planner: VRESTORE=UNDEF XFSDUMP="/sbin/xfsdump"
planner: XFSRESTORE="/sbin/xfsrestore" VXDUMP=UNDEF VXRESTORE=UNDEF
planner: SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
planner: COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
planner: LPRCMD=UNDEF MAILER=UNDEF
planner: listed_incr_dir="/var/lib/amanda/gnutar-lists"
planner: defs: DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
planner: DEFAULT_TAPE_SERVER="localhost" DEFAULT_TAPE_DEVICE=""
planner: NEED_STRSTR AMFLOCK_POSIX AMFLOCK_FLOCK AMFLOCK_LOCKF
planner: AMFLOCK_LNLOCK SETPGRP_VOID ASSERTIONS AMANDA_DEBUG_DAYS=4
planner: BSD_SECURITY USE_AMANDAHOSTS CLIENT_LOGIN="amandabackup"
planner: CHECK_USERID HAVE_GZIP COMPRESS_SUFFIX=".gz"
planner: COMPRESS_FAST_OPT="--fast" COMPRESS_BEST_OPT="--best"
planner: UNCOMPRESS_OPT="-dc"
READING CONF INFO...
planner: timestamp 20101105115136
planner: tape_length is set from tape length (204800000 KB) * runtapes (1) ==
204800000 KB
planner: time 0.000: startup took 0.000 secs
SENDING FLUSHES...
ENDFLUSH
SETTING UP FOR ESTIMATES...
driver: tape size 204800000
reserving 0 out of 0 for degraded-mode dumps
planner: time 0.000: setting up estimates for asterisk-it0.mydomain.com:/
setup_estimate: asterisk-it0.mydomain.com:/: command 0, options: none
last_level 0 next_level0 6 level_days 0 getting estimates 0 (-3) 1 (-3) -1
(-3)
planner: time 0.000: setting up estimates took 0.000 secs
GETTING ESTIMATES...
driver: started dumper0 pid 3662
driver: send-cmd time 0.001 to dumper0: START 20101105115136
driver: started dumper1 pid 3663
driver: send-cmd time 0.001 to dumper1: START 20101105115136
driver: started dumper2 pid 3664
driver: send-cmd time 0.002 to dumper2: START 20101105115136
driver: started dumper3 pid 3665
driver: send-cmd time 0.002 to dumper3: START 20101105115136
driver: send-cmd time 0.002 to taper: START-TAPER worker0 20101105115136
driver: start time 0.002 inparallel 4 bandwidth 2048000 diskspace 0 dir
OBSOLETE datestamp 20101105115136 driver: drain-ends tapeq FIRST big-dumpers
sssS
driver: state time 0.002 free kps: 2048000 space: 0 taper: idle idle-dumpers: 4
qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 0.002 if default: free 2048000
driver: hdisk-state time 0.002
dumper: pid 3662 executable dumper0 version 3.2.0
dumper: pid 3665 executable dumper3 version 3.2.0
dumper: pid 3663 executable dumper1 version 3.2.0
dumper: pid 3664 executable dumper2 version 3.2.0
driver: state time 0.262 free kps: 2048000 space: 0 taper: idle idle-dumpers: 4
qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 0.262 if default: free 2048000
driver: hdisk-state time 0.262
driver: result time 0.262 from taper: TAPER-OK worker0
planner: time 6.113: got partial result for host asterisk-it0.mydomain.com disk /: 0
-> -3K, 1 -> -3K, -1 -> -3K
planner: time 9.104: got partial result for host asterisk-it0.mydomain.com disk /: 0
-> 2492830K, 1 -> -3K, -1 -> -3K
planner: time 9.615: got partial result for host asterisk-it0.mydomain.com disk /: 0
-> 2492830K, 1 -> 8830K, -1 -> -3K
planner: time 9.616: got result for host asterisk-it0.mydomain.com disk /: 0 ->
2492830K, 1 -> 8830K, -1 -> -3K
planner: time 9.617: getting estimates took 9.616 secs
FAILED QUEUE: empty
DONE QUEUE:
0: asterisk-it0.mydomain.com /
ANALYZING ESTIMATES...
pondering asterisk-it0.mydomain.com:/... next_level0 6 last_level 0 (not due
for a full dump, picking an incr level)
picklev: last night 0, so tonight level 1
curr level 1 nsize 8830 csize 612 total size 711 total_lev0 0
balanced-lev0size 162097
INITIAL SCHEDULE (size 711):
asterisk-it0.mydomain.com / pri 1 lev 1 nsize 8830 csize 612
DELAYING DUMPS IF NEEDED, total_size 711, tape length 204800000 mark 1
delay: Total size now 711.
PROMOTING DUMPS IF NEEDED, total_lev0 0, balanced_size 162097...
planner: time 9.617: analysis took 0.000 secs
GENERATING SCHEDULE:
--------
DUMP asterisk-it0.mydomain.com ffffffff9efefbffffffffff01 / 20101105115136 1 1
2010:11:4:15:4:8 8830 612 6 102 "Skipping: a full is not planned, so can't dump in
degraded mode"
--------
driver: flush size 0
find diskspace: not enough diskspace. Left with 672 K
driver: send-cmd time 9.619 to taper: PORT-WRITE worker0 00-00001 asterisk-it0.mydomain.com / 1 20101105115136 "" "" ""
"" "" "" "" "" AMANDA
driver: state time 9.619 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle:
no-diskspace
driver: interface-state time 9.619 if default: free 2047898
driver: hdisk-state time 9.619
driver: state time 9.623 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle:
no-diskspace
driver: interface-state time 9.623 if default: free 2047898
driver: hdisk-state time 9.623
driver: result time 9.623 from taper: PORT worker0 00-00001 34982
"127.0.0.1:44020"
driver: send-cmd time 9.623 to dumper1: PORT-DUMP 00-00001 34982 asterisk-it0.mydomain.com ffffffff9efefbffffffffff01 / NODEVICE 1 2010:11:4:15:4:8 GNUTAR "" "" ""
/var/lib/amanda/.ssh/id_rsa ssh AMANDA 127.0.0.1:44020
|"<auth>ssh</auth>\n<compress>FAST</compress>\n<record>YES</record>\n<index>YES</index>\n<datapath>AMANDA</datapath>\n<exclude>\n<list>/etc/amanda/exclude.gtar</list>\n</exclude>\n"
taper: status file asterisk-it0.mydomain.com
/:/tmp/amanda/taper_status_file_neH05v
driver: state time 9.761 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle:
no-diskspace
driver: interface-state time 9.761 if default: free 2047898
driver: hdisk-state time 9.761
driver: result time 9.761 from taper: REQUEST-NEW-TAPE 00-00001
driver: send-cmd time 9.761 to taper: START-SCAN worker0 00-00001
driver: send-cmd time 9.761 to taper: NEW-TAPE worker0 00-00001
taper: wrote label 'daily-09'
driver: state time 11.998 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 11.998 if default: free 2047898
driver: hdisk-state time 11.998
driver: result time 11.998 from taper: NEW-TAPE 00-00001 daily-09
driver: state time 12.002 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 12.002 if default: free 2047898
driver: hdisk-state time 12.002
driver: result time 12.002 from taper: PARTDONE 00-00001 daily-09 1 616 "[sec
0.001406 kb 616 kps 438002.396214]"
driver: state time 12.003 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 12.003 if default: free 2047898
driver: hdisk-state time 12.003
driver: result time 12.003 from taper: DUMPER-STATUS 00-00001
driver: state time 15.353 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 15.353 if default: free 2047898
driver: hdisk-state time 15.353
driver: result time 15.353 from dumper1: DONE 00-00001 8840 617 6 "[sec 5.599 kb 617
kps 110.2 orig-kb 8840]"
driver: finished-cmd time 15.353 dumper1 dumped asterisk-it0.mydomain.com:/
driver: send-cmd time 15.353 to taper: DONE worker0 00-00001 8840
driver: state time 15.355 free kps: 2047898 space: 0 taper: writing
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 15.355 if default: free 2047898
driver: hdisk-state time 15.355
driver: result time 15.355 from taper: DONE 00-00001 INPUT-GOOD TAPE-GOOD "[sec 2.000000 kb 616 kps
308.000000 orig-kb 8840]" "" ""
driver: finished-cmd time 15.355 taper wrote asterisk-it0.mydomain.com:/
driver: state time 15.356 free kps: 2048000 space: 0 taper: idle idle-dumpers:
4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 15.356 if default: free 2048000
driver: hdisk-state time 15.356
driver: QUITTING time 15.356 telling children to quit
driver: send-cmd time 15.356 to dumper0: QUIT ""
driver: send-cmd time 15.356 to dumper1: QUIT ""
driver: send-cmd time 15.356 to dumper2: QUIT ""
driver: send-cmd time 15.356 to dumper3: QUIT ""
driver: send-cmd time 15.356 to taper: QUIT
driver: FINISHED time 16.357
amdump: end at Fri Nov 5 11:51:53 CDT 2010
Warning: no log files found for tape daily-01 written 2010-10-28 14:05:47
Warning: no log files found for tape daily-10 written 2010-10-28 00:01:01
--
Rhys
