Amanda-Users

Re: amdump freezes

2008-05-30 14:53:12
Subject: Re: amdump freezes
From: "Stefan G. Weichinger" <sgw AT amanda DOT org>
To: "Amanda user's group" <amanda-users AT amanda DOT org>
Date: Thu, 29 May 2008 21:32:14 +0200
Jean-Louis Martineau schrieb:
Stefan G. Weichinger wrote:
Jean-Louis Martineau schrieb:
It must abort the dump, and restart it.

And what's the solution for my problem?
Did it retried the dump on the next tape?

Yes.

Can you explain the problem, because i don't understand it, except you hit the end of a tape.

I try to "amadmin force" a few DLEs to 2 tapes for archiving them off-site.

All but one DLE succeed, one always hits EOT (to be expected) and triggers that error.

Post the amdump.1 log file.

I had to edit that one a bit to hide the company's name ....
See attached file.

You can increase runtapes,

Should fit easily in 2 tapes, see in attached log.

split your dle in smaller dles,

fun is that the largest DLE succeeds ;)

add more holding disk, ...

I wanted to *not* use the holdingdisk to avoid copying /data/xy within the same RAID-system every time.

One big fat RAID5 contains data and holdingdisk there.
Seems a waste of time and ressources to me ...

But if it helps doing those archive tapes, OK.

amanda only try a dump 2 times, maybe it hit end of tape on both tries?

You can try to dump only that disk: amdump <conf> <hodt> <disk>

hmm, that's plan b then. Maybe I activate the holdingdisk for that one DLE first. Slower, but if it works, ok ...

Thanks, Stefan

amdump: start at Wed May 28 21:16:06 CEST 2008
amdump: datestamp 20080528
amdump: starttime 20080528211606
planner: pid 29269 executable /usr/local/libexec/planner version 2.5.2p1
planner: build: VERSION="Amanda-2.5.2p1"
planner:        BUILT_DATE="Tue Feb 26 09:56:47 CET 2008"
planner:        BUILT_MACH="Linux dor.i.xyz.at 2.6.18-53.1.13.el5 #1 SMP Tue 
Feb 12 13:02:30 EST 2008 x86_64 x86_64 x86_64 GNU/Linux"
planner:        CC="gcc"
planner:        CONFIGURE_COMMAND="'./configure' '--with-user=amanda' 
'--with-group=disk' '--with-owner=amanda' '--with-tape-device=/dev/nst0' 
'--with-gnu-ld' '--prefix=/usr/local' '--with-debugging=/var/log/amanda/debug' 
'--with-amandahosts' '--with-config=daily' 
'--with-configdir=/usr/local/etc/amanda/' '--with-tcpportrange=50000,50040' 
'--with-udpportrange=890,899' '--without-ipv6'"
planner: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin"
planner:        libexecdir="/usr/local/libexec" mandir="/usr/local/man"
planner:        AMANDA_TMPDIR="/tmp/amanda"
planner:        AMANDA_DBGDIR="/var/log/amanda/debug"
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/bin/smbclient" GNUTAR="/bin/gtar"
planner:        COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
planner:        LPRCMD="/usr/bin/lpr" MAILER="/usr/bin/Mail"
planner:        listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
planner: defs:  DEFAULT_SERVER="dor.i.xyz.at" DEFAULT_CONFIG="daily"
planner:        DEFAULT_TAPE_SERVER="dor.i.xyz.at"
planner:        DEFAULT_TAPE_DEVICE="/dev/nst0" HAVE_MMAP NEED_STRSTR
planner:        HAVE_SYSVSHM LOCKING=POSIX_FCNTL SETPGRP_VOID 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...
driver: pid 29270 executable /usr/local/libexec/driver version 2.5.2p1
planner: timestamp 20080528211606
planner: time 0.005: startup took 0.005 secs

SENDING FLUSHES...
driver: tape size 407896064
driver: adding holding disk 0 dir /data/backup/amhold/daily size 2344462316 
chunksize 1048576
reserving 2344462316 out of 2344462316 for degraded-mode dumps
driver: send-cmd time 0.035 to taper: START-TAPER 20080528211606
driver: started dumper0 pid 29272
driver: send-cmd time 0.035 to dumper0: START 20080528211606
driver: started dumper1 pid 29273
driver: send-cmd time 0.035 to dumper1: START 20080528211606
driver: started dumper2 pid 29274
driver: send-cmd time 0.036 to dumper2: START 20080528211606
driver: started dumper3 pid 29275
driver: send-cmd time 0.036 to dumper3: START 20080528211606
driver: started dumper4 pid 29276
driver: send-cmd time 0.037 to dumper4: START 20080528211606
driver: started dumper5 pid 29277
driver: send-cmd time 0.037 to dumper5: START 20080528211606
driver: started dumper6 pid 29278
driver: send-cmd time 0.037 to dumper6: START 20080528211606
driver: started dumper7 pid 29279
driver: send-cmd time 0.038 to dumper7: START 20080528211606
driver: started dumper8 pid 29280
driver: send-cmd time 0.038 to dumper8: START 20080528211606
driver: started dumper9 pid 29281
driver: send-cmd time 0.038 to dumper9: START 20080528211606
driver: started dumper10 pid 29282
driver: send-cmd time 0.039 to dumper10: START 20080528211606
driver: started dumper11 pid 29283
driver: send-cmd time 0.039 to dumper11: START 20080528211606
driver: started dumper12 pid 29284
driver: send-cmd time 0.040 to dumper12: START 20080528211606
driver: started dumper13 pid 29285
driver: send-cmd time 0.040 to dumper13: START 20080528211606
driver: started dumper14 pid 29286
driver: send-cmd time 0.041 to dumper14: START 20080528211606
driver: started dumper15 pid 29287
driver: send-cmd time 0.041 to dumper15: START 20080528211606
ENDFLUSH

