Amanda-Users

Re: Why no incrementals in degraded mode?

2006-01-20 09:36:14
Subject: Re: Why no incrementals in degraded mode?
From: Josef Wolf <jw AT raven.inka DOT de>
To: amanda-users AT amanda DOT org
Date: Fri, 20 Jan 2006 15:14:42 +0100
On Fri, Jan 20, 2006 at 02:25:41PM +0100, Paul Bijnens wrote:

> >Since I have re-installed amanda, I get no more incrementals when it runs
> >into degraded mode.  From the logfiles I can't find any hint why it always
> >does full-backups.  All DLEs are affected by this problem.  But in the
> >description below, I list only one DLE.
> 
> OK, you picked all the lines with evidence for not doing incrementals, 
> and still doing full dumps.  Strange indeed.
> But the other lines, which you did not find important may contain the
> hint why amanda did fulls instead of the expected incrementals.
> The complete amanda.conf + disklist file would also be interesting
> + version of amanda.  Which version did you use before?

Well, I did not want to bother the list with a lengthy message which is
not interesting for most of the readers.  But since you say it is needed,
I have appended it below.

> One of the obvious reasons, but difficult to tell from here, is that
> when you re-installed, you have actually two versions instead of one.
> And you update one config, but Amanda uses the other config.

This is next to impossible, since I installed the box completely from
scratch (I always do). Here is the config and the disklist:

  org      "PPC"
  mailto   "amanda"

  logdir   "/m/b/lib/amanda/ppc"
  indexdir "/m/b/lib/amanda/ppc/index"
  infofile "/m/b/lib/amanda/ppc/curinfo"
  tapelist "/m/b/lib/amanda/ppc/tapelist"

  tapetype dvd
  labelstr "^PPC[0-9][0-9]*$"

  runtapes          2
  tpchanger         "/usr/local/bin/chg-disk.pl"
  tapedev           "file:/m/amchanger/ppc"
  amrecover_changer "file:/m/amchanger/ppc"

  holdingdisk hd {
    directory "/m/amchanger/ppc/hold"
    use       9Gb
  }

  netusage   800 Kbps
  taperalgo  largestfit

  dumpcycle  0
  tapecycle  9 tapes

  define tapetype dvd {
    length   4450 mbytes
    filemark   50 kbytes
    speed   15000 kbytes
  }

  define dumptype default {
    program  "GNUTAR"
    compress client best
    exclude  list optional ".amanda.exclude"
    index    yes
  }

  define interface local  {
    use 10000 kbps
  }
  define interface eth100 {
    use 8000 kbps
  }
  define interface eth10  {
    use 800 kbps
  }

Disklist:

  host.do.main / {
    default
    compress client best
  } 1 local

  host.do.main /m/b {
    default
    compress client best
  } 1 local

  host.do.main /m/check {
    default
    compress client best
  } 1 local

  host.do.main /m/l {
    default
    compress client best
  } 1 local

  host.do.main /var {
    default
    compress client best
  } 1 local

> Find out with "amadmin x version" and see where Amanda gets the 
> configuration files.
> It could also be a bug introduced in some later version of course.
> That's why I would like to know the current+previous version. Did
> you compile yourself, or used rpms?

This is amanda 2.4.5, that comes with the suse-10.0 distribution.
The prevoius version was 2.4.4p1 that came with suse-9.0.

Here comes the amdump.1 file:

amdump: start at Fri Jan 20 03:05:01 CET 2006
amdump: datestamp 20060120
planner: pid 736 executable /usr/lib/amanda/planner version 2.4.5
planner: build: VERSION="Amanda-2.4.5"
planner:        BUILT_DATE="Tue Sep 13 01:54:22 UTC 2005"
planner:        BUILT_MACH="Linux adalid 2.6.12 #1 SMP Tue Jun 7 15:57:09 UTC 
2005 i686 athlon i386 GNU/Linux"
planner:        CC="gcc"
planner:        CONFIGURE_COMMAND="'./configure' '--mandir=/usr/share/man' 
'--prefix=/usr' '--infodir=/usr/share/info' '--sysconfdir=/etc' 
'--libdir=/usr/lib' '--libexecdir=/usr/lib/amanda' '--localstatedir=/var/lib' 
'--with-index-server=localhost' 
'--with-gnutar-listdir=/var/lib/amanda/gnutar-lists' 
'--with-smbclient=/usr/bin/smbclient' '--with-amandahosts' '--with-user=amanda' 
'--with-group=disk' '--with-gnutar=/bin/tar' '--disable-libtool' 
'--disable-shared' '--disable-static'"
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" VDUMP=UNDEF VRESTORE=UNDEF
planner:        XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF
planner:        SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
planner:        COMPRESS_PATH="/usr/bin/gzip"
planner:        UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr"
planner:        MAILER="/usr/bin/Mail"
planner:        listed_incr_dir="/var/lib/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="amanda" FORCE_USERID HAVE_GZIP
planner:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
planner:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
planner: time 0.000: dgram_bind: socket bound to 0.0.0.0.557
READING CONF FILES...
planner: time 0.015: startup took 0.015 secs

SENDING FLUSHES...
ENDFLUSH

SETTING UP FOR ESTIMATES...
planner: time 0.015: setting up estimates for host.do.main:/
host.do.main:/ overdue 1 day for level 0
setup_estimate: host.do.main:/: command 0, options: none    last_level 0 
next_level0 -1 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.033: setting up estimates for host.do.main:/m/b
host.do.main:/m/b overdue 1 day for level 0
setup_estimate: host.do.main:/m/b: command 0, options: none    last_level 0 
next_level0 -1 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.042: setting up estimates for host.do.main:/m/check
host.do.main:/m/check overdue 1 day for level 0
setup_estimate: host.do.main:/m/check: command 0, options: none    last_level 0 
next_level0 -1 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.069: setting up estimates for host.do.main:/m/l
host.do.main:/m/l overdue 1 day for level 0
setup_estimate: host.do.main:/m/l: command 0, options: none    last_level 0 
next_level0 -1 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.069: setting up estimates for host.do.main:/var
host.do.main:/var overdue 1 day for level 0
setup_estimate: host.do.main:/var: command 0, options: none    last_level 0 
next_level0 -1 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.070: setting up estimates took 0.054 secs

