Amanda-Users

amdump fails

2004-05-18 10:06:09
Subject: amdump fails
From: "Pablo Quinta Vidal" <qvidal82 AT hotmail DOT com>
To: amanda-users AT amanda DOT org
Date: Tue, 18 May 2004 15:50:50 +0200
Hi!
My configuration pass amcheck with no errors but
after executing amdump Diaria I get this errors in the log.*.* file:

START driver date 20040311
DISK planner 193.144.50.86 /amanda
START planner date 20040311
INFO planner Adding new disk 193.144.50.86:/amanda.
START taper datestamp 20040311 label Diaria3 tape 0
FAIL planner 193.144.50.86 /amanda 20040311 0 [disk /amanda, all estimate
failed]
FINISH planner date 20040311
WARNING driver WARNING: got empty schedule from planner
STATS driver startup time 1.205
INFO taper tape Diaria3 kb 0 fm 0 [OK]
FINISH driver date 20040311 time 1.242



And this ones in amdump.X check the lines between the ***** :



amdump: start at Thu Mar 11 01:17:08 CET 2004
amdump: datestamp 20040311
planner: pid 380 executable /usr/local/libexec/planner version 2.4.4p2
planner: build: VERSION="Amanda-2.4.4p2"
planner: BUILT_DATE="Thu Mar 4 23:42:09 CET 2004"
planner: BUILT_MACH="Linux medulio 2.4.20 #1 SMP Sun Aug 24 08:17:52
CEST 2003 i586 GNU/Linux"
planner: CC="gcc"
planner: CONFIGURE_COMMAND="'./configure' '--with-user=amanda'
'--with-group=backup' '--with-configdir=/usr/local/etc/amanda/'
'--with-debugging'"
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=UNDEF RESTORE=UNDEF VDUMP=UNDEF
planner: VRESTORE=UNDEF XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF
planner: VXRESTORE=UNDEF SAMBA_CLIENT=UNDEF GNUTAR="/bin/tar"
planner: COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
planner: LPRCMD=UNDEF MAILER="/usr/bin/Mail"
planner: listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
planner: defs: DEFAULT_SERVER="medulio" DEFAULT_CONFIG="DailySet1"
planner: DEFAULT_TAPE_SERVER="medulio"
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="amanda" FORCE_USERID HAVE_GZIP
planner: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
planner: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
******************************************
planner: time 0.044: dgram_bind: socket bound to 0.0.0.0.896
******************************************
READING CONF FILES...
driver: pid 381 executable /usr/local/libexec/driver version 2.4.4p2
driver: tape size 8192
driver: send-cmd time 0.074 to taper: START-TAPER 20040311
planner: time 0.114: startup took 0.114 secs

SETTING UP FOR ESTIMATES...
planner: time 0.115: setting up estimates for irixoa06.des.udc.es:/amanda
irixoa06.des.udc.es:/amanda overdue 12488 days for level 0
setup_estimate: irixoa06.des.udc.es:/amanda: command 0, options:
last_level -1 next_level0 -12488 level_days 0
******************************************
getting estimates 0 (0) -1 (-1) -1 (-1)
******************************************
planner: time 0.118: setting up estimates took 0.003 secs