SETTING UP FOR ESTIMATES...
planner: time 0.059: setting up estimates for server.db.xyz.at:/
driver: start time 0.056 inparallel 16 bandwidth 2000 diskspace 2344462316  dir 
OBSOLETE datestamp 20080528211606 driver: drain-ends tapeq LARGESTFIT 
big-dumpers BTBTBTBTBTBTBTBT
setup_estimate: server.db.xyz.at:/: command 0, options: none    last_level 1 
next_level0 1 level_days 7    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.059: setting up estimates for server.db.xyz.at:/var
setup_estimate: server.db.xyz.at:/var: command 0, options: none    last_level 2 
next_level0 1 level_days 5    getting estimates 0 (-2) 2 (-2) -1 (-2)
planner: time 0.060: setting up estimates for server.db.xyz.at:/home
setup_estimate: server.db.xyz.at:/home: command 0, options: none    last_level 
1 next_level0 1 level_days 7    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.060: setting up estimates for server.db.xyz.at:/usr
setup_estimate: server.db.xyz.at:/usr: command 0, options: none    last_level 0 
next_level0 4 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.060: setting up estimates for server.db.xyz.at:/data
setup_estimate: server.db.xyz.at:/data: command 0, options: none    last_level 
1 next_level0 1 level_days 7    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.060: setting up estimates for server.db.xyz.at:data_db
server.db.xyz.at:data_db overdue 7 days for level 0
setup_estimate: server.db.xyz.at:data_db: command 1, options: none    
last_level -1 next_level0 -7 level_days 0    getting estimates 0 (-2) -1 (-2) 
-1 (-2)
planner: time 0.061: setting up estimates for server.db.xyz.at:data_lf
server.db.xyz.at:data_lf overdue 7 days for level 0
setup_estimate: server.db.xyz.at:data_lf: command 1, options: none    
last_level -1 next_level0 -7 level_days 0    getting estimates 0 (-2) -1 (-2) 
-1 (-2)
planner: time 0.061: setting up estimates for server.db.xyz.at:data_mail
server.db.xyz.at:data_mail overdue 7 days for level 0
setup_estimate: server.db.xyz.at:data_mail: command 1, options: none    
last_level -1 next_level0 -7 level_days 0    getting estimates 0 (-2) -1 (-2) 
-1 (-2)
planner: time 0.061: setting up estimates for server.db.xyz.at:data_wn
server.db.xyz.at:data_wn overdue 7 days for level 0
setup_estimate: server.db.xyz.at:data_wn: command 1, options: none    
last_level -1 next_level0 -7 level_days 0    getting estimates 0 (-2) -1 (-2) 
-1 (-2)
planner: time 0.061: setting up estimates for server.db.xyz.at:data_gf
server.db.xyz.at:data_gf overdue 7 days for level 0
setup_estimate: server.db.xyz.at:data_gf: command 1, options: none    
last_level -1 next_level0 -7 level_days 0    getting estimates 0 (-2) -1 (-2) 
-1 (-2)
planner: time 0.061: setting up estimates for server.db.xyz.at:data_installation
server.db.xyz.at:data_installation overdue 7 days for level 0
setup_estimate: server.db.xyz.at:data_installation: command 1, options: none    
last_level -1 next_level0 -7 level_days 0    getting estimates 0 (-2) -1 (-2) 
-1 (-2)
planner: time 0.061: setting up estimates for server.wn.xyz.at:/etc
setup_estimate: server.wn.xyz.at:/etc: command 0, options: none    last_level 0 
next_level0 4 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.062: setting up estimates for server.lf.xyz.at:/etc
setup_estimate: server.lf.xyz.at:/etc: command 0, options: none    last_level 0 
next_level0 4 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
planner: time 0.062: setting up estimates took 0.003 secs

