Amanda-Users

Unable to do the backup

2002-08-23 11:43:59
Subject: Unable to do the backup
From: Jorge Godoy <godoy AT metalab.unc DOT edu>
To: amanda-users AT amanda DOT org
Date: Fri, 23 Aug 2002 12:31:05 -0300
Can somebody help me with these logs? I'm not being able to find out
what is happening.

I'm including relevant parts of logs (I hope all parts needed are
here) below.

I'm keeping on searching for something on the net, but if you can
help, I'll be very grateful.



Thanks in antecipation,
Godoy.


>From log.XXXXXX.Y:


START planner date 20020823
WARNING planner Last full dump of localhost:/GIS/KPC0101 on tape  overwritten in
 1 run.
WARNING planner Last full dump of localhost:/GIS/SIG/CALCULO81 on tape  overwrit
ten in 1 run.
WARNING planner Last full dump of localhost:/GIS/SIG/DIRETORIA on tape  overwrit
ten in 1 run.
WARNING planner Last full dump of localhost:/GIS/SIG/INDUSTRIA on tape  overwrit
ten in 1 run.
WARNING planner Last full dump of localhost:/GIS/SIG/PLOTER on tape  overwritten
 in 1 run.
WARNING planner Last full dump of localhost:/GIS/SIG/Registro on tape  overwritt
en in 1 run.
WARNING planner Last full dump of localhost:/GIS/SIG/OUTROS on tape  overwritten
 in 1 run.
START driver date 20020823
START taper datestamp 20020823 label FITA-1.1 tape 0
FINISH planner date 20020823
STATS driver startup time 14.420
FAIL dumper localhost /GIS/SIG/CALCULO81 0 [/sbin/dump returned 1]
  sendbackup: start [localhost:/GIS/SIG/CALCULO81 level 0]
  sendbackup: info BACKUP=/sbin/dump
  sendbackup: info RECOVER_CMD=/sbin/restore -f... -

  sendbackup: info end
  |   DUMP: You can't update the dumpdates file when dumping a subdirectory
  |   DUMP: The ENTIRE dump is aborted.
  sendbackup: error [/sbin/dump returned 1]
SUCCESS taper localhost /GIS/SIG/CALCULO81 20020823 0 [sec 4.707 kb 64 kps 13.6 
{wr: writers 2 rdwait 0.000 wrwait 0.002 filemark 4.704}]
FAIL driver localhost /GIS/SIG/CALCULO81 0 [dump to tape failed]
...

and it repeats for every entry I have putten above.


In amdump.Y:

amdump: start at Sex Ago 23 12:21:15 BRT 2002
planner: pid 3339 executable /usr/lib/amanda/planner version 2.4.2p2
planner: build: VERSION="Amanda-2.4.2p2"
planner:        BUILT_DATE="Mon May 27 22:45:16 BRT 2002"
planner:        BUILT_MACH="Linux mapi2.distro.conectiva 2.4.17-18clsmp #1 SMP F
ri Mar 1 22:42:26 BRT 2002 i686 unknown"
planner:        CC="gcc-stackguard"
planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
planner:        libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
planner:        AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
planner:        CONFIG_DIR="/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="/usr/bin/gzip"
planner:        UNCOMPRESS_PATH="/usr/bin/gzip" MAILER="/usr/bin/Mail"
planner:        listed_incr_dir="/var/amanda/gnutar-lists"
planner: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
planner:        DEFAULT_TAPE_SERVER="localhost"
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="operator" FORCE_USERID HAVE_GZIP
planner:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
planner:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
planner: dgram_bind: socket bound to 0.0.0.0.1014
READING CONF FILES...
startup took 0.001 secs

SETTING UP FOR ESTIMATES...
setting up estimates for localhost:/GIS/KPC0101
localhost:/GIS/KPC0101 overdue 11923 days for level 0
setup_estimate: localhost:/GIS/KPC0101: command 0, options:
    last_level 0 next_level0 -11923 level_days 0
    getting estimates 0 (-1) 1 (0) -1 (-1)
setting up estimates for localhost:/GIS/SIG/CALCULO81
localhost:/GIS/SIG/CALCULO81 overdue 11923 days for level 0
...
setting up estimates took 0.001 secs