GETTING ESTIMATES...
driver: started dumper0 pid 383
driver: started dumper1 pid 384
driver: started dumper2 pid 385
driver: started dumper3 pid 386
taper: pid 382 executable taper version 2.4.4p2
taper: page size is 4096
taper: buffer size is 32768
taper: buffer[00] at 0x40223000
taper: buffer[01] at 0x4022b000
taper: buffer[02] at 0x40233000
taper: buffer[03] at 0x4023b000
taper: buffer[04] at 0x40243000
taper: buffer[05] at 0x4024b000
taper: buffer[06] at 0x40253000
taper: buffer[07] at 0x4025b000
taper: buffer[08] at 0x40263000
taper: buffer[09] at 0x4026b000
taper: buffer[10] at 0x40273000
taper: buffer[11] at 0x4027b000
taper: buffer[12] at 0x40283000
taper: buffer[13] at 0x4028b000
taper: buffer[14] at 0x40293000
taper: buffer[15] at 0x4029b000
taper: buffer[16] at 0x402a3000
taper: buffer[17] at 0x402ab000
taper: buffer[18] at 0x402b3000
taper: buffer[19] at 0x402bb000
taper: buffer structures at 0x402c3000 for 240 bytes
******************************************
dumper: dgram_bind: socket bound to 0.0.0.0.899
******************************************
dumper: pid 383 executable dumper0 version 2.4.4p2, using port 899
******************************************
dumper: dgram_bind: socket bound to 0.0.0.0.900
******************************************
dumper: pid 384 executable dumper1 version 2.4.4p2, using port 900
changer: opening pipe to: /usr/local/libexec/chg-disk -info
******************************************
dumper: dgram_bind: socket bound to 0.0.0.0.902
******************************************
dumper: pid 386 executable dumper3 version 2.4.4p2, using port 902
******************************************
dumper: dgram_bind: socket bound to 0.0.0.0.901
******************************************
dumper: pid 385 executable dumper2 version 2.4.4p2, using port 901
changer: got exit: 0 str: 1 4 1
changer: opening pipe to: /usr/local/libexec/chg-disk -slot current
changer: got exit: 0 str: 1 file:/backups/Diaria
taper: slot 1: date 20040305 label Diaria1 (active tape)
changer: opening pipe to: /usr/local/libexec/chg-disk -slot next
******************************************
planner: time 1.660: got result for host irixoa06.des.udc.es disk /amanda: 0
-> -1K, -1 -> -1K, -1 -> -1K
******************************************
planner: time 1.661: getting estimates took 1.542 secs
FAILED QUEUE:
0: irixoa06.des.udc.es /amanda
DONE QUEUE: empty

ANALYZING ESTIMATES...
planner: FAILED irixoa06.des.udc.es /amanda 20040311 0 [disk /amanda, all
estimate failed]
INITIAL SCHEDULE (size 2064):

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

PROMOTING DUMPS IF NEEDED, total_lev0 0, balanced_size 0...
planner: time 1.666: analysis took 0.002 secs

GENERATING SCHEDULE:
--------
ENDFLUSH
--------
driver: adding holding disk 0 dir /dumps/amanda size 204800
reserving 204800 out of 204800 for degraded-mode dumps
driver: flush size 0
driver: start time 1.654 inparallel 4 bandwidth 2000 diskspace 204800 dir
OBSOLETE datestamp 20040311 driver: drain-ends tapeq FIRST big-dumpers sssS
changer: got exit: 0 str: 2 file:/backups/Diaria
taper: slot 2: date X label Diaria2 (new tape)
taper: read label `Diaria2' date `X'
taper: wrote label `Diaria2' date `20040311'
driver: result time 1.904 from taper: TAPER-OK
driver: state time 1.904 free kps: 2000 space: 204800 taper: idle
idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle:
not-idle
driver: interface-state time 1.904 if : free 600 if LE0: free 400 if LOCAL:
free 1000
driver: hdisk-state time 1.904 hdisk 0: free 204800 dumpers 0
driver: QUITTING time 1.905 telling children to quit
driver: send-cmd time 1.905 to dumper0: QUIT
driver: send-cmd time 1.906 to dumper1: QUIT
driver: send-cmd time 1.906 to dumper2: QUIT
driver: send-cmd time 1.907 to dumper3: QUIT
driver: send-cmd time 1.907 to taper: QUIT
taper: DONE [idle wait: 0.006 secs]
taper: writing end marker. [Diaria2 OK kb 0 fm 0]
driver: FINISHED time 1.938
amdump: end at Thu Mar 11 01:17:10 CET 2004



Is correct the socket bound to 0.0.0.0.X ??
The client and the host have configured the services and initd. Amcheck
didnt fails. What happens?

Here is the sendsize*