GETTING ESTIMATES...
taper: pid 29271 executable taper version 2.5.2p1
taper: page size = 4096
taper: buffer size is 32768
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -info
dumper: pid 29273 executable dumper1 version 2.5.2p1
dumper: pid 29272 executable dumper0 version 2.5.2p1
dumper: pid 29274 executable dumper2 version 2.5.2p1
dumper: pid 29277 executable dumper5 version 2.5.2p1
dumper: pid 29278 executable dumper6 version 2.5.2p1
dumper: pid 29279 executable dumper7 version 2.5.2p1
dumper: pid 29280 executable dumper8 version 2.5.2p1
dumper: pid 29284 executable dumper12 version 2.5.2p1
dumper: pid 29285 executable dumper13 version 2.5.2p1
dumper: pid 29286 executable dumper14 version 2.5.2p1
dumper: pid 29287 executable dumper15 version 2.5.2p1
dumper: pid 29275 executable dumper3 version 2.5.2p1
dumper: pid 29276 executable dumper4 version 2.5.2p1
dumper: pid 29281 executable dumper9 version 2.5.2p1
dumper: pid 29282 executable dumper10 version 2.5.2p1
dumper: pid 29283 executable dumper11 version 2.5.2p1
planner: time 0.178: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk data_gf: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk data_wn: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk data_lf: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk data_db: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk /data: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk /usr: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk /home: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk /var: 0 
-> -2K, 2 -> -2K, -1 -> -2K
planner: time 0.178: got partial result for host server.db.xyz.at disk /: 0 -> 
-2K, 1 -> -2K, -1 -> -2K
planner: time 0.244: got partial result for host server.wn.xyz.at disk /etc: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 0.267: got partial result for host server.lf.xyz.at disk /etc: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 0.356: got partial result for host server.lf.xyz.at disk /etc: 0 
-> 55370K, 1 -> -2K, -1 -> -2K
planner: time 0.390: got partial result for host server.lf.xyz.at disk /etc: 0 
-> 55370K, 1 -> 290K, -1 -> -2K
planner: time 0.394: got result for host server.lf.xyz.at disk /etc: 0 -> 
55370K, 1 -> 290K, -1 -> -2K
planner: time 1.239: got partial result for host server.wn.xyz.at disk /etc: 0 
-> 62380K, 1 -> -2K, -1 -> -2K
planner: time 1.285: got partial result for host server.wn.xyz.at disk /etc: 0 
-> 62380K, 1 -> 340K, -1 -> -2K
planner: time 1.286: got result for host server.wn.xyz.at disk /etc: 0 -> 
62380K, 1 -> 340K, -1 -> -2K
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -search wpa-05
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -slot current
planner: time 7.953: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk data_gf: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk data_wn: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk data_lf: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk data_db: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk /data: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk /usr: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk /home: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk /var: 0 
-> -2K, 2 -> -2K, -1 -> -2K
planner: time 7.953: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk data_gf: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk data_wn: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk data_lf: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk data_db: 
0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk /data: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk /usr: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk /home: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk /var: 0 
-> -2K, 2 -> -2K, -1 -> -2K
planner: time 8.233: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk 
data_db: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk /data: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk /usr: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk /home: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk /var: 0 
-> 293270K, 2 -> -2K, -1 -> -2K
planner: time 10.464: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk 
data_db: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk /data: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk /usr: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk /home: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk /var: 0 
-> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 10.523: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk 
data_db: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk /data: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk /usr: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> -2K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk /var: 0 
-> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 12.318: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk 
data_db: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk /data: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk /usr: 0 
-> -2K, 1 -> -2K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk /var: 0 
-> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 12.457: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
taper: slot: 2 wrote label `wpa-05' date `20080528211606'
driver: result time 14.294 from taper: TAPER-OK 
driver: state time 14.294 free kps: 2000 space: 2344462316 taper: idle 
idle-dumpers: 16 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 14.294 if default: free 600 if local: free 1000 if 
le0: free 400
driver: hdisk-state time 14.294 hdisk 0: free 2344462316 dumpers 0
planner: time 41.207: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk 
data_db: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk /data: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk /usr: 0 
-> 1907650K, 1 -> -2K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk /var: 0 
-> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 41.207: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk 
data_db: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk /data: 
0 -> -2K, 1 -> -2K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk /usr: 0 
-> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk /var: 0 
-> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 42.583: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 47.403: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 47.403: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 47.403: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 47.403: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 47.403: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 47.403: got partial result for host server.db.xyz.at disk 
data_db: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 47.404: got partial result for host server.db.xyz.at disk /data: 
0 -> 15447600K, 1 -> 761491K, -1 -> -2K
planner: time 47.404: got partial result for host server.db.xyz.at disk /usr: 0 
-> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 47.404: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 47.404: got partial result for host server.db.xyz.at disk /var: 0 
-> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 47.404: got partial result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk 
data_db: 0 -> 189292990K, -1 -> -2K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk /data: 
0 -> 15447600K, 1 -> 761491K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk /usr: 
0 -> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk /var: 
0 -> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 141.124: got partial result for host server.db.xyz.at disk /: 0 
-> 511090K, 1 -> 2290K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> 37152655K, -1 -> -2K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk 
data_db: 0 -> 189292990K, -1 -> -2K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk /data: 
0 -> 15447600K, 1 -> 761491K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk /usr: 
0 -> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk /var: 
0 -> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 159.269: got partial result for host server.db.xyz.at disk /: 0 
-> 511090K, 1 -> 2290K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> 5218700K, -1 -> -2K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> 37152655K, -1 -> -2K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk 
data_db: 0 -> 189292990K, -1 -> -2K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk /data: 
0 -> 15447600K, 1 -> 761491K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk /usr: 
0 -> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk /var: 
0 -> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 162.304: got partial result for host server.db.xyz.at disk /: 0 
-> 511090K, 1 -> 2290K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> 382165387K, -1 -> -2K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> 5218700K, -1 -> -2K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> 37152655K, -1 -> -2K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk 
data_db: 0 -> 189292990K, -1 -> -2K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk /data: 
0 -> 15447600K, 1 -> 761491K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk /usr: 
0 -> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk /var: 
0 -> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 309.907: got partial result for host server.db.xyz.at disk /: 0 
-> 511090K, 1 -> 2290K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> -2K, -1 -> -2K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> 7118103K, -1 -> -2K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> 382165387K, -1 -> -2K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> 5218700K, -1 -> -2K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> 37152655K, -1 -> -2K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk 
data_db: 0 -> 189292990K, -1 -> -2K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk /data: 
0 -> 15447600K, 1 -> 761491K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk /usr: 
0 -> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk /var: 
0 -> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 310.666: got partial result for host server.db.xyz.at disk /: 0 
-> 511090K, 1 -> 2290K, -1 -> -2K
planner: time 313.250: got partial result for host server.db.xyz.at disk 
data_installation: 0 -> 9387530K, -1 -> -2K, -1 -> -2K
planner: time 313.250: got partial result for host server.db.xyz.at disk 
data_gf: 0 -> 7118103K, -1 -> -2K, -1 -> -2K
planner: time 313.250: got partial result for host server.db.xyz.at disk 
data_wn: 0 -> 382165387K, -1 -> -2K, -1 -> -2K
planner: time 313.250: got partial result for host server.db.xyz.at disk 
data_mail: 0 -> 5218700K, -1 -> -2K, -1 -> -2K
planner: time 313.250: got partial result for host server.db.xyz.at disk 
data_lf: 0 -> 37152655K, -1 -> -2K, -1 -> -2K
planner: time 313.250: got partial result for host server.db.xyz.at disk 
data_db: 0 -> 189292990K, -1 -> -2K, -1 -> -2K
planner: time 313.251: got partial result for host server.db.xyz.at disk /data: 
0 -> 15447600K, 1 -> 761491K, -1 -> -2K
planner: time 313.251: got partial result for host server.db.xyz.at disk /usr: 
0 -> 1907650K, 1 -> 15570K, -1 -> -2K
planner: time 313.251: got partial result for host server.db.xyz.at disk /home: 
0 -> 12835300K, 1 -> 556090K, -1 -> -2K
planner: time 313.251: got partial result for host server.db.xyz.at disk /var: 
0 -> 293270K, 2 -> 140970K, -1 -> -2K
planner: time 313.251: got partial result for host server.db.xyz.at disk /: 0 
-> 511090K, 1 -> 2290K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk 
data_installation: 0 -> 9387530K, -1 -> -2K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk data_gf: 0 -> 
7118103K, -1 -> -2K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk data_wn: 0 -> 
382165387K, -1 -> -2K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk data_mail: 0 
-> 5218700K, -1 -> -2K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk data_lf: 0 -> 
37152655K, -1 -> -2K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk data_db: 0 -> 
189292990K, -1 -> -2K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk /data: 0 -> 
15447600K, 1 -> 761491K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk /usr: 0 -> 
1907650K, 1 -> 15570K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk /home: 0 -> 
12835300K, 1 -> 556090K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk /var: 0 -> 
293270K, 2 -> 140970K, -1 -> -2K
planner: time 313.251: got result for host server.db.xyz.at disk /: 0 -> 
511090K, 1 -> 2290K, -1 -> -2K
planner: time 313.251: getting estimates took 313.189 secs
FAILED QUEUE: empty
DONE QUEUE:
  0: server.lf.xyz.at /etc
  1: server.wn.xyz.at /etc
  2: server.db.xyz.at data_installation
  3: server.db.xyz.at data_gf
  4: server.db.xyz.at data_wn
  5: server.db.xyz.at data_mail
  6: server.db.xyz.at data_lf
  7: server.db.xyz.at data_db
  8: server.db.xyz.at /data
  9: server.db.xyz.at /usr
 10: server.db.xyz.at /home
 11: server.db.xyz.at /var
 12: server.db.xyz.at /   

