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]