GETTING ESTIMATES...
driver: pid 3340 executable /usr/lib/amanda/driver version 2.4.2p2
driver: send-cmd time 0.001 to taper: START-TAPER 20020823
taper: pid 3343 executable taper version 2.4.2p2
dumper: dgram_bind: socket bound to 0.0.0.0.1019
dumper: pid 3344 executable dumper version 2.4.2p2, using port 1019
driver: started dumper0 pid 3344
driver: started dumper1 pid 3348
dumper: dgram_bind: socket bound to 0.0.0.0.1023
dumper: pid 3348 executable dumper version 2.4.2p2, using port 1023
dumper: dgram_bind: socket bound to 0.0.0.0.512
dumper: pid 3349 executable dumper version 2.4.2p2, using port 512
driver: started dumper2 pid 3349
dumper: dgram_bind: socket bound to 0.0.0.0.513
dumper: pid 3350 executable dumper version 2.4.2p2, using port 513
...
driver: started dumper9 pid 3356
taper: read label `FITA-1.1' date `20020823'
taper: wrote label `FITA-1.1' date `20020823'
got result for host localhost disk /GIS/SIG/OUTROS: 0 -> 25276K, 1 -> -1K, -1 ->
 -1K
got result for host localhost disk /GIS/SIG/Registro: 0 -> 2055K, 1 -> -1K, -1 -
> -1K
got result for host localhost disk /GIS/SIG/PLOTER: 0 -> 3288K, 1 -> -1K, -1 -> 
-1K
got result for host localhost disk /GIS/SIG/INDUSTRIA: 0 -> 535577K, 1 -> -1K, -
1 -> -1K
got result for host localhost disk /GIS/SIG/DIRETORIA: 0 -> 14077K, 1 -> -1K, -1
 -> -1K
got result for host localhost disk /GIS/SIG/CALCULO81: 0 -> 2000K, 1 -> -1K, -1 
-> -1K
got result for host localhost disk /GIS/KPC0101: 0 -> 1017558K, 1 -> -1K, -1 -> 
-1K
getting estimates took 14.435 secs

FAILED QUEUE: empty
DONE QUEUE:
  0: localhost  /GIS/SIG/OUTROS
  1: localhost  /GIS/SIG/Registro
  2: localhost  /GIS/SIG/PLOTER
  3: localhost  /GIS/SIG/INDUSTRIA
  4: localhost  /GIS/SIG/DIRETORIA
  5: localhost  /GIS/SIG/CALCULO81
  6: localhost  /GIS/KPC0101

ANALYZING ESTIMATES...
pondering localhost:/GIS/SIG/OUTROS... next_level0 -11923 last_level 0 (due for 
level 0) (picking inclevel for degraded mode)
   picklev: last night 0, so tonight level 1
  curr level 0 size 25276 total size 25738 total_lev0 25276 balanced-lev0size 25
276
pondering localhost:/GIS/SIG/Registro... next_level0 -11923 last_level 0 (due fo
r level 0) (picking inclevel for degraded mode)
   picklev: last night 0, so tonight level 1
  curr level 0 size 2055 total size 27947 total_lev0 27331 balanced-lev0size 273
31
...

INITIAL SCHEDULE (size 1601217):
  localhost /GIS/KPC0101 pri 11925 lev 0 size 1017558
  localhost /GIS/SIG/INDUSTRIA pri 11925 lev 0 size 535577
  localhost /GIS/SIG/OUTROS pri 11925 lev 0 size 25276
  localhost /GIS/SIG/DIRETORIA pri 11925 lev 0 size 14077
  localhost /GIS/SIG/PLOTER pri 11925 lev 0 size 3288
  localhost /GIS/SIG/Registro pri 11925 lev 0 size 2055
  localhost /GIS/SIG/CALCULO81 pri 11925 lev 0 size 2000

DELAYING DUMPS IF NEEDED, total_size 1601217, tape length 20480000 mark 122
  delay: Total size now 1601217.

PROMOTING DUMPS IF NEEDED, total_lev0 1599831, balanced_size 1599831...
analysis took 0.000 secs