ANALYZING ESTIMATES...
pondering server.lf.xyz.at:/etc... next_level0 4 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 290 csize 27 total size 123 total_lev0 0 balanced-lev0size 
880
pondering server.wn.xyz.at:/etc... next_level0 4 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 340 csize 47 total size 202 total_lev0 0 balanced-lev0size 
1945
pondering server.db.xyz.at:data_installation... next_level0 -7 last_level -1 
(due for level 0) (new disk, can't switch to degraded mode)
  curr level 0 nsize 9387530 csize 8592130 total size 8592364 total_lev0 
8592130 balanced-lev0size 1720371
pondering server.db.xyz.at:data_gf... next_level0 -7 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 nsize 7118103 csize 1495601 total size 10087997 total_lev0 
10087731 balanced-lev0size 2019491
pondering server.db.xyz.at:data_wn... next_level0 -7 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 nsize 382165387 csize 267644242 total size 277732271 total_lev0 
277731973 balanced-lev0size 55548339
pondering server.db.xyz.at:data_mail... next_level0 -7 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 nsize 5218700 csize 2609350 total size 280341653 total_lev0 
280341323 balanced-lev0size 56070209
pondering server.db.xyz.at:data_lf... next_level0 -7 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 nsize 37152655 csize 29699733 total size 310041418 total_lev0 
310041056 balanced-lev0size 62010155
pondering server.db.xyz.at:data_db... next_level0 -7 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 nsize 189292990 csize 156116112 total size 466157562 total_lev0 
466157168 balanced-lev0size 93233377
pondering server.db.xyz.at:/data... next_level0 1 last_level 1 (not due for a 
full dump, picking an incr level)
   pick: size 761491 level 1 days 7 (thresh 3089520K, 2 days)
  curr level 1 nsize 761491 csize 464872 total size 466622466 total_lev0 
466157168 balanced-lev0size 95855707
pondering server.db.xyz.at:/usr... next_level0 4 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 15570 csize 5453 total size 466627951 total_lev0 466157168 
balanced-lev0size 95996529
pondering server.db.xyz.at:/home... next_level0 1 last_level 1 (not due for a 
full dump, picking an incr level)
   pick: size 556090 level 1 days 7 (thresh 2567060K, 2 days)
  curr level 1 nsize 556090 csize 148598 total size 466776581 total_lev0 
466157168 balanced-lev0size 98397882
pondering server.db.xyz.at:/var... next_level0 1 last_level 2 (not due for a 
full dump, picking an incr level)
   pick: size 140970 level 2 days 5 (thresh 234616K, 2 days)
  curr level 2 nsize 140970 csize 32190 total size 466808803 total_lev0 
466157168 balanced-lev0size 98417755
pondering server.db.xyz.at:/... next_level0 1 last_level 1 (not due for a full 
dump, picking an incr level)
   pick: size 2290 level 1 days 7 (thresh 102218K, 2 days)
  curr level 1 nsize 2290 csize 291 total size 466809126 total_lev0 466157168 
balanced-lev0size 98446529
INITIAL SCHEDULE (size 466809126):
  server.db.xyz.at data_wn pri 7 lev 0 nsize 382165387 csize 267644242
  server.db.xyz.at data_db pri 7 lev 0 nsize 189292990 csize 156116112
  server.db.xyz.at data_lf pri 7 lev 0 nsize 37152655 csize 29699733
  server.db.xyz.at data_installation pri 7 lev 0 nsize 9387530 csize 8592130
  server.db.xyz.at data_mail pri 7 lev 0 nsize 5218700 csize 2609350
  server.db.xyz.at data_gf pri 7 lev 0 nsize 7118103 csize 1495601
  server.db.xyz.at /data pri 0 lev 1 nsize 761491 csize 464872
  server.db.xyz.at /home pri 0 lev 1 nsize 556090 csize 148598
  server.db.xyz.at /var pri 0 lev 2 nsize 140970 csize 32190
  server.db.xyz.at /usr pri 0 lev 1 nsize 15570 csize 5453
  server.db.xyz.at / pri 0 lev 1 nsize 2290 csize 291
  server.wn.xyz.at /etc pri 0 lev 1 nsize 340 csize 47
  server.lf.xyz.at /etc pri 0 lev 1 nsize 290 csize 27

DELAYING DUMPS IF NEEDED, total_size 466809126, tape length 815792128 mark 0
  delay: Total size now 466809126.

PROMOTING DUMPS IF NEEDED, total_lev0 466157168, balanced_size 98446529...
planner: time 313.270: analysis took 0.017 secs

GENERATING SCHEDULE:
--------
DUMP server.db.xyz.at ffffffff9ffeffffffff00 data_wn 20080528211606 7 0 
1970:1:1:0:0:0 382165387 267644242 19580 13669
DUMP server.db.xyz.at ffffffff9ffeffffffff00 data_db 20080528211606 7 0 
1970:1:1:0:0:0 189292990 156116112 11209 13927
DUMP server.db.xyz.at ffffffff9ffeffffffff00 data_lf 20080528211606 7 0 
1970:1:1:0:0:0 37152655 29699733 2105 14105
DUMP server.db.xyz.at ffffffff9ffeffffffff00 data_installation 20080528211606 7 
0 1970:1:1:0:0:0 9387530 8592130 542 15833
DUMP server.db.xyz.at ffffffff9ffeffffffff00 data_mail 20080528211606 7 0 
1970:1:1:0:0:0 5218700 2609350 2548 1024
DUMP server.db.xyz.at ffffffff9ffeffffffff00 data_gf 20080528211606 7 0 
1970:1:1:0:0:0 7118103 1495601 164 9117
DUMP server.db.xyz.at ffffffff9ffeffffffff00 /data 20080528211606 0 1 
2008:5:22:23:23:33 761491 464872 60 7732
DUMP server.db.xyz.at ffffffff9ffeffffffff00 /home 20080528211606 0 1 
2008:5:23:0:19:10 556090 148598 77 1921
DUMP server.db.xyz.at ffffffff9ffeffffffff00 /var 20080528211606 0 2 
2008:5:24:9:18:9 140970 32190 12 2553
DUMP server.db.xyz.at ffffffff9ffeffffffff00 /usr 20080528211606 0 1 
2008:5:28:5:48:7 15570 5453 11 479
DUMP server.db.xyz.at ffffffff9ffeffffffff00 / 20080528211606 0 1 
2008:5:23:0:46:47 2290 291 7 37
DUMP server.wn.xyz.at ffffffff9ffeffffffff00 /etc 20080528211606 0 1 
2008:5:27:20:38:23 340 47 0 64
DUMP server.lf.xyz.at ffffffff9ffeffffffff00 /etc 20080528211606 0 1 
2008:5:27:20:38:23 290 27 0 33
--------
driver: flush size 0
driver: started chunker0 pid 30850
driver: send-cmd time 313.286 to chunker0: START 20080528211606
driver: send-cmd time 313.286 to chunker0: PORT-WRITE 00-00001 
/data/backup/amhold/daily/20080528211606/server.wn.xyz.at._etc.1 
server.wn.xyz.at ffffffff9ffeffffffff00 /etc 1 2008:5:27:20:38:23 1048576 
GNUTAR 128 
|;auth=BSD;compress-best;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;
chunker: pid 30850 executable chunker0 version 2.5.2p1
driver: result time 313.313 from chunker0: PORT 50022
driver: send-cmd time 313.313 to dumper0: PORT-DUMP 00-00001 50022 
server.wn.xyz.at ffffffff9ffeffffffff00 /etc NODEVICE 1 2008:5:27:20:38:23 
GNUTAR X X X 
|;auth=BSD;compress-best;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;
driver: started chunker1 pid 30851
driver: send-cmd time 313.314 to chunker1: START 20080528211606
driver: send-cmd time 313.314 to chunker1: PORT-WRITE 01-00002 
/data/backup/amhold/daily/20080528211606/server.lf.xyz.at._etc.1 
server.lf.xyz.at ffffffff9ffeffffffff00 /etc 1 2008:5:27:20:38:23 1048576 
GNUTAR 96 
|;auth=BSD;compress-best;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;
chunker: pid 30851 executable chunker1 version 2.5.2p1
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.wn.xyz.at;config=daily;
GNUTAR /etc  1 2008:5:27:20:38:23 OPTIONS 
|;auth=BSD;compress-best;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;

----

driver: result time 313.322 from chunker1: PORT 50023
driver: send-cmd time 313.322 to dumper1: PORT-DUMP 01-00002 50023 
server.lf.xyz.at ffffffff9ffeffffffff00 /etc NODEVICE 1 2008:5:27:20:38:23 
GNUTAR X X X 
|;auth=BSD;compress-best;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.lf.xyz.at;config=daily;
GNUTAR /etc  1 2008:5:27:20:38:23 OPTIONS 
|;auth=BSD;compress-best;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;

----

got response:
----
CONNECT DATA 50014 MESG 50015 INDEX 50016
OPTIONS features=ffffffff9ffeffffffff00;

----

got response:
----
CONNECT DATA 50037 MESG 50038 INDEX 50039
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: state time 313.794 free kps: 1903 space: 2344462092 taper: idle 
idle-dumpers: 14 qlen tapeq: 0 runq: 11 roomq: 0 wakeup: 0 driver-idle: 
no-bandwidth
driver: interface-state time 313.794 if default: free 503 if local: free 1000 
if le0: free 400
driver: hdisk-state time 313.794 hdisk 0: free 2344462092 dumpers 2
driver: result time 313.794 from dumper0: DONE 00-00001 340 17 0 "[sec 0.272 kb 
17 kps 62.3 orig-kb 340]"
driver: finished-cmd time 313.794 dumper0 dumped server.wn.xyz.at:/etc
driver: send-cmd time 313.794 to chunker0: DONE 00-00001
driver: state time 313.795 free kps: 1903 space: 2344462092 taper: idle 
idle-dumpers: 14 qlen tapeq: 0 runq: 11 roomq: 0 wakeup: 0 driver-idle: 
no-bandwidth
driver: interface-state time 313.795 if default: free 503 if local: free 1000 
if le0: free 400
driver: hdisk-state time 313.795 hdisk 0: free 2344462092 dumpers 2
driver: result time 313.795 from chunker0: DONE 00-00001 17 "[sec 0.450 kb 17 
kps 108.8]"
driver: finished-cmd time 313.795 chunker0 chunked server.wn.xyz.at:/etc
driver: send-cmd time 313.804 to taper: FILE-WRITE 00-00003 
/data/backup/amhold/daily/20080528211606/server.wn.xyz.at._etc.1 
server.wn.xyz.at ffffffff9ffeffffffff00 /etc 1 20080528211606 0
driver: startaflush: LARGESTFIT server.wn.xyz.at /etc 49 407896064
driver: state time 313.896 free kps: 1967 space: 2344462171 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 11 roomq: 0 wakeup: 0 driver-idle: 
no-bandwidth
driver: interface-state time 313.896 if default: free 567 if local: free 1000 
if le0: free 400
driver: hdisk-state time 313.896 hdisk 0: free 2344462171 dumpers 1
driver: result time 313.896 from dumper1: DONE 01-00002 290 14 0 "[sec 0.362 kb 
14 kps 38.7 orig-kb 290]"
driver: finished-cmd time 313.896 dumper1 dumped server.lf.xyz.at:/etc
driver: send-cmd time 313.896 to chunker1: DONE 01-00002
driver: state time 313.897 free kps: 1967 space: 2344462171 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 11 roomq: 0 wakeup: 0 driver-idle: 
no-bandwidth
driver: interface-state time 313.897 if default: free 567 if local: free 1000 
if le0: free 400
driver: hdisk-state time 313.897 hdisk 0: free 2344462171 dumpers 1
driver: result time 313.897 from chunker1: DONE 01-00002 14 "[sec 0.552 kb 14 
kps 83.3]"
driver: finished-cmd time 313.897 chunker1 chunked server.lf.xyz.at:/etc
taper: reader-side: got label wpa-05 filenum 1
driver: state time 316.431 free kps: 2000 space: 2344462221 taper: writing 
idle-dumpers: 16 qlen tapeq: 1 runq: 11 roomq: 0 wakeup: 0 driver-idle: no-hold
driver: interface-state time 316.431 if default: free 600 if local: free 1000 
if le0: free 400
driver: hdisk-state time 316.431 hdisk 0: free 2344462221 dumpers 0
driver: result time 316.431 from taper: DONE 00-00003 wpa-05 1 "[sec 2.621 kb 
32 kps 12.2 {wr: writers 2 rdwait 0.000 wrwait 0.001 filemark 2.619}]"
driver: finished-cmd time 316.431 taper wrote server.wn.xyz.at:/etc
driver: send-cmd time 316.431 to taper: FILE-WRITE 00-00004 
/data/backup/amhold/daily/20080528211606/server.lf.xyz.at._etc.1 
server.lf.xyz.at ffffffff9ffeffffffff00 /etc 1 20080528211606 0
driver: startaflush: LARGESTFIT server.lf.xyz.at /etc 46 407896015
taper: reader-side: got label wpa-05 filenum 2
driver: state time 316.482 free kps: 2000 space: 2344462270 taper: writing 
idle-dumpers: 16 qlen tapeq: 0 runq: 11 roomq: 0 wakeup: 0 driver-idle: no-hold
driver: interface-state time 316.482 if default: free 600 if local: free 1000 
if le0: free 400
driver: hdisk-state time 316.482 hdisk 0: free 2344462270 dumpers 0
driver: result time 316.482 from taper: DONE 00-00004 wpa-05 2 "[sec 0.050 kb 
32 kps 635.9 {wr: writers 2 rdwait 0.000 wrwait 0.001 filemark 0.048}]"
driver: finished-cmd time 316.482 taper wrote server.lf.xyz.at:/etc
driver: dumping server.db.xyz.at:data_wn directly to tape
driver: send-cmd time 316.482 to taper: PORT-WRITE 00-00005 server.db.xyz.at 
ffffffff9ffeffffffff00 data_wn 0 20080528211606 0 NULL 65536
driver: result time 316.487 from taper: PORT 50004
driver: send-cmd time 316.487 to dumper0: PORT-DUMP 00-00005 50004 
server.db.xyz.at ffffffff9ffeffffffff00 data_wn /data/wn 0 1970:1:1:0:0:0 
GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 316.487 free kps: 0 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 10 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 316.487 if default: free 0 if local: free 1000 if 
le0: free 400
driver: hdisk-state time 316.487 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR data_wn /data/wn 0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;compress-fast;index;