GETTING ESTIMATES...
driver: pid 737 executable /usr/lib/amanda/driver version 2.4.5
driver: tape size 4556800
driver: send-cmd time 0.002 to taper: START-TAPER 20060120
driver: adding holding disk 0 dir /m/amchanger/ppc/hold size 9437184 chunksize 
1048576
reserving 9437184 out of 9437184 for degraded-mode dumps
driver: started dumper0 pid 739
driver: started dumper1 pid 740
driver: started dumper2 pid 741
driver: started dumper3 pid 742
driver: started dumper4 pid 743
driver: started dumper5 pid 744
driver: started dumper6 pid 745
driver: started dumper7 pid 746
driver: started dumper8 pid 747
driver: started dumper9 pid 748
driver: flush size 0
driver: start time 0.024 inparallel 10 bandwidth 19600 diskspace 9437184 dir 
OBSOLETE datestamp 20060120 driver: drain-ends tapeq LARGESTFIT big-dumpers ttt
taper: pid 738 executable taper version 2.4.5
taper: page size is 4096
taper: buffer size is 32768
taper: buffer[00] at 0xb7cb1000
taper: buffer[01] at 0xb7cb9000
taper: buffer[02] at 0xb7cc1000
taper: buffer[03] at 0xb7cc9000
taper: buffer[04] at 0xb7cd1000
taper: buffer[05] at 0xb7cd9000
taper: buffer[06] at 0xb7ce1000
taper: buffer[07] at 0xb7ce9000
taper: buffer[08] at 0xb7cf1000
taper: buffer[09] at 0xb7cf9000
taper: buffer[10] at 0xb7d01000
taper: buffer[11] at 0xb7d09000
taper: buffer[12] at 0xb7d11000
taper: buffer[13] at 0xb7d19000
taper: buffer[14] at 0xb7d21000
taper: buffer[15] at 0xb7d29000
taper: buffer[16] at 0xb7d31000
taper: buffer[17] at 0xb7d39000
taper: buffer[18] at 0xb7d41000
taper: buffer[19] at 0xb7d49000
taper: buffer structures at 0xb7d51000 for 240 bytes
changer: opening pipe to: /usr/local/bin/chg-disk.pl -info
changer: got exit: 0 str: 1 9 1 1
changer_query: changer return was 9 1 1
changer_query: searchable = 1
changer_find: looking for PPC02 changer is searchable = 1
changer_search: PPC02
changer: opening pipe to: /usr/local/bin/chg-disk.pl -search PPC02
changer: got exit: 1 str: 0 Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot current
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
dumper: dgram_bind: socket bound to 0.0.0.0.565
dumper: pid 743 executable dumper4 version 2.4.5, using port 565
dumper: dgram_bind: socket bound to 0.0.0.0.563
dumper: pid 741 executable dumper2 version 2.4.5, using port 563
dumper: dgram_bind: socket bound to 0.0.0.0.564
dumper: pid 742 executable dumper3 version 2.4.5, using port 564
dumper: dgram_bind: socket bound to 0.0.0.0.567
dumper: pid 745 executable dumper6 version 2.4.5, using port 567
dumper: dgram_bind: socket bound to 0.0.0.0.561
dumper: pid 739 executable dumper0 version 2.4.5, using port 561
dumper: dgram_bind: socket bound to 0.0.0.0.566
dumper: pid 744 executable dumper5 version 2.4.5, using port 566
dumper: dgram_bind: socket bound to 0.0.0.0.562
dumper: pid 740 executable dumper1 version 2.4.5, using port 562
dumper: dgram_bind: socket bound to 0.0.0.0.568
dumper: pid 746 executable dumper7 version 2.4.5, using port 568
dumper: dgram_bind: socket bound to 0.0.0.0.569
dumper: pid 747 executable dumper8 version 2.4.5, using port 569
dumper: dgram_bind: socket bound to 0.0.0.0.570
dumper: pid 748 executable dumper9 version 2.4.5, using port 570
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
changer: opening pipe to: /usr/local/bin/chg-disk.pl -slot next
changer: got exit: 1 str: 0 Drive was not loaded
taper: slot 0: Drive was not loaded
driver: result time 0.474 from taper: TAPE-ERROR [label PPC02 or new tape not 
found in rack]
dump of driver schedule before start degraded mode:
--------
--------
dump of driver schedule after start degraded mode:
--------
--------
driver: state time 0.474 free kps: 19600 space: 9437184 taper: DOWN 
idle-dumpers: 10 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle: 
not-idle
driver: interface-state time 0.474 if : free 800 if ETH10: free 800 if ETH100: 
free 8000 if LOCAL: free 10000
driver: hdisk-state time 0.474 hdisk 0: free 9437184 dumpers 0
planner: time 10.751: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 10.751: got partial result for host host.do.main disk /m/l: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 10.751: got partial result for host host.do.main disk /m/check: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 10.751: got partial result for host host.do.main disk /m/b: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 10.751: got partial result for host host.do.main disk /: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 247.849: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 247.871: got partial result for host host.do.main disk /m/l: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 247.871: got partial result for host host.do.main disk /m/check: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 247.871: got partial result for host host.do.main disk /m/b: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 247.871: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> -2K, -1 -> -2K
planner: time 417.061: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 417.073: got partial result for host host.do.main disk /m/l: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 417.073: got partial result for host host.do.main disk /m/check: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 417.073: got partial result for host host.do.main disk /m/b: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 417.073: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 424.569: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 424.569: got partial result for host host.do.main disk /m/l: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 424.569: got partial result for host host.do.main disk /m/check: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 424.569: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> -2K, -1 -> -2K
planner: time 424.569: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 425.115: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 425.115: got partial result for host host.do.main disk /m/l: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 425.115: got partial result for host host.do.main disk /m/check: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 425.115: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 425.115: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 427.381: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 427.381: got partial result for host host.do.main disk /m/l: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 427.381: got partial result for host host.do.main disk /m/check: 
0 -> 1284960K, 1 -> -2K, -1 -> -2K
planner: time 427.381: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 427.381: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 427.486: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 427.486: got partial result for host host.do.main disk /m/l: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 427.486: got partial result for host host.do.main disk /m/check: 
0 -> 1284960K, 1 -> 630K, -1 -> -2K
planner: time 427.486: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 427.486: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 430.769: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 430.769: got partial result for host host.do.main disk /m/l: 0 -> 
202380K, 1 -> -2K, -1 -> -2K
planner: time 430.769: got partial result for host host.do.main disk /m/check: 
0 -> 1284960K, 1 -> 630K, -1 -> -2K
planner: time 430.769: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 430.769: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 431.040: got partial result for host host.do.main disk /var: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 431.040: got partial result for host host.do.main disk /m/l: 0 -> 
202380K, 1 -> 6730K, -1 -> -2K
planner: time 431.040: got partial result for host host.do.main disk /m/check: 
0 -> 1284960K, 1 -> 630K, -1 -> -2K
planner: time 431.040: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 431.040: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 433.399: got partial result for host host.do.main disk /var: 0 -> 
428820K, 1 -> -2K, -1 -> -2K
planner: time 433.399: got partial result for host host.do.main disk /m/l: 0 -> 
202380K, 1 -> 6730K, -1 -> -2K
planner: time 433.399: got partial result for host host.do.main disk /m/check: 
0 -> 1284960K, 1 -> 630K, -1 -> -2K
planner: time 433.399: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 433.399: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 433.679: got partial result for host host.do.main disk /var: 0 -> 
428820K, 1 -> 45610K, -1 -> -2K
planner: time 433.679: got partial result for host host.do.main disk /m/l: 0 -> 
202380K, 1 -> 6730K, -1 -> -2K
planner: time 433.679: got partial result for host host.do.main disk /m/check: 
0 -> 1284960K, 1 -> 630K, -1 -> -2K
planner: time 433.679: got partial result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 433.679: got partial result for host host.do.main disk /: 0 -> 
6508970K, 1 -> 24900K, -1 -> -2K
planner: time 433.680: got result for host host.do.main disk /var: 0 -> 
428820K, 1 -> 45610K, -1 -> -2K
planner: time 433.680: got result for host host.do.main disk /m/l: 0 -> 
202380K, 1 -> 6730K, -1 -> -2K
planner: time 433.680: got result for host host.do.main disk /m/check: 0 -> 
1284960K, 1 -> 630K, -1 -> -2K
planner: time 433.680: got result for host host.do.main disk /m/b: 0 -> 
2209050K, 1 -> 741870K, -1 -> -2K
planner: time 433.680: got result for host host.do.main disk /: 0 -> 6508970K, 
1 -> 24900K, -1 -> -2K
planner: time 433.680: getting estimates took 433.610 secs
FAILED QUEUE: empty
DONE QUEUE:
  0: host.do.main /var
  1: host.do.main /m/l
  2: host.do.main /m/check
  3: host.do.main /m/b
  4: host.do.main /   