sendsize: debug 1 pid 5860 ruid 1001 euid 1001: start at Tue May 11 18:55:54
2004
sendsize: version 2.4.4p2
sendsize[5860]: time 0.081: waiting for any estimate child: 1 running
sendsize[5862]: time 0.082: calculating for amname '/amanda', dirname
'/amanda', spindle -1
sendsize[5862]: time 0.082: getting size via dump for /amanda level 0
sendsize[5862]: time 0.085: calculating for device '/amanda' with ''
sendsize[5862]: time 0.085: running "/sbin/dump 0Ssf 1048576 - /amanda"
sendsize[5862]: time 0.086: running /usr/local/libexec/killpgrp
sendsize[5862]: time 0.262: DUMP: Cannot open /dev/hda2
sendsize[5862]: time 0.263: DUMP: The ENTIRE dump is aborted.
sendsize[5862]: time 0.264: .....
sendsize[5862]: estimate time for /amanda level 0: 0.179
sendsize[5862]: no size line match in /sbin/dump output for "/amanda"
sendsize[5862]: .....
sendsize[5862]: estimate size for /amanda level 0: -1 KB
sendsize[5862]: time 0.264: asking killpgrp to terminate
sendsize[5862]: time 1.272: done with amname '/amanda', dirname '/amanda',
spindle -1
sendsize[5860]: time 1.272: child 5862 terminated normally
sendsize: time 1.272: pid 5860 finish time Tue May 11 18:55:55 2004

why cant open /dev/hda2 ?? permissions?


And the amandad*

amandad: debug 1 pid 5858 ruid 1001 euid 1001: start at Tue May 11 18:55:54
2004
amandad: version 2.4.4p2
amandad: build: VERSION="Amanda-2.4.4p2"
amandad: BUILT_DATE="Tue Apr 27 17:43:34 CEST 2004"
amandad: BUILT_MACH="Linux irixoa06 2.4.12 #1 Mon Nov 12 20:08:40 CET
2001 i686 unknown"
amandad: CC="gcc"
amandad: CONFIGURE_COMMAND="'./configure' '--with-user=amanda'
'--with-group=backup' '--with-configdir=/usr/local/etc/amanda/'
'--with-debugging' '--without-server'"
amandad: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin"
amandad: libexecdir="/usr/local/libexec" mandir="/usr/local/man"
amandad: AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
amandad: CONFIG_DIR="/usr/local/etc/amanda/" DEV_PREFIX="/dev/"
amandad: RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
amandad: RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
amandad: XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF
amandad: SAMBA_CLIENT=UNDEF GNUTAR="/bin/tar"
amandad: COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
amandad: LPRCMD="/usr/bin/lpr" MAILER="/usr/bin/Mail"
amandad: listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
amandad: defs: DEFAULT_SERVER="irixoa06" DEFAULT_CONFIG="DailySet1"
amandad: DEFAULT_TAPE_SERVER="irixoa06"
amandad: DEFAULT_TAPE_DEVICE="/dev/null" HAVE_MMAP HAVE_SYSVSHM
amandad: LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
amandad: AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
amandad: CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP
amandad: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
amandad: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
amandad: time 0.000: got packet:
--------
Amanda 2.4 REQ HANDLE 000-30540608 SEQ 1078964228
SECURITY USER amanda
SERVICE noop
OPTIONS features=fffffeff9ffe0f;
--------

amandad: time 0.000: sending ack:
----
Amanda 2.4 ACK HANDLE 000-30540608 SEQ 1078964228
----

amandad: time 0.001: bsd security: remote host amanda_serv user amanda local
user amanda
amandad: time 0.035: amandahosts security check passed
amandad: time 0.035: running service "noop"
amandad: time 0.035: sending REP packet:
----
Amanda 2.4 REP HANDLE 000-30540608 SEQ 1078964228
OPTIONS features=fffffeff9ffe0f;
----

amandad: time 0.036: got packet:
----
Amanda 2.4 ACK HANDLE 000-30540608 SEQ 1078964228
----

amandad: time 0.036: pid 5858 finish time Tue May 11 18:55:54 2004




Thanks for the help.

Pablo

_________________________________________________________________
Protege tu correo contra los virus con MSN Premium. Pruébalo gratis dos meses. http://join.msn.com/?pgmarket=es-es&page=features/firewall&XAPID=1684&DI=1055&HL=TAG2OPENINGTEXT_MSNPREMIUM_VIRUS


<Prev in Thread] Current Thread [Next in Thread>