----

got response:
----
CONNECT DATA 50011 MESG 50013 INDEX 50014
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 19892.445 from dumper0: DONE 00-00005 382404210 268089728 
19576 "[sec 19575.897 kb 268089728 kps 13694.9 orig-kb 382404210]"
taper: reader-side: got label wpa-05 filenum 3
driver: result time 19892.462 from taper: DONE 00-00005 wpa-05 3 "[sec 
19575.971 kb 268089728 kps 13694.8 {wr: writers 8377805 rdwait 16669.745 wrwait 
2893.053 filemark 0.048}]"
driver: dumping server.db.xyz.at:data_db directly to tape
driver: send-cmd time 19892.519 to taper: PORT-WRITE 00-00006 server.db.xyz.at 
ffffffff9ffeffffffff00 data_db 0 20080528211606 0 NULL 65536
driver: result time 19892.539 from taper: PORT 50023
driver: send-cmd time 19892.539 to dumper0: PORT-DUMP 00-00006 50023 
server.db.xyz.at ffffffff9ffeffffffff00 data_db /data/db 0 1970:1:1:0:0:0 
GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 19892.539 free kps: 0 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 9 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 19892.539 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 19892.539 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR data_db /data/db 0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;compress-fast;index;

----

got response:
----
CONNECT DATA 50030 MESG 50031 INDEX 50032
OPTIONS features=ffffffff9ffeffffffff00;