ANALYZING ESTIMATES...
pondering host.do.main:/var... next_level0 -1 last_level 0 (due for level 0) 
(picking inclevel for degraded mode)   picklev: last night 0, so tonight level 1

  curr level 0 size 182652 total size 182898 total_lev0 182652 
balanced-lev0size 182652
pondering host.do.main:/m/l... next_level0 -1 last_level 0 (due for level 0) 
(picking inclevel for degraded mode)   picklev: last night 0, so tonight level 1

  curr level 0 size 140717 total size 323697 total_lev0 323369 
balanced-lev0size 323369
pondering host.do.main:/m/check... next_level0 -1 last_level 0 (due for level 
0) (picking inclevel for degraded mode)   picklev: last night 0, so tonight 
level 1

  curr level 0 size 705498 total size 1029277 total_lev0 1028867 
balanced-lev0size 1028867
pondering host.do.main:/m/b... next_level0 -1 last_level 0 (due for level 0) 
(picking inclevel for degraded mode)   picklev: last night 0, so tonight level 1

  curr level 0 size 1592721 total size 2622080 total_lev0 2621588 
balanced-lev0size 2621588
pondering host.do.main:/... next_level0 -1 last_level 0 (due for level 0) 
(picking inclevel for degraded mode)   picklev: last night 0, so tonight level 1

  curr level 0 size 2586945 total size 5209107 total_lev0 5208533 
