Subject: dump is slow over network and/or raidframe
To: NetBSD Users <netbsd-users@NetBSD.org>
From: Louis Guillaume <lguillaume@berklee.edu>
List: netbsd-users
Date: 11/26/2006 23:11:50
Hi!

I recently put in a DLT tape drive, expecting to see some decent backup
speed, and was surprised to see this...

  [11:00:04 EST] DUMP: 3220943 tape blocks on 1 volume
  [11:00:04 EST] DUMP: Volume 1 completed at: Sun Nov 26 11:00:04 2006
  [11:00:04 EST] DUMP: Volume 1 took 8:34:15
  [11:00:04 EST] DUMP: Volume 1 transfer rate: 104 KB/s
  [11:00:04 EST] DUMP: Date of this level 0 dump: Sun Nov 26 02:25:42 2006
  [11:00:04 EST] DUMP: Date this dump completed:  Sun Nov 26 11:00:04 2006
  [11:00:04 EST] DUMP: Average transfer rate: 104 KB/s
  [11:00:04 EST] DUMP: level 0 dump on Sun Nov 26 02:25:42 2006
  [11:00:04 EST] DUMP: Closing /dev/nrst0
  [11:00:07 EST] DUMP: DUMP IS DONE


... that was on a remote system on the same subnet; 100baseTX media for
each system. The filesystem is FFSv1 on raidframe, RAID-1.

So I did some smaller (/) filesystems. Host "maat" has the tape drive
and things work as expected. Host "thoth" is the remote one. The only
difference is that we're going over the network (RCMD_CMD=ssh) and
thoth's filesystems are on raidframe...



maat# dump -0eaut /
  [22:39:58 EST] DUMP: Found /dev/rwd0a on / in /etc/fstab
  [22:39:58 EST] DUMP: Date of this level 0 dump: Sun Nov 26 22:39:58 2006
  [22:39:58 EST] DUMP: Date of last level 0 dump: the epoch
  [22:39:58 EST] DUMP: Dumping /dev/rwd0a (/) to /dev/nrst0
  [22:39:58 EST] DUMP: Label: none
  [22:39:58 EST] DUMP: mapping (Pass I) [regular files]
  [22:39:59 EST] DUMP: mapping (Pass II) [directories]
  [22:39:59 EST] DUMP: estimated 59577 tape blocks.
  [22:39:59 EST] DUMP: Volume 1 started at: Sun Nov 26 22:39:59 2006
  [22:39:59 EST] DUMP: dumping (Pass III) [directories]
  [22:40:00 EST] DUMP: dumping (Pass IV) [regular files]
  [22:40:32 EST] DUMP: 59578 tape blocks on 1 volume
  [22:40:32 EST] DUMP: Volume 1 completed at: Sun Nov 26 22:40:32 2006
  [22:40:32 EST] DUMP: Volume 1 took 0:00:33
  [22:40:32 EST] DUMP: Volume 1 transfer rate: 1805 KB/s
  [22:40:32 EST] DUMP: Date of this level 0 dump: Sun Nov 26 22:39:58 2006
  [22:40:32 EST] DUMP: Date this dump completed:  Sun Nov 26 22:40:32 2006
  [22:40:32 EST] DUMP: Average transfer rate: 1805 KB/s
  [22:40:32 EST] DUMP: level 0 dump on Sun Nov 26 22:39:58 2006
  [22:40:32 EST] DUMP: Closing /dev/nrst0
  [22:40:34 EST] DUMP: DUMP IS DONE
maat#


... Maat looks normal. But...




thoth # dump -0eautf maat:/dev/nrst0 /
  [22:42:12 EST] DUMP: Found /dev/rraid1a on / in /etc/fstab
  [22:42:12 EST] DUMP: Date of this level 0 dump: Sun Nov 26 22:42:12 2006
  [22:42:12 EST] DUMP: Date of last level 0 dump: the epoch
  [22:42:12 EST] DUMP: Dumping /dev/rraid1a (/) to /dev/nrst0 on host maat
  [22:42:12 EST] DUMP: Label: none
  [22:42:12 EST] DUMP: mapping (Pass I) [regular files]
  [22:42:12 EST] DUMP: mapping (Pass II) [directories]
  [22:42:12 EST] DUMP: estimated 55957 tape blocks.
  [22:42:13 EST] DUMP: Volume 1 started at: Sun Nov 26 22:42:12 2006
  [22:42:13 EST] DUMP: dumping (Pass III) [directories]
  [22:42:20 EST] DUMP: dumping (Pass IV) [regular files]
  [22:47:13 EST] DUMP: 54.95% done, finished in 0:04 (at 22:51:18 EST)
  [22:51:40 EST] DUMP: 55975 tape blocks on 1 volume
  [22:51:40 EST] DUMP: Volume 1 completed at: Sun Nov 26 22:51:40 2006
  [22:51:40 EST] DUMP: Volume 1 took 0:09:28
  [22:51:40 EST] DUMP: Volume 1 transfer rate: 98 KB/s
  [22:51:40 EST] DUMP: Date of this level 0 dump: Sun Nov 26 22:42:12 2006
  [22:51:40 EST] DUMP: Date this dump completed:  Sun Nov 26 22:51:40 2006
  [22:51:40 EST] DUMP: Average transfer rate: 98 KB/s
  [22:51:40 EST] DUMP: level 0 dump on Sun Nov 26 22:42:12 2006
  [22:51:40 EST] DUMP: Closing /dev/nrst0
  [22:51:42 EST] DUMP: DUMP IS DONE
thoth #




... thoth seems to be having some trouble!!

Any idea what's going on here? Device and system info are below...

Louis



$ dmesg|egrep "(ahc|scsi|st0)"
ahc1 at pci0 dev 10 function 0: Adaptec 2940 Ultra SCSI adapter
ahc1: interrupting at irq 10
ahc1: aic7880: Ultra Wide Channel A, SCSI Id=7, 16/253 SCBs
scsibus0 at ahc1: 16 targets, 8 luns per target
scsibus0: waiting 2 seconds for devices to settle...
st0 at scsibus0 target 5 lun 0: <QUANTUM, DLT7000, 391B> tape removable
st0: drive empty
st0: sync (100.00ns offset 8), 16-bit (20.000MB/s) transfers
sd0 at scsibus0 target 6 lun 0: <IBM, DNES-309170, SAH0> disk fixed


$ uname -a
NetBSD maat.zabrico.com 3.1_STABLE NetBSD 3.1_STABLE (GENERIC) #1: Thu
Nov  9 12:50:46 EST 2006
louis@maat.zabrico.com:/usr/obj/sys/arch/i386/compile/GENERIC i386