----

taper: writing end marker. [wpa-05 ERR kb 410072096 fm 4]
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -info
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -search wpa-06
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -slot current
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -slot next
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -slot next
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -slot next
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -slot next
changer: opening pipe to: /usr/local/libexec/chg-zd-mtx -slot next
taper: slot: 14 wrote label `wpa-06' date `20080528211606'
dumper: kill index command
driver: result time 31032.847 from dumper0: FAILED 00-00006 "[data write: 
Connection reset by peer]"
driver: result time 31032.847 from taper: TRY-AGAIN 00-00006 "[writing file: No 
space left on device]"
driver: dumping server.db.xyz.at:data_lf directly to tape
driver: send-cmd time 31032.962 to taper: PORT-WRITE 00-00007 server.db.xyz.at 
ffffffff9ffeffffffff00 data_lf 0 20080528211606 0 NULL 65536
driver: result time 31032.995 from taper: PORT 50011
driver: send-cmd time 31032.995 to dumper0: PORT-DUMP 00-00007 50011 
server.db.xyz.at ffffffff9ffeffffffff00 data_lf /data/lf 0 1970:1:1:0:0:0 
GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 31032.995 free kps: 0 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 8 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 31032.995 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 31032.995 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR data_lf /data/lf 0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;compress-fast;index;

----

got response:
----
CONNECT DATA 50018 MESG 50019 INDEX 50020
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 33147.225 from dumper0: DONE 00-00007 37217700 29772509 
2114 "[sec 2113.967 kb 29772509 kps 14083.7 orig-kb 37217700]"
taper: reader-side: got label wpa-06 filenum 1
driver: result time 33147.267 from taper: DONE 00-00007 wpa-06 1 "[sec 2114.267 
kb 29772512 kps 14081.7 {wr: writers 930392 rdwait 1789.720 wrwait 319.453 
filemark 3.651}]"
driver: dumping server.db.xyz.at:data_installation directly to tape
driver: send-cmd time 33147.339 to taper: PORT-WRITE 00-00008 server.db.xyz.at 
ffffffff9ffeffffffff00 data_installation 0 20080528211606 0 NULL 65536
driver: result time 33147.373 from taper: PORT 50035
driver: send-cmd time 33147.373 to dumper0: PORT-DUMP 00-00008 50035 
server.db.xyz.at ffffffff9ffeffffffff00 data_installation /data/installation 0 
1970:1:1:0:0:0 GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 33147.373 free kps: 0 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 7 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 33147.373 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 33147.373 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR data_installation /data/installation 0 1970:1:1:0:0:0 OPTIONS 
|;auth=BSD;compress-fast;index;

----