balanced-lev0size 5208533
INITIAL SCHEDULE (size 5209107):
  host.do.main / pri 2 lev 0 size 2586945
  host.do.main /m/b pri 2 lev 0 size 1592721
  host.do.main /m/check pri 2 lev 0 size 705498
  host.do.main /var pri 2 lev 0 size 182652
  host.do.main /m/l pri 2 lev 0 size 140717

DELAYING DUMPS IF NEEDED, total_size 5209107, tape length 9113600 mark 50
  delay: Total size now 5209107.

PROMOTING DUMPS IF NEEDED, total_lev0 5208533, balanced_size 5208533...
planner: time 433.726: analysis took 0.045 secs

GENERATING SCHEDULE:
--------
DUMP host.do.main fffffeff9ffe7f / 20060120 2 0 1970:1:1:0:0:0 2586945 3077 1 
2006:1:19:2:29:0 12450 415
DUMP host.do.main fffffeff9ffe7f /m/b 20060120 2 0 1970:1:1:0:0:0 1592721 548 1 
2006:1:19:2:11:40 370935 12364
DUMP host.do.main fffffeff9ffe7f /m/check 20060120 2 0 1970:1:1:0:0:0 705498 
505 1 2006:1:19:2:19:40 315 10
DUMP host.do.main fffffeff9ffe7f /var 20060120 2 0 1970:1:1:0:0:0 182652 214 1 
2006:1:19:2:7:50 22805 760
DUMP host.do.main fffffeff9ffe7f /m/l 20060120 2 0 1970:1:1:0:0:0 140717 55 1 
2006:1:19:2:6:52 3365 112
--------
driver: send-cmd time 433.671 to dumper0: FILE-DUMP 00-00001 
/m/amchanger/ppc/hold/20060120030501/host.do.main._m_l.0 host.do.main 
fffffeff9ffe7f /m/l NODEVICE 0 1970:1:1:0:0:0 1048576 GNUTAR 140800 
|;bsd-auth;compress-best;index;exclude-list=.amanda.exclude;exclude-optional;
driver: state time 433.671 free kps: 17042 space: 9296384 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 0 runq: 4 roomq: 0 wakeup: 15 driver-idle: 
start-wait
driver: interface-state time 433.671 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 7442
driver: hdisk-state time 433.671 hdisk 0: free 9296384 dumpers 1
dumper: stream_client: connected to 192.168.1.12.1137
dumper: stream_client: our side is 0.0.0.0.1140
dumper: stream_client: connected to 192.168.1.12.1138
dumper: stream_client: our side is 0.0.0.0.1141
dumper: stream_client: connected to 192.168.1.12.1139
dumper: stream_client: our side is 0.0.0.0.1142
driver: state time 448.669 free kps: 17042 space: 9296384 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 0 runq: 4 roomq: 0 wakeup: 86400 driver-idle: 
client-constrained
driver: interface-state time 448.669 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 7442
driver: hdisk-state time 448.669 hdisk 0: free 9296384 dumpers 1
driver: result time 490.270 from dumper0: DONE 00-00001 202380 140235 56 [sec 
56.406 kb 140235 kps 2486.1 orig-kb 202380]
driver: finished-cmd time 490.364 dumper0 dumped host.do.main:/m/l
driver: send-cmd time 490.365 to dumper0: FILE-DUMP 00-00002 
/m/amchanger/ppc/hold/20060120030501/host.do.main._var.0 host.do.main 
fffffeff9ffe7f /var NODEVICE 0 1970:1:1:0:0:0 1048576 GNUTAR 182720 
|;bsd-auth;compress-best;index;exclude-list=.amanda.exclude;exclude-optional;
driver: state time 490.365 free kps: 18747 space: 9114196 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 1 runq: 3 roomq: 0 wakeup: 15 driver-idle: 
start-wait
driver: interface-state time 490.365 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 9147
driver: hdisk-state time 490.365 hdisk 0: free 9114196 dumpers 1
dumper: stream_client: connected to 192.168.1.12.1143
dumper: stream_client: our side is 0.0.0.0.1146
dumper: stream_client: connected to 192.168.1.12.1144
dumper: stream_client: our side is 0.0.0.0.1147
dumper: stream_client: connected to 192.168.1.12.1145
dumper: stream_client: our side is 0.0.0.0.1148
driver: state time 505.362 free kps: 18747 space: 9114196 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 1 runq: 3 roomq: 0 wakeup: 86400 driver-idle: 
client-constrained
driver: interface-state time 505.362 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 9147
driver: hdisk-state time 505.362 hdisk 0: free 9114196 dumpers 1
driver: result time 691.494 from dumper0: DONE 00-00002 416110 178150 201 [sec 
200.917 kb 178150 kps 886.7 orig-kb 416110]
driver: finished-cmd time 691.563 dumper0 dumped host.do.main:/var
driver: send-cmd time 691.564 to dumper0: FILE-DUMP 00-00003 
/m/amchanger/ppc/hold/20060120030501/host.do.main._m_check.0 host.do.main 
fffffeff9ffe7f /m/check NODEVICE 0 1970:1:1:0:0:0 1048576 GNUTAR 705568 
|;bsd-auth;compress-best;index;exclude-list=.amanda.exclude;exclude-optional;
driver: state time 691.564 free kps: 18203 space: 8413165 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 2 runq: 2 roomq: 0 wakeup: 15 driver-idle: 
start-wait
driver: interface-state time 691.564 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 8603
driver: hdisk-state time 691.564 hdisk 0: free 8413165 dumpers 1
dumper: stream_client: connected to 192.168.1.12.1149
dumper: stream_client: our side is 0.0.0.0.1152
dumper: stream_client: connected to 192.168.1.12.1150
dumper: stream_client: our side is 0.0.0.0.1153
dumper: stream_client: connected to 192.168.1.12.1151
dumper: stream_client: our side is 0.0.0.0.1154
driver: state time 706.563 free kps: 18203 space: 8413165 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 2 runq: 2 roomq: 0 wakeup: 86400 driver-idle: 
client-constrained
driver: interface-state time 706.563 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 8603
driver: hdisk-state time 706.563 hdisk 0: free 8413165 dumpers 1
driver: result time 1104.269 from dumper0: DONE 00-00003 1284000 704894 412 
[sec 412.385 kb 704894 kps 1709.3 orig-kb 1284000]
driver: finished-cmd time 1104.372 dumper0 dumped host.do.main:/m/check
driver: send-cmd time 1104.372 to dumper0: FILE-DUMP 00-00004 
/m/amchanger/ppc/hold/20060120030501/host.do.main._m_b.0 host.do.main 
fffffeff9ffe7f /m/b NODEVICE 0 1970:1:1:0:0:0 1048576 GNUTAR 1592832 
|;bsd-auth;compress-best;index;exclude-list=.amanda.exclude;exclude-optional;
driver: state time 1104.372 free kps: 16694 space: 6820974 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 3 runq: 1 roomq: 0 wakeup: 15 driver-idle: 
start-wait
driver: interface-state time 1104.372 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 7094
driver: hdisk-state time 1104.372 hdisk 0: free 6820974 dumpers 1
dumper: stream_client: connected to 192.168.1.12.1155
dumper: stream_client: our side is 0.0.0.0.1158
dumper: stream_client: connected to 192.168.1.12.1156
dumper: stream_client: our side is 0.0.0.0.1159
dumper: stream_client: connected to 192.168.1.12.1157
dumper: stream_client: our side is 0.0.0.0.1160
driver: state time 1119.370 free kps: 16694 space: 6820974 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 3 runq: 1 roomq: 0 wakeup: 86400 driver-idle: 
client-constrained
driver: interface-state time 1119.370 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 7094
driver: hdisk-state time 1119.370 hdisk 0: free 6820974 dumpers 1
driver: result time 1587.751 from dumper0: RQ-MORE-DISK 00-00004
driver: send-cmd time 1587.770 to dumper0: CONTINUE 00-00004 
/m/amchanger/ppc/hold/20060120030501/host.do.main._m_b.0 1048576 79680
driver: state time 1587.771 free kps: 16694 space: 6741294 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 3 runq: 1 roomq: 0 wakeup: 86400 driver-idle: 
client-constrained
driver: interface-state time 1587.771 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 7094
driver: hdisk-state time 1587.771 hdisk 0: free 6741294 dumpers 1
driver: result time 1617.429 from dumper0: RQ-MORE-DISK 00-00004
driver: send-cmd time 1617.429 to dumper0: CONTINUE 00-00004 
/m/amchanger/ppc/hold/20060120030501/host.do.main._m_b.0 1048576 83680
driver: state time 1617.430 free kps: 16694 space: 6657614 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 3 runq: 1 roomq: 0 wakeup: 86400 driver-idle: 
client-constrained
driver: interface-state time 1617.430 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 7094
driver: hdisk-state time 1617.430 hdisk 0: free 6657614 dumpers 1
driver: result time 1618.194 from dumper0: DONE 00-00004 2202810 1673281 514 
[sec 513.526 kb 1673281 kps 3258.4 orig-kb 2202810]
driver: finished-cmd time 1618.284 dumper0 dumped host.do.main:/m/b
driver: send-cmd time 1618.284 to dumper0: FILE-DUMP 00-00005 
/m/amchanger/ppc/hold/20060120030501/host.do.main._.0 host.do.main 
fffffeff9ffe7f / NODEVICE 0 1970:1:1:0:0:0 1048576 GNUTAR 2587104 
|;bsd-auth;compress-best;index;exclude-list=.amanda.exclude;exclude-optional;
driver: state time 1618.284 free kps: 18760 space: 4153356 taper: DOWN 
idle-dumpers: 9 qlen tapeq: 4 runq: 0 roomq: 0 wakeup: 86400 driver-idle: 
not-idle
driver: interface-state time 1618.284 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 9160
driver: hdisk-state time 1618.284 hdisk 0: free 4153356 dumpers 1
dumper: stream_client: connected to 192.168.1.12.1161
dumper: stream_client: our side is 0.0.0.0.1164
dumper: stream_client: connected to 192.168.1.12.1162
dumper: stream_client: our side is 0.0.0.0.1165
dumper: stream_client: connected to 192.168.1.12.1163
dumper: stream_client: our side is 0.0.0.0.1166
driver: result time 4393.391 from dumper0: DONE 00-00005 6508890 2586440 2775 
[sec 2774.765 kb 2586440 kps 932.1 orig-kb 6508890]
driver: finished-cmd time 4393.537 dumper0 dumped host.do.main:/
driver: state time 4393.537 free kps: 19600 space: 4153923 taper: DOWN 
idle-dumpers: 10 qlen tapeq: 5 runq: 0 roomq: 0 wakeup: 86400 driver-idle: 
not-idle
driver: interface-state time 4393.537 if : free 800 if ETH10: free 800 if 
ETH100: free 8000 if LOCAL: free 10000
driver: hdisk-state time 4393.537 hdisk 0: free 4153923 dumpers 0
driver: QUITTING time 4393.537 telling children to quit
driver: send-cmd time 4393.537 to dumper0: QUIT
driver: send-cmd time 4393.537 to dumper1: QUIT
driver: send-cmd time 4393.537 to dumper2: QUIT
driver: send-cmd time 4393.537 to dumper3: QUIT
driver: send-cmd time 4393.537 to dumper4: QUIT
driver: send-cmd time 4393.537 to dumper5: QUIT
driver: send-cmd time 4393.537 to dumper6: QUIT
driver: send-cmd time 4393.537 to dumper7: QUIT
driver: send-cmd time 4393.537 to dumper8: QUIT
driver: send-cmd time 4393.537 to dumper9: QUIT
driver: send-cmd time 4393.537 to taper: QUIT
taper: DONE [idle wait: 4393.065 secs]
driver: FINISHED time 4393.573
amdump: end at Fri Jan 20 04:18:15 CET 2006
Scanning /m/amchanger/ppc/hold...
  20060120030501: found Amanda directory.


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