hi list...
I installed amanda (tested versions 2.5.2p1 and 2.5.1p3) an FreeBSD, and
configured it with vtapes to be used as storage.
I configured a backup profile namend 'daily' for my daily backups, and
an 'amcheck daily' reports no errors.
For testing purposes I created a folder (one the backupserver itself)
which contains around 4.5 GB of data. When I start 'amdump daily' from
the console as the configured backup user, the command exists
immediately and I my syslog daemon catches the following kernel message:
kernel: pid 2007 (taper), uid 1002: exited on signal 12 (core dumped)
my log.<TIMESTAMP> file contains these informations:
DISK planner backup.system.lan /data/test
START planner date 20080107004352
INFO planner Adding new disk backup.system.lan:/data/test.
STATS driver hostname backup.system.lan
STATS driver startup time 0.002
FINISH planner date 20080107004352 time 0.138
FAIL driver backup.system.lan /data/test 20080107004352 0 [can't switch
to incremental dump]
WARNING driver taper pid 2007 exited with signal 12
FINISH driver date 20080107004352 time 0.186
The 'amdump.1' log file is attached.
In the '/tmp/amanda' folder I can find a file called 'taper.core' which
is around 750kb large, and contains the core dump from the taper
process.
I reviewed the logfiles in the '/tmp/amanda' from the run I made, and
couldn't see any reason why the taper process dies. But I'm far away
from knowing amanda.
So what are the next steps to troubleshoot and solve the problem?
Greetz Olli
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