got response:
----
CONNECT DATA 50001 MESG 50002 INDEX 50003
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 33693.186 from dumper0: DONE 00-00008 9389430 8594303 546 
"[sec 545.533 kb 8594303 kps 15753.9 orig-kb 9389430]"
taper: reader-side: got label wpa-06 filenum 2
driver: result time 33693.215 from taper: DONE 00-00008 wpa-06 2 "[sec 545.838 
kb 8594304 kps 15745.1 {wr: writers 268573 rdwait 449.435 wrwait 92.477 
filemark 3.511}]"
driver: dumping server.db.xyz.at:data_mail directly to tape
driver: send-cmd time 33693.260 to taper: PORT-WRITE 00-00009 server.db.xyz.at 
ffffffff9ffeffffffff00 data_mail 0 20080528211606 0 NULL 65536
driver: result time 33693.293 from taper: PORT 50007
driver: send-cmd time 33693.293 to dumper0: PORT-DUMP 00-00009 50007 
server.db.xyz.at ffffffff9ffeffffffff00 data_mail /data/mail 0 1970:1:1:0:0:0 
GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 33693.293 free kps: 976 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 6 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 33693.293 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 33693.293 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR data_mail /data/mail 0 1970:1:1:0:0:0 OPTIONS 
|;auth=BSD;compress-fast;index;

----

got response:
----
CONNECT DATA 50014 MESG 50015 INDEX 50016
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 34073.030 from dumper0: DONE 00-00009 5218900 3343730 379 
"[sec 379.461 kb 3343730 kps 8811.8 orig-kb 5218900]"
taper: reader-side: got label wpa-06 filenum 3
driver: result time 34073.043 from taper: DONE 00-00009 wpa-06 3 "[sec 379.744 
kb 3343744 kps 8805.2 {wr: writers 104493 rdwait 343.780 wrwait 34.393 filemark 
1.414}]"
driver: dumping server.db.xyz.at:data_gf directly to tape
driver: send-cmd time 34073.062 to taper: PORT-WRITE 00-00010 server.db.xyz.at 
ffffffff9ffeffffffff00 data_gf 0 20080528211606 0 NULL 65536
driver: result time 34073.081 from taper: PORT 50017
driver: send-cmd time 34073.081 to dumper0: PORT-DUMP 00-00010 50017 
server.db.xyz.at ffffffff9ffeffffffff00 data_gf /data/gf 0 1970:1:1:0:0:0 
GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 34073.081 free kps: 0 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 5 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 34073.081 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 34073.081 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR data_gf /data/gf 0 1970:1:1:0:0:0 OPTIONS |;auth=BSD;compress-fast;index;

----

got response:
----
CONNECT DATA 50024 MESG 50025 INDEX 50026
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 34255.192 from dumper0: DONE 00-00010 7119090 1495799 182 
"[sec 181.927 kb 1495799 kps 8221.9 orig-kb 7119090]"
taper: reader-side: got label wpa-06 filenum 4
driver: result time 34255.228 from taper: DONE 00-00010 wpa-06 4 "[sec 182.142 
kb 1495808 kps 8212.3 {wr: writers 46745 rdwait 161.977 wrwait 16.465 filemark 
3.621}]"
driver: dumping server.db.xyz.at:/data directly to tape
driver: send-cmd time 34255.266 to taper: PORT-WRITE 00-00011 server.db.xyz.at 
ffffffff9ffeffffffff00 /data 1 20080528211606 0 NULL 65536
driver: result time 34255.286 from taper: PORT 50036
driver: send-cmd time 34255.286 to dumper0: PORT-DUMP 00-00011 50036 
server.db.xyz.at ffffffff9ffeffffffff00 /data NODEVICE 1 2008:5:22:23:23:33 
GNUTAR X X X 
|;auth=BSD;compress-fast;index;exclude-file=./snapshots;exclude-file=./backup/amhold;exclude-file=./db;exclude-file=./lf;exclude-file=./mail;exclude-file=./wn;exclude-file=./gf;exclude-file=./installation;
driver: state time 34255.286 free kps: 0 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 4 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 34255.286 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 34255.286 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR /data  1 2008:5:22:23:23:33 OPTIONS 
|;auth=BSD;compress-fast;index;exclude-file=./snapshots;exclude-file=./backup/amhold;exclude-file=./db;exclude-file=./lf;exclude-file=./mail;exclude-file=./wn;exclude-file=./gf;exclude-file=./installation;

----

got response:
----
CONNECT DATA 50002 MESG 50003 INDEX 50004
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 34308.844 from dumper0: DONE 00-00011 821260 762889 53 
"[sec 53.337 kb 762889 kps 14303.0 orig-kb 821260]"
taper: reader-side: got label wpa-06 filenum 5
driver: result time 34308.851 from taper: DONE 00-00011 wpa-06 5 "[sec 53.560 
kb 762912 kps 14244.0 {wr: writers 23842 rdwait 43.317 wrwait 8.464 filemark 
1.741}]"
driver: dumping server.db.xyz.at:/home directly to tape
driver: send-cmd time 34308.864 to taper: PORT-WRITE 00-00012 server.db.xyz.at 
ffffffff9ffeffffffff00 /home 1 20080528211606 0 NULL 65536
driver: result time 34308.867 from taper: PORT 50007
driver: send-cmd time 34308.867 to dumper0: PORT-DUMP 00-00012 50007 
server.db.xyz.at ffffffff9ffeffffffff00 /home NODEVICE 1 2008:5:23:0:19:10 
GNUTAR X X X 
|;auth=BSD;compress-fast;index;exclude-list=/usr/local/etc/amanda/daily/excludes/home_excludes;
driver: state time 34308.867 free kps: 79 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 3 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 34308.867 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 34308.867 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR /home  1 2008:5:23:0:19:10 OPTIONS 
|;auth=BSD;compress-fast;index;exclude-list=/usr/local/etc/amanda/daily/excludes/home_excludes;

----

got response:
----
CONNECT DATA 50014 MESG 50015 INDEX 50016
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 34342.097 from dumper0: DONE 00-00012 574980 553092 33 
"[sec 33.181 kb 553092 kps 16668.5 orig-kb 574980]"
taper: reader-side: got label wpa-06 filenum 6
driver: result time 34342.097 from taper: DONE 00-00012 wpa-06 6 "[sec 33.226 
kb 553120 kps 16646.8 {wr: writers 17286 rdwait 26.236 wrwait 6.441 filemark 
0.522}]"
driver: dumping server.db.xyz.at:/var directly to tape
driver: send-cmd time 34342.120 to taper: PORT-WRITE 00-00013 server.db.xyz.at 
ffffffff9ffeffffffff00 /var 2 20080528211606 0 NULL 65536
driver: result time 34342.123 from taper: PORT 50040
driver: send-cmd time 34342.123 to dumper0: PORT-DUMP 00-00013 50040 
server.db.xyz.at ffffffff9ffeffffffff00 /var NODEVICE 2 2008:5:24:9:18:9 GNUTAR 
X X X 
|;auth=BSD;compress-fast;index;exclude-list=/usr/local/etc/amanda/daily/excludes/var_excludes;
driver: state time 34342.123 free kps: 0 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 2 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 34342.123 if default: free 0 if local: free 1000 
if le0: free 400
driver: hdisk-state time 34342.123 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR /var  2 2008:5:24:9:18:9 OPTIONS 
|;auth=BSD;compress-fast;index;exclude-list=/usr/local/etc/amanda/daily/excludes/var_excludes;

