Zhen Liu
Via Webmail
[EMAIL PROTECTED]
------ Forwarded message -------
From: Zhen Liu <[EMAIL PROTECTED]>
To: [EMAIL PROTECTED]
Cc: [EMAIL PROTECTED]
Date: Fri, 29 Mar 2002 09:09:14 -0500
Hi,
Thanks for your suggestions, I did change the etimeout from 300
(5minutes by default) to 3600 (1 hour). Meanwhile, I changed the
"localhost" to the FQDN...and my holding disk in amanda.conf to
20000Mb... But I still got some kind of timeout issue...I tried to
back up 5 file systems and got 3 of them failed...
Can you please help? below are my log files:
****This is my /var/spool/mail message:
>From amanda Fri Mar 29 03:47:29 2002
Return-Path: <[EMAIL PROTECTED]>
Received: (from amanda@localhost)
by apple.iusb.edu (8.11.2/8.11.2) id g2T8lSM10987
for zliu; Fri, 29 Mar 2002 03:47:28 -0500
Date: Fri, 29 Mar 2002 03:47:28 -0500
From: Amanda user <[EMAIL PROTECTED]>
Message-Id: <[EMAIL PROTECTED]>
To: [EMAIL PROTECTED]
Subject: DailyBackup AMANDA MAIL REPORT FOR March 29, 2002
These dumps were to tape DailySet11.
The next 2 tapes Amanda expects to used are: a new tape, a new tape.
FAILURE AND STRANGE DUMP SUMMARY:
apple.iusb /scsi4-data lev 0 FAILED [data timeout]
apple.iusb /scsi0-data lev 1 FAILED [data timeout]
apple.iusb /apps lev 0 FAILED [data timeout]
STATISTICS:
Total Full Daily
-------- -------- --------
Estimate Time (hrs:min) 0:13
Run Time (hrs:min) 2:42
Dump Time (hrs:min) 0:18 0:00 0:18
Output Size (meg) 1936.2 0.0 1936.2
Original Size (meg) 1936.2 0.0 1936.2
Avg Compressed Size (%) -- -- -- (level:#disks
.....)
Filesystems Dumped 2 0 2 (1:2)
Avg Dump Rate (k/s) 1840.6 -- 1840.6
Tape Time (hrs:min) 0:13 0:00 0:13
Tape Size (meg) 1936.3 0.0 1936.3
Tape Used (%) 4.8 0.0 4.8 (level:#disks
.....)
Filesystems Taped 2 0 2 (1:2)
Avg Tp Write Rate (k/s) 2524.5 -- 2524.5
^L
FAILED AND STRANGE DUMP DETAILS:
/-- apple.iusb /scsi4-data lev 0 FAILED [data timeout]
sendbackup: start [apple.iusb.edu:/scsi4-data level 0]
sendbackup: info BACKUP=/sbin/dump
sendbackup: info RECOVER_CMD=/sbin/restore -f... -
sendbackup: info end
| DUMP: Date of this level 0 dump: Fri Mar 29 01:17:51 2002
| DUMP: Date of last level 0 dump: the epoch
| DUMP: Dumping /dev/sda9 (/scsi4-data) to standard output
| DUMP: Label: none
| DUMP: mapping (Pass I) [regular files]
| DUMP: mapping (Pass II) [directories]
| DUMP: estimated 620986 tape blocks.
| DUMP: Volume 1 started at: Fri Mar 29 01:18:27 2002
| DUMP: dumping (Pass III) [directories]
| DUMP: dumping (Pass IV) [regular files]
\--------
/-- apple.iusb /scsi0-data lev 1 FAILED [data timeout]
sendbackup: start [apple.iusb.edu:/scsi0-data level 1]
sendbackup: info BACKUP=/sbin/dump
sendbackup: info RECOVER_CMD=/sbin/restore -f... -
sendbackup: info end
| DUMP: Date of this level 1 dump: Fri Mar 29 01:51:33 2002
| DUMP: Date of last level 0 dump: Thu Mar 28 13:26:41 2002
| DUMP: Dumping /dev/sda7 (/scsi0-data) to standard output
| DUMP: Label: none
| DUMP: mapping (Pass I) [regular files]
| DUMP: mapping (Pass II) [directories]
| DUMP: estimated 950437 tape blocks.
| DUMP: Volume 1 started at: Fri Mar 29 01:52:17 2002
| DUMP: dumping (Pass III) [directories]
| DUMP: dumping (Pass IV) [regular files]
| DUMP: 66.01% done at 2091 KB/s, finished in 0:02
\--------
/-- apple.iusb /apps lev 0 FAILED [data timeout]
sendbackup: start [apple.iusb.edu:/apps level 0]
sendbackup: info BACKUP=/sbin/dump
sendbackup: info RECOVER_CMD=/sbin/restore -f... -
sendbackup: info end
| DUMP: Date of this level 0 dump: Fri Mar 29 02:47:12 2002
| DUMP: Date of last level 0 dump: the epoch
| DUMP: Dumping /dev/sda6 (/apps) to standard output
| DUMP: Label: none
| DUMP: mapping (Pass I) [regular files]
| DUMP: mapping (Pass II) [directories]
| DUMP: estimated 13642189 tape blocks.
| DUMP: Volume 1 started at: Fri Mar 29 02:53:35 2002
| DUMP: dumping (Pass III) [directories]
| DUMP: dumping (Pass IV) [regular files]
| DUMP: 2.89% done at 1314 KB/s, finished in 2:47
| DUMP: 6.73% done at 1530 KB/s, finished in 2:18
| DUMP: 11.72% done at 1776 KB/s, finished in 1:52
| DUMP: 19.07% done at 2168 KB/s, finished in 1:24
\--------
^L
NOTES:
planner: Last full dump of apple.iusb.edu:/apps on tape overwritten
in 1 run.
planner: Adding new disk apple.iusb.edu:/scsi4-data.
taper: tape DailySet11 kb 1982752 fm 2 [OK]
^L
DUMP SUMMARY:
DUMPER STATS TAPER
STATS
HOSTNAME DISK L ORIG-KB OUT-KB COMP% MMM:SS KB/s MMM:SS
KB/s
-------------------------- ---------------------------------
------------
apple.iusb.e /apps 0 FAILED
---------------------------------------
apple.iusb.e /scsi0-data 1 FAILED
---------------------------------------
apple.iusb.e /scsi2-data 1 992576 992576 -- 9:291745.1
5:492843.4
apple.iusb.e /scsi4-data 0 FAILED
---------------------------------------
apple.iusb.e /scsi6-data 1 990112 990112 -- 8:281947.4
7:162269.4
(brought to you by Amanda version 2.4.2p2)
****This is my amdump log file:
[root@apple log]# more amdump.1
amdump: start at Fri Mar 29 01:05:01 EST 2002
planner: pid 20092 executable /usr/lib/amanda/planner version 2.4.2p2
planner: build: VERSION="Amanda-2.4.2p2"
planner: BUILT_DATE="Thu Apr 5 09:18:00 EDT 2001"
planner: BUILT_MACH="Linux porky.devel.redhat.com 2.2.17-8smp
#1 SMP Fri
Nov 17 16:12:17 EST 2000 i686 unknown"
planner: CC="gcc"
planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
planner: libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
planner: AMANDA_TMPDIR="/tmp/amanda"
AMANDA_DBGDIR="/tmp/amanda"
planner: CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
planner: RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
planner: RESTORE="/sbin/restore"
SAMBA_CLIENT="/usr/bin/smbclient"
planner: GNUTAR="/bin/tar" COMPRESS_PATH="/usr/bin/gzip"
planner: UNCOMPRESS_PATH="/usr/bin/gzip" 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: dgram_bind: socket bound to 0.0.0.0.649
READING CONF FILES...
driver: pid 20093 executable /usr/lib/amanda/driver version 2.4.2p2
driver: send-cmd time 0.003 to taper: START-TAPER 20020329
taper: pid 20094 executable taper version 2.4.2p2
startup took 0.021 secs
SETTING UP FOR ESTIMATES...
setting up estimates for apple.iusb.edu:/apps
changer: opening pipe to: /usr/lib/amanda/chg-zd-mtx -info
apple.iusb.edu:/apps overdue 11768 days for level 0
setup_estimate: apple.iusb.edu:/apps: command 0, options:
last_level 0 next_level0 -11768 level_days 0
getting estimates 0 (-1) 1 (0) -1 (-1)
setting up estimates for apple.iusb.edu:/scsi0-data
driver: started dumper0 pid 20104
driver: started dumper1 pid 20105
driver: started dumper2 pid 20106
driver: started dumper3 pid 20107
setup_estimate: apple.iusb.edu:/scsi0-data: command 0, options:
last_level 0 next_level0 6 level_days 0
getting estimates 0 (11858845) 1 (0) -1 (-1)
setting up estimates for apple.iusb.edu:/scsi2-data
setup_estimate: apple.iusb.edu:/scsi2-data: command 0, options:
last_level 0 next_level0 6 level_days 0
getting estimates 0 (10796050) 1 (0) -1 (-1)
setting up estimates for apple.iusb.edu:/scsi4-data
dumper: dgram_bind: socket bound to 0.0.0.0.661
dumper: pid 20104 executable dumper version 2.4.2p2, using port 661
apple.iusb.edu:/scsi4-data overdue 11775 days for level 0
setup_estimate: apple.iusb.edu:/scsi4-data: command 0, options:
last_level -1 next_level0 -11775 level_days 0
getting estimates 0 (0) -1 (-1) -1 (-1)
setting up estimates for apple.iusb.edu:/scsi6-data
dumper: dgram_bind: socket bound to 0.0.0.0.663
dumper: pid 20106 executable dumper version 2.4.2p2, using port 663
setup_estimate: apple.iusb.edu:/scsi6-data: command 0, options:
last_level 0 next_level0 6 level_days 0
getting estimates 0 (9082887) 1 (0) -1 (-1)
setting up estimates took 0.092 secs
GETTING ESTIMATES...
dumper: dgram_bind: socket bound to 0.0.0.0.664
dumper: pid 20107 executable dumper version 2.4.2p2, using port 664
dumper: dgram_bind: socket bound to 0.0.0.0.662
dumper: pid 20105 executable dumper version 2.4.2p2, using port 662
changer: got exit: 0 str: 1 6 1 1
changer: opening pipe to: /usr/lib/amanda/chg-zd-mtx -slot current
changer: got exit: 0 str: 1 /dev/nst0
taper: slot 1: rewinding tape: Input/output error
changer: opening pipe to: /usr/lib/amanda/chg-zd-mtx -slot next
changer: got exit: 0 str: /dev/nst0: Input/output error
taper: slot /dev/nst0:: no tape online
changer: opening pipe to: /usr/lib/amanda/chg-zd-mtx -slot next
changer: got exit: 0 str: 3 /dev/nst0
taper: slot 3: date X label DailySet11 (first labelstr match)
taper: read label `DailySet11' date `X'
taper: wrote label `DailySet11' date `20020329'
got result for host apple.iusb.edu disk /scsi6-data: 0 -> 979701K, 1
-> 979701K,
-1 -> -1K
got result for host apple.iusb.edu disk /scsi4-data: 0 -> 620986K, -1
-> -1K, -1
-> -1K
got result for host apple.iusb.edu disk /scsi2-data: 0 -> 980712K, 1
-> 980712K,
-1 -> -1K
got result for host apple.iusb.edu disk /scsi0-data: 0 -> 950437K, 1
-> 950437K,
-1 -> -1K
got result for host apple.iusb.edu disk /apps: 0 -> 12648577K, 1 ->
12648577K, -
1 -> -1K
getting estimates took 769.530 secs
FAILED QUEUE: empty
DONE QUEUE:
0: apple.iusb.edu /scsi6-data
1: apple.iusb.edu /scsi4-data
2: apple.iusb.edu /scsi2-data
3: apple.iusb.edu /scsi0-data
4: apple.iusb.edu /apps
ANALYZING ESTIMATES...
pondering apple.iusb.edu:/scsi6-data... next_level0 6 last_level 0
(not due for
a full dump, picking an incr level)
picklev: last night 0, so tonight level 1
curr level 1 size 979701 total size 979821 total_lev0 0
balanced-lev0size 2799
1
pondering apple.iusb.edu:/scsi4-data... next_level0 -11775 last_level
-1 (due fo
r level 0) (new disk, can't switch to degraded mode)
curr level 0 size 620986 total size 1600847 total_lev0 620986
balanced-lev0siz
e 45733
pondering apple.iusb.edu:/scsi2-data... next_level0 6 last_level 0
(not due for
a full dump, picking an incr level)
picklev: last night 0, so tonight level 1
curr level 1 size 980712 total size 2581599 total_lev0 620986
balanced-lev0siz
e 73753
pondering apple.iusb.edu:/scsi0-data... next_level0 6 last_level 0
(not due for
a full dump, picking an incr level)
picklev: last night 0, so tonight level 1
curr level 1 size 950437 total size 3532076 total_lev0 620986
balanced-lev0siz
e 100908
pondering apple.iusb.edu:/apps... next_level0 -11768 last_level 0 (due
for level
0) (picking inclevel for degraded mode)
picklev: last night 0, so tonight level 1
curr level 0 size 12648577 total size 16180693 total_lev0 13269563
balanced-le
v0size 462295
INITIAL SCHEDULE (size 16180693):
apple.iusb.edu /scsi4-data pri 11777 lev 0 size 620986
apple.iusb.edu /apps pri 11770 lev 0 size 12648577
apple.iusb.edu /scsi2-data pri 2 lev 1 size 980712
apple.iusb.edu /scsi6-data pri 2 lev 1 size 979701
apple.iusb.edu /scsi0-data pri 2 lev 1 size 950437
DELAYING DUMPS IF NEEDED, total_size 16180693, tape length 81920000
mark 8
delay: Total size now 16180693.
PROMOTING DUMPS IF NEEDED, total_lev0 13269563, balanced_size
462295...
analysis took 0.098 secs
GENERATING SCHEDULE:
--------
apple.iusb.edu /scsi4-data 11777 0 1970:1:1:0:0:0 620986 20699
apple.iusb.edu /apps 11770 0 1970:1:1:0:0:0 12648577 421619 1
1970:1:1:0:0:0 126
48577 421619
apple.iusb.edu /scsi2-data 2 1 2002:3:28:16:55:58 980712 32690
apple.iusb.edu /scsi6-data 2 1 2002:3:28:15:30:2 979701 32656
apple.iusb.edu /scsi0-data 2 1 2002:3:28:17:26:12 950437 31681
--------
driver: adding holding disk 0 dir /home/dumps size 20480000
reserving 20480000 out of 20480000 for degraded-mode dumps
driver: start time 769.785 inparallel 4 bandwidth 2000 diskspace
20480000 dir OB
SOLETE datestamp 20020329 driver: drain-ends tapeq LFFO big-dumpers 1
driver: result time 769.794 from taper: TAPER-OK
driver: send-cmd time 769.794 to dumper0: FILE-DUMP 00-00001
/home/dumps/2002032
9/apple.iusb.edu._scsi4-data.0 apple.iusb.edu /scsi4-data 0
1970:1:1:0:0:0 10485
76 DUMP 621056 |;bsd-auth;index;
driver: state time 769.794 free kps: 1970 space: 19858944 taper: idle
idle-dumpe
rs: 3 qlen tapeq: 0 runq: 4 roomq: 0 wakeup: 15 driver-idle:
start-wait
driver: interface-state time 769.794 if : free 570 if LE0: free 400 if
LOCAL: fr
ee 1000
driver: hdisk-state time 769.794 hdisk 0: free 19858944 dumpers 1
dumper: stream_client: connected to 149.161.80.120.49520
dumper: stream_client: our side is 0.0.0.0.49523
dumper: stream_client: connected to 149.161.80.120.49521
dumper: stream_client: our side is 0.0.0.0.49524
dumper: stream_client: connected to 149.161.80.120.49522
dumper: stream_client: our side is 0.0.0.0.49525
driver: state time 784.795 free kps: 1970 space: 19858944 taper: idle
idle-dumpe
rs: 3 qlen tapeq: 0 runq: 4 roomq: 0 wakeup: 86400 driver-idle:
client-constrain
ed
driver: interface-state time 784.795 if : free 570 if LE0: free 400 if
LOCAL: fr
ee 1000
driver: hdisk-state time 784.795 hdisk 0: free 19858944 dumpers 1
dumper: kill index command
driver: result time 2775.188 from dumper0: FAILED 00-00001 [data
timeout]
driver: send-cmd time 2790.355 to dumper0: FILE-DUMP 00-00002
/home/dumps/200203
29/apple.iusb.edu._scsi0-data.1 apple.iusb.edu /scsi0-data 1
2002:3:28:17:26:12
1048576 DUMP 950528 |;bsd-auth;index;
driver: state time 2790.355 free kps: 1970 space: 19529472 taper: idle
idle-dump
ers: 3 qlen tapeq: 0 runq: 3 roomq: 0 wakeup: 15 driver-idle:
start-wait
driver: interface-state time 2790.355 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 2790.355 hdisk 0: free 19529472 dumpers 1
dumper: stream_client: connected to 149.161.80.120.50117
dumper: stream_client: our side is 0.0.0.0.50120
dumper: stream_client: connected to 149.161.80.120.50118
dumper: stream_client: our side is 0.0.0.0.50121
dumper: stream_client: connected to 149.161.80.120.50119
dumper: stream_client: our side is 0.0.0.0.50122
driver: state time 2805.352 free kps: 1970 space: 19529472 taper: idle
idle-dump
ers: 3 qlen tapeq: 0 runq: 3 roomq: 0 wakeup: 86400 driver-idle:
client-constrai
ned
driver: interface-state time 2805.352 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 2805.352 hdisk 0: free 19529472 dumpers 1
dumper: kill index command
driver: result time 5035.651 from dumper0: FAILED 00-00002 [data
timeout]
driver: send-cmd time 5050.742 to dumper0: FILE-DUMP 00-00003
/home/dumps/200203
29/apple.iusb.edu._scsi6-data.1 apple.iusb.edu /scsi6-data 1
2002:3:28:15:30:2 1
048576 DUMP 979776 |;bsd-auth;index;
driver: state time 5050.742 free kps: 1970 space: 19500224 taper: idle
idle-dump
ers: 3 qlen tapeq: 0 runq: 2 roomq: 0 wakeup: 15 driver-idle:
start-wait
driver: interface-state time 5050.742 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 5050.742 hdisk 0: free 19500224 dumpers 1
dumper: stream_client: connected to 149.161.80.120.50722
dumper: stream_client: our side is 0.0.0.0.50725
dumper: stream_client: connected to 149.161.80.120.50723
dumper: stream_client: our side is 0.0.0.0.50726
dumper: stream_client: connected to 149.161.80.120.50724
dumper: stream_client: our side is 0.0.0.0.50727
driver: state time 5065.749 free kps: 1970 space: 19500224 taper: idle
idle-dump
ers: 3 qlen tapeq: 0 runq: 2 roomq: 0 wakeup: 86400 driver-idle:
client-constrai
ned
driver: interface-state time 5065.749 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 5065.749 hdisk 0: free 19500224 dumpers 1
driver: result time 5555.475 from dumper0: RQ-MORE-DISK 00-00003
driver: send-cmd time 5555.540 to dumper0: CONTINUE
/home/dumps/20020329/apple.i
usb.edu._scsi6-data.1 1048576 49024
driver: state time 5555.540 free kps: 1970 space: 19451200 taper: idle
idle-dump
ers: 3 qlen tapeq: 0 runq: 2 roomq: 0 wakeup: 86400 driver-idle:
client-constrai
ned
driver: interface-state time 5555.540 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 5555.540 hdisk 0: free 19451200 dumpers 1
driver: result time 5559.962 from dumper0: DONE 00-00003 990109 990112
508 [sec
508.431 kb 990112 kps 1947.4 orig-kb 990109]
driver: finished-cmd time 5560.153 dumper0 dumped
apple.iusb.edu:/scsi6-data
driver: send-cmd time 5560.153 to taper: FILE-WRITE 00-00004
/home/dumps/2002032
9/apple.iusb.edu._scsi6-data.1 apple.iusb.edu /scsi6-data 1 20020329
driver: send-cmd time 5560.153 to dumper0: FILE-DUMP 01-00005
/home/dumps/200203
29/apple.iusb.edu._scsi2-data.1 apple.iusb.edu /scsi2-data 1
2002:3:28:16:55:58
1048576 DUMP 980800 |;bsd-auth;index;
driver: state time 5560.153 free kps: 1970 space: 18509056 taper:
writing idle-d
umpers: 3 qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 15 driver-idle:
start-wait
driver: interface-state time 5560.153 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 5560.153 hdisk 0: free 18509056 dumpers 1
dumper: stream_client: connected to 149.161.80.120.50846
dumper: stream_client: our side is 0.0.0.0.50849
dumper: stream_client: connected to 149.161.80.120.50847
dumper: stream_client: our side is 0.0.0.0.50850
dumper: stream_client: connected to 149.161.80.120.50848
dumper: stream_client: our side is 0.0.0.0.50851
driver: state time 5575.152 free kps: 1970 space: 18509056 taper:
writing idle-d
umpers: 3 qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 86400 driver-idle:
client-const
rained
driver: interface-state time 5575.152 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 5575.152 hdisk 0: free 18509056 dumpers 1
taper: reader-side: got label DailySet11 filenum 1
driver: result time 5996.486 from taper: DONE 00-00004 DailySet11 1
[sec 436.304
kb 990144 kps 2269.4 {wr: writers 30942 rdwait 112.520 wrwait 316.178
filemark
7.115}]
driver: finished-cmd time 6001.721 taper wrote
apple.iusb.edu:/scsi6-data
driver: state time 6001.721 free kps: 1970 space: 19499200 taper: idle
idle-dump
ers: 3 qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 86400 driver-idle:
client-constrai
ned
driver: interface-state time 6001.721 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 6001.721 hdisk 0: free 19499200 dumpers 1
driver: result time 6125.667 from dumper0: RQ-MORE-DISK 01-00005
driver: send-cmd time 6125.667 to dumper0: CONTINUE
/home/dumps/20020329/apple.i
usb.edu._scsi2-data.1 1048576 49088
driver: state time 6125.667 free kps: 1970 space: 19450112 taper: idle
idle-dump
ers: 3 qlen tapeq: 0 runq: 1 roomq: 0 wakeup: 86400 driver-idle:
client-constrai
ned
driver: interface-state time 6125.667 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 6125.667 hdisk 0: free 19450112 dumpers 1
driver: result time 6129.640 from dumper0: DONE 01-00005 992557 992576
569 [sec
568.794 kb 992576 kps 1745.1 orig-kb 992557]
driver: finished-cmd time 6129.713 dumper0 dumped
apple.iusb.edu:/scsi2-data
driver: send-cmd time 6129.713 to taper: FILE-WRITE 00-00006
/home/dumps/2002032
9/apple.iusb.edu._scsi2-data.1 apple.iusb.edu /scsi2-data 1 20020329
driver: send-cmd time 6129.713 to dumper0: FILE-DUMP 01-00007
/home/dumps/200203
29/apple.iusb.edu._apps.0 apple.iusb.edu /apps 0 1970:1:1:0:0:0
1048576 DUMP 126
49056 |;bsd-auth;index;
driver: state time 6129.713 free kps: 1970 space: 6838336 taper:
writing idle-du
mpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle:
not-idle
driver: interface-state time 6129.713 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 6129.713 hdisk 0: free 6838336 dumpers 1
dumper: stream_client: connected to 149.161.80.120.50968
dumper: stream_client: our side is 0.0.0.0.50971
dumper: stream_client: connected to 149.161.80.120.50969
dumper: stream_client: our side is 0.0.0.0.50972
dumper: stream_client: connected to 149.161.80.120.50970
dumper: stream_client: our side is 0.0.0.0.50973
taper: reader-side: got label DailySet11 filenum 2
driver: result time 6478.821 from taper: DONE 00-00006 DailySet11 2
[sec 349.096
kb 992608 kps 2843.4 {wr: writers 31019 rdwait 63.746 wrwait 282.588
filemark 2
...215}]
driver: finished-cmd time 6482.900 taper wrote
apple.iusb.edu:/scsi2-data
driver: state time 6482.900 free kps: 1970 space: 7830944 taper: idle
idle-dumpe
rs: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle:
not-idle
driver: interface-state time 6482.900 if : free 570 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 6482.900 hdisk 0: free 7830944 dumpers 1
dumper: kill index command
driver: result time 9715.429 from dumper0: FAILED 01-00007 [data
timeout]
driver: state time 9737.789 free kps: 2000 space: 20480000 taper: idle
idle-dump
ers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 86400 driver-idle:
not-idle
driver: interface-state time 9737.789 if : free 600 if LE0: free 400
if LOCAL: f
ree 1000
driver: hdisk-state time 9737.789 hdisk 0: free 20480000 dumpers 0
driver: QUITTING time 9737.789 telling children to quit
driver: send-cmd time 9737.789 to dumper0: QUIT
driver: send-cmd time 9737.789 to dumper1: QUIT
driver: send-cmd time 9737.789 to dumper2: QUIT
driver: send-cmd time 9737.789 to dumper3: QUIT
driver: send-cmd time 9737.789 to taper: QUIT
taper: DONE [idle wait: 8682.297 secs]
taper: writing end marker. [DailySet11 OK kb 1982752 fm 2]
driver: FINISHED time 9745.272
amdump: end at Fri Mar 29 03:47:26 EST 2002
****This is my sendsize log file:
[root@apple amanda]# more sendsize.20020329010501.debug
sendsize: debug 1 pid 20139 ruid 33 euid 33 start time Fri Mar 29
01:05:01 2002
/usr/lib/amanda/sendsize: version 2.4.2p2
calculating for amname '/apps', dirname '/apps'
sendsize: getting size via dump for /apps level 0
sendsize: running "/sbin/dump 0Ssf 1048576 - /dev/sda6"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
12952142848
.......
asking killpgrp to terminate
sendsize: getting size via dump for /apps level 1
sendsize: running "/sbin/dump 1Ssf 1048576 - /dev/sda6"
running /usr/lib/amanda/killpgrp
DUMP: WARNING: There is no inferior level dump on this filesystem
DUMP: WARNING: Assuming a level 0 dump by default
DUMP: Label: none
12952142848
.......
asking killpgrp to terminate
calculating for amname '/scsi0-data', dirname '/scsi0-data'
sendsize: getting size via dump for /scsi0-data level 0
sendsize: running "/sbin/dump 0Ssf 1048576 - /dev/sda7"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
973247488
.......
asking killpgrp to terminate
sendsize: getting size via dump for /scsi0-data level 1
sendsize: running "/sbin/dump 1Ssf 1048576 - /dev/sda7"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
973247488
.......
asking killpgrp to terminate
calculating for amname '/scsi2-data', dirname '/scsi2-data'
sendsize: getting size via dump for /scsi2-data level 0
sendsize: running "/sbin/dump 0Ssf 1048576 - /dev/sda8"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
1004249088
.......
asking killpgrp to terminate
sendsize: getting size via dump for /scsi2-data level 1
sendsize: running "/sbin/dump 1Ssf 1048576 - /dev/sda8"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
1004249088
.......
asking killpgrp to terminate
calculating for amname '/scsi4-data', dirname '/scsi4-data'
sendsize: getting size via dump for /scsi4-data level 0
sendsize: running "/sbin/dump 0Ssf 1048576 - /dev/sda9"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
635889664
.......
asking killpgrp to terminate
calculating for amname '/scsi6-data', dirname '/scsi6-data'
sendsize: getting size via dump for /scsi6-data level 0
sendsize: running "/sbin/dump 0Ssf 1048576 - /dev/sda10"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
1003213824
.......
asking killpgrp to terminate
sendsize: getting size via dump for /scsi6-data level 1
sendsize: running "/sbin/dump 1Ssf 1048576 - /dev/sda10"
running /usr/lib/amanda/killpgrp
DUMP: Label: none
1003213824
.......
asking killpgrp to terminate
sendsize: pid 20139 finish time Fri Mar 29 01:17:50 2002
-------------------
> >I am working on the amanda backup project now. ...
>
> Welcome!
>
> >When enter "su amanda -c amdump daily", my dump for the localhost
> >/scsi9-data failed... I check the logs and debugs, it said "FAIL
> >planner localhost /scsi9-data 0 [Request to localhost timed out.]
>
> As Mark Schoonover said, please don't use "localhost" for any of
your
> client names.
>
> Now on to the real problem. This message means it took too long for
> your client to respond to planner with the estimate. You didn't
post
> your sendsize*debug file, but we can see part of what happened from
> the corresponding amandad*debug:
>
> >amandad: debug 1 pid 11580 ruid 33 euid 33 start time Fri Mar 22
09:07:01 2002
> >...
> >got packet:
> >--------
> >Amanda 2.4 REQ HANDLE 000-C03A0608 SEQ 1016806021
> >SECURITY USER amanda
> >SERVICE sendsize
> >OPTIONS maxdumps=1;hostname=localhost;
> >GNUTAR /scsi9-data 0 1970:1:1:0:0:0 -1
exclude-list=/usr/lib/amanda/exclude.gtar
> >...
> >amandad: pid 11580 finish time Fri Mar 22 10:29:09 2002
>
> Note the start and end time. It took about one and a half hours to
do
> this estimate. The default value for etimeout in amanda.conf allows
> five minutes per disk per client. You missed that by a lot :-).
>
> I don't know why GNU tar took so long to do the estimate. That's
not
> an Amanda problem. However, you might check what version of GNU tar
> you're using. It needs to be either 1.12 plus the patches on the
> www.amanda.org web page, or else 1.13.19 or later (from
alpha.gnu.org).
> If --version reports just "1.13", that's a *very*, *very* bad
version.
>
> Or it could be that the particular data on the file system is just
really
> bad for tar to deal with. For instance, if there are a bajillion
files
> it can take tar a long time to process each one. If that's the case
you
> may just have to increase etimeout a lot in amanda.conf.
>
> >Zhen Liu
>
> John R. Jackson, Technical Software Specialist, [EMAIL PROTECTED]
>
Zhen Liu
Via Webmail
[EMAIL PROTECTED]