GENERATING SCHEDULE:
--------
localhost /GIS/KPC0101 11925 0 1970:1:1:0:0:0 1017558 33918
localhost /GIS/SIG/INDUSTRIA 11925 0 1970:1:1:0:0:0 535577 17852
localhost /GIS/SIG/OUTROS 11925 0 1970:1:1:0:0:0 25276 842
localhost /GIS/SIG/DIRETORIA 11925 0 1970:1:1:0:0:0 14077 469
localhost /GIS/SIG/PLOTER 11925 0 1970:1:1:0:0:0 3288 109
localhost /GIS/SIG/Registro 11925 0 1970:1:1:0:0:0 2055 68
localhost /GIS/SIG/CALCULO81 11925 0 1970:1:1:0:0:0 2000 66
--------
reserving 0 out of 0 for degraded-mode dumps
driver: start time 14.420 inparallel 10 bandwidth 300 diskspace 0 dir OBSOLETE d
atestamp 20020823 driver: drain-ends tapeq LFFO big-dumpers 7
driver: result time 14.421 from taper: TAPER-OK
driver: dumping localhost:/GIS/SIG/CALCULO81 directly to tape
driver: send-cmd time 14.421 to taper: PORT-WRITE 00-00001 localhost /GIS/SIG/CA
LCULO81 0 20020823
taper: try_socksize: receive buffer size is 65536
taper: stream_server: waiting for connection: 0.0.0.0.33011
driver: result time 14.421 from taper: PORT 33011
driver: send-cmd time 14.421 to dumper0: PORT-DUMP 01-00002 33011 localhost /GIS
/SIG/CALCULO81 0 1970:1:1:0:0:0 DUMP |;bsd-auth;index;
driver: state time 14.421 free kps: 270 space: 0 taper: writing idle-dumpers: 9 
qlen tapeq: 0 runq: 6 roomq: 0 wakeup: 86400 driver-idle: not-idle
driver: interface-state time 14.421 if : free 270
driver: hdisk-state time 14.421
dumper: stream_client: connected to 127.0.0.1.33011
dumper: stream_client: our side is 0.0.0.0.33012
dumper: try_socksize: send buffer size is 65536
taper: stream_accept: connection from 127.0.0.1.33012
taper: try_socksize: receive buffer size is 32768
dumper: stream_client: connected to 127.0.0.1.33013
dumper: stream_client: our side is 0.0.0.0.33016
dumper: stream_client: connected to 127.0.0.1.33014
dumper: stream_client: our side is 0.0.0.0.33017
dumper: stream_client: connected to 127.0.0.1.33015
dumper: stream_client: our side is 0.0.0.0.33018
driver: result time 14.460 from dumper0: FAILED 01-00002 [/sbin/dump returned 1]
taper: reader-side: got label FITA-1.1 filenum 1
driver: result time 19.130 from taper: DONE 00-00001 FITA-1.1 1 [sec 4.707 kb 64
 kps 13.6 {wr: writers 2 rdwait 0.000 wrwait 0.002 filemark 4.704}]
driver: error time 19.136 serial gen mismatch
...

driver: interface-state time 27.525 if : free 300
driver: hdisk-state time 27.525
driver: QUITTING time 27.525 telling children to quit
driver: send-cmd time 27.525 to dumper0: QUIT
driver: send-cmd time 27.526 to dumper1: QUIT
driver: send-cmd time 27.526 to dumper2: QUIT
driver: send-cmd time 27.526 to dumper3: QUIT
driver: send-cmd time 27.526 to dumper4: QUIT
driver: send-cmd time 27.526 to dumper5: QUIT
driver: send-cmd time 27.526 to dumper6: QUIT
driver: send-cmd time 27.526 to dumper7: QUIT
driver: send-cmd time 27.526 to dumper8: QUIT
driver: send-cmd time 27.526 to dumper9: QUIT
driver: send-cmd time 27.526 to taper: QUIT
taper: DONE [idle wait: 9.512 secs]
taper: writing end marker. [FITA-1.1 OK kb 448 fm 7]
driver: FINISHED time 30.559
amdump: end at Sex Ago 23 12:21:45 BRT 2002


-- 
Godoy.     <godoy AT ieee DOT org>

"Ser poeta não é minha ambição, é minha maneira de estar sozinho"
 (Being a poet is not my ambition, it's my way of being lonely)
                                - Fernando Pessoa

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