----

got response:
----
CONNECT DATA 50006 MESG 50007 INDEX 50008
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 34353.153 from dumper0: DONE 00-00013 138650 30489 11 "[sec 
10.996 kb 30489 kps 2772.5 orig-kb 138650]"
taper: reader-side: got label wpa-06 filenum 7
driver: result time 34353.155 from taper: DONE 00-00013 wpa-06 7 "[sec 11.029 
kb 30496 kps 2765.1 {wr: writers 954 rdwait 9.031 wrwait 0.322 filemark 1.672}]"
driver: dumping server.db.xyz.at:/usr directly to tape
driver: send-cmd time 34353.178 to taper: PORT-WRITE 00-00014 server.db.xyz.at 
ffffffff9ffeffffffff00 /usr 1 20080528211606 0 NULL 65536
driver: result time 34353.183 from taper: PORT 50010
driver: send-cmd time 34353.183 to dumper0: PORT-DUMP 00-00014 50010 
server.db.xyz.at ffffffff9ffeffffffff00 /usr NODEVICE 1 2008:5:28:5:48:7 GNUTAR 
X X X 
|;auth=BSD;compress-fast;index;exclude-file=./adm/amanda;exclude-file=./local/var/amanda;
driver: state time 34353.183 free kps: 1521 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 34353.183 if default: free 121 if local: free 1000 
if le0: free 400
driver: hdisk-state time 34353.183 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR /usr  1 2008:5:28:5:48:7 OPTIONS 
|;auth=BSD;compress-fast;index;exclude-file=./adm/amanda;exclude-file=./local/var/amanda;

----

got response:
----
CONNECT DATA 50017 MESG 50018 INDEX 50019
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 34387.175 from dumper0: DONE 00-00014 18110 3714 34 "[sec 
33.942 kb 3714 kps 109.4 orig-kb 18110]"
taper: reader-side: got label wpa-06 filenum 8
driver: result time 34387.180 from taper: DONE 00-00014 wpa-06 8 "[sec 33.992 
kb 3744 kps 110.1 {wr: writers 118 rdwait 30.953 wrwait 0.044 filemark 2.993}]"
driver: dumping server.db.xyz.at:/ directly to tape
driver: send-cmd time 34387.181 to taper: PORT-WRITE 00-00015 server.db.xyz.at 
ffffffff9ffeffffffff00 / 1 20080528211606 0 NULL 65536
driver: result time 34387.186 from taper: PORT 50003
driver: send-cmd time 34387.186 to dumper0: PORT-DUMP 00-00015 50003 
server.db.xyz.at ffffffff9ffeffffffff00 / NODEVICE 1 2008:5:23:0:46:47 GNUTAR X 
X X 
|;auth=BSD;compress-fast;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;
driver: state time 34387.186 free kps: 1963 space: 2344462316 taper: writing 
idle-dumpers: 15 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 34387.186 if default: free 563 if local: free 1000 
if le0: free 400
driver: hdisk-state time 34387.186 hdisk 0: free 2344462316 dumpers 0
send request:
----
SERVICE sendbackup
OPTIONS features=ffffffff9ffeffffffff00;hostname=server.db.xyz.at;config=daily;
GNUTAR /  1 2008:5:23:0:46:47 OPTIONS 
|;auth=BSD;compress-fast;index;exclude-list=/usr/local/etc/amanda/daily/excludes/root_excludes;

----

got response:
----
CONNECT DATA 50010 MESG 50011 INDEX 50013
OPTIONS features=ffffffff9ffeffffffff00;

----

driver: result time 34394.953 from dumper0: DONE 00-00015 2290 287 8 "[sec 
7.711 kb 287 kps 37.2 orig-kb 2290]"
taper: reader-side: got label wpa-06 filenum 9
driver: result time 34394.958 from taper: DONE 00-00015 wpa-06 9 "[sec 7.767 kb 
288 kps 37.1 {wr: writers 10 rdwait 6.143 wrwait 0.004 filemark 1.619}]"
driver: state time 34394.959 free kps: 2000 space: 2344462316 taper: idle 
idle-dumpers: 16 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 34394.959 if default: free 600 if local: free 1000 
if le0: free 400
driver: hdisk-state time 34394.959 hdisk 0: free 2344462316 dumpers 0
driver: QUITTING time 34394.959 telling children to quit
driver: send-cmd time 34394.959 to dumper0: QUIT
driver: send-cmd time 34394.959 to dumper1: QUIT
driver: send-cmd time 34394.959 to dumper2: QUIT
driver: send-cmd time 34394.959 to dumper3: QUIT
driver: send-cmd time 34394.959 to dumper4: QUIT
driver: send-cmd time 34394.959 to dumper5: QUIT
driver: send-cmd time 34394.959 to dumper6: QUIT
driver: send-cmd time 34394.959 to dumper7: QUIT
driver: send-cmd time 34394.959 to dumper8: QUIT
driver: send-cmd time 34394.959 to dumper9: QUIT
driver: send-cmd time 34394.959 to dumper10: QUIT
driver: send-cmd time 34394.959 to dumper11: QUIT
driver: send-cmd time 34394.959 to dumper12: QUIT
driver: send-cmd time 34394.959 to dumper13: QUIT
driver: send-cmd time 34394.959 to dumper14: QUIT
driver: send-cmd time 34394.959 to dumper15: QUIT
driver: send-cmd time 34394.959 to taper: QUIT
taper: DONE [idle wait: 299.930 secs]
taper: writing end marker. [wpa-06 OK kb 44557216 fm 9]
driver: FINISHED time 34397.140
amdump: end at Thu May 29 06:49:24 CEST 2008
0
0
0
0
0
0
0
0
0
<Prev in Thread] Current Thread [Next in Thread>