Skip site navigation (1)Skip section navigation (2)
Date:      Tue, 27 Apr 1999 21:28:15 +0200
From:      Juergen Lock <nox@jelal.kn-bremen.de>
To:        freebsd-stable@FreeBSD.ORG
Cc:        freebsd-scsi@FreeBSD.ORG
Subject:   QIC tape problems on -stable (was: hanging `tar xfvR /dev/nrst0' process, can i debug it?)
Message-ID:  <19990427212815.A674@saturn.kn-bremen.de>
In-Reply-To: <19990420023954.A20589@saturn.kn-bremen.de>; from nox on Tue, Apr 20, 1999 at 02:39:54AM %2B0200
References:  <19990420023954.A20589@saturn.kn-bremen.de>

next in thread | previous in thread | raw e-mail | index | archive | help
On Tue, Apr 20, 1999 at 02:39:54AM +0200, nox wrote:
> [Cc'd to -scsi, subscription is on the way...]
> 
> bash# tar xfvR /dev/nrst0
> tar: rec 0: read error on /dev/nrst0 : Input/output error
> 
> ^C
> 
> [other xterm:]
> bash# ps -xawl|grep tar
>     0 20103 19849   4  -6  0   940  516 piperd S+    p1    0:00.03 grep tar
>     0 19838 18532   0  -6  0   436  192 cgticb DE+   p8    0:00.00  (tar)
> 
>  The tape drive is silent, all other targets on the bus can be
> accessed without problems.  I have positioned the tape with a script
> thats doing
> 
> 	camcontrol cmd -u 0 -n sa -t 3000 -c "2b 4 0 v v v v 0 0 0" 0x$c3 0x$c2 0x$c1 0x$c0

Well...  after having discovered mt rdhpos/sethpos :), and mounted
the drive into a new external case (the old ones PSU died), i now have
some more data:

1.  exactly the same happens when i use mt sethpos instead of the
camcontrol script: the 1st tar returns `tar: rec 0: Blocksize = 12 records',
(ok so maybe the position i gave is a few blocks off), the next goes
hanging like mentioned above.  And if i then look at the console there
is this:

Apr 27 19:00:22 saturn /kernel: (sa0:ncr0:0:5:0): WRITE FILEMARKS. CDB: 10 0 0 0 0 0 
Apr 27 19:00:22 saturn /kernel: (sa0:ncr0:0:5:0): DATA PROTECT asc:27,0
Apr 27 19:00:22 saturn /kernel: (sa0:ncr0:0:5:0): Write protected
Apr 27 19:00:45 saturn /kernel: (sa0:ncr0:0:5:0): WRITE FILEMARKS. CDB: 10 0 0 0 0 0 
Apr 27 19:00:45 saturn /kernel: (sa0:ncr0:0:5:0): DATA PROTECT asc:27,0
Apr 27 19:00:45 saturn /kernel: (sa0:ncr0:0:5:0): Write protected
Apr 27 19:02:49 saturn /kernel: (sa0:ncr0:0:5:0): WRITE FILEMARKS. CDB: 10 0 0 0 0 0 
Apr 27 19:02:49 saturn /kernel: (sa0:ncr0:0:5:0): ILLEGAL REQUEST asc:2c,0
Apr 27 19:02:49 saturn /kernel: (sa0:ncr0:0:5:0): Command sequence error
Apr 27 19:02:51 saturn /kernel: (sa0:ncr0:0:5:0): WRITE FILEMARKS. CDB: 10 0 0 0 0 0 
Apr 27 19:02:51 saturn /kernel: (sa0:ncr0:0:5:0): ILLEGAL REQUEST asc:2c,0
Apr 27 19:02:51 saturn /kernel: (sa0:ncr0:0:5:0): Command sequence error

 Huh!??  Why on earth is it trying to write filemarks??  Good that the
tape was write protected...

2. reading the tape from beginning (repeated tar tfvR /dev/nrst0 until
the wanted position is reached) works ok i.e. i then can read past the
failing spot without problems.  (except that doing it this way takes
looong...)  so i presume something in the internal state of the nrst (sa)
driver is causing the problem...  (the tape drive is most likely
innocent, see below.)

3. mt bl(ocksize) doesn't work.  regardless what blocksize i give i get:

Apr 27 16:00:47 saturn /kernel: (sa0:ncr0:0:5:0): MODE SELECT(06). CDB: 15 0 0 0 c 0 
Apr 27 16:00:47 saturn /kernel: (sa0:ncr0:0:5:0): ILLEGAL REQUEST asc:26,0
Apr 27 16:00:47 saturn /kernel: (sa0:ncr0:0:5:0): Invalid field in parameter list sks:8f,4

(should the requested blocksize appear in the CDB?  it is always
15 0 0 0 c 0...)  the default blocksize is OK (tape is streaming)
so this doesn't worry me too much, but i thought i'll mention it anyway.
(on a related note, i cant get camcontrol defects to work on any of my
4 disk drives, i only get this:

Apr 27 15:55:48 saturn /kernel: (probe0:ncr0:0:0:0): COMMAND FAILED (6 ff) @0xf07a1400.

dmesg below.)

4. on one reboot i got this:

Apr 27 15:55:48 saturn /kernel: Waiting 5 seconds for SCSI devices to settle
Apr 27 15:55:48 saturn /kernel: ncr0: restart (scsi reset).
Apr 27 15:55:48 saturn /kernel: (probe6:ncr0:0:6:0): COMMAND FAILED (6 ff) @0xf07bc000.
Apr 27 15:55:48 saturn /kernel: (probe5:ncr0:0:5:0): COMMAND FA
Apr 27 15:55:48 saturn /kernel: ILED (6 ff) @0xf07bc600.
Apr 27 15:55:48 saturn /kernel: (probe4:ncr0:0:4:0): COMMAND FAILED (6 ff) @0xf07bcc00.
Apr 27 15:55:48 saturn /kernel: (probe3:ncr0:0:3:0): COMMAND FAILED (6 ff) @0xf07bb200.
Apr 27 15:55:48 saturn /kernel: (probe2:ncr0:0:2:0): COMMAND FAILED (6 ff) @0xf07bb800.
Apr 27 15:55:48 saturn /kernel: (probe1:ncr0:0:1:0): COMMAND FAILED (6 ff) @0xf07bbe00.
Apr 27 15:55:48 saturn /kernel: (probe0:ncr0:0:0:0): COMMAND FAILED (6 ff) @0xf07a1400.
Apr 27 15:55:48 saturn /kernel: (probe4:ncr0:0:4:0): INQUIRY. CDB: 12 1 80 0 ff 0 
Apr 27 15:55:48 saturn /kernel: (probe4:ncr0:0:4:0): ILLEGAL REQUEST asc:24,0
Apr 27 15:55:48 saturn /kernel: (probe4:ncr0:0:4:0): Invalid field in CDB
Apr 27 15:55:48 saturn /kernel: (probe5:ncr0:0:5:0): INQUIRY. CDB: 12 1 80 0 ff 0 
Apr 27 15:55:48 saturn /kernel: (probe5:ncr0:0:5:0): ILLEGAL REQUEST asc:24,0
Apr 27 15:55:48 saturn /kernel: (probe5:ncr0:0:5:0): Invalid field in CDB sks:c8,1

and then it goes on listing the SCSI devices as usual.  should i worry?

5. back to the tape, booting a 2.2.8-stable kernel:  the tape is not
streaming and regardless what blocksize i set it to it stays that way
(always start/stop behavior).  i did not try my positioning script.

6. booting a 2.1-stable kernel:  everything works!  the tape is
streaming, i can position it (using my old version of the script thats
doing scsi -f), and then i can read from there on without problems.
well, there is one minor nit:  i first have to read a few blocks off
/dev/nrst0 or else it will rewind before starting to read after the
positioning command...

 Here is the latest dmesg (boot -v, 3.1-stable):

Copyright (c) 1992-1999 FreeBSD Inc.
Copyright (c) 1982, 1986, 1989, 1991, 1993
	The Regents of the University of California. All rights reserved.
FreeBSD 3.1-STABLE #29: Tue Apr 27 00:18:14 MET DST 1999
    nox@saturn:/www/w/usr/home5/cvs/src31/src/sys/compile/SP3G.3I
Calibrating clock(s) ... i8254 clock: 1228973 Hz
1228973 Hz differs from default of 1193182 Hz by more than 1%
Timecounter "i8254"  frequency 1193182 Hz
CPU: i486 DX4 (486-class CPU)
  Origin = "GenuineIntel"  Id = 0x480  Stepping=0
  Features=0x3<FPU,VME>
real memory  = 33554432 (32768K bytes)
Physical memory chunk(s):
0x00001000 - 0x0009efff, 647168 bytes (158 pages)
0x002d6000 - 0x01febfff, 30498816 bytes (7446 pages)
avail memory = 29859840 (29160K bytes)
Found BIOS32 Service Directory header at 0xf00fc310
Entry = 0xfc740 (0xf00fc740)  Rev = 0  Len = 1
PCI BIOS entry at 0xc770
Other BIOS signatures found:
ACPI: 00000000
$PnP: 00000000
Preloaded elf kernel "kernel" at 0xf02c5000.
VESA: information block
56 45 53 41 02 01 3a 4f 00 c0 00 00 00 00 57 4f 
00 c0 20 00 00 00 00 00 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
VESA: 20 mode(s) found
Math emulator present
pci_open(1):	mode 1 addr port (0x0cf8) is 0x00000000
pci_open(1a):	mode1res=0x00000000 (0x80000000)
pci_open(1b):	mode1res=0x00000000 (0xff000001)
pci_open(2):	mode 2 enable port (0x0cf8) is 0x00
pci_open(2a):	mode2res=0x0e (0x0e)
pci_open(2a):	now trying mechanism 2
pci_cfgcheck:	device 0 [class=000000] 1 [class=000000] 2 [class=000000] 3 4 5 6 [class=030000] [hdr=00] is there (id=88115333)
Probing for devices on PCI bus 0:
found->	vendor=0x8086, dev=0x0483, revid=0x04
	class=00-00-00, hdrtype=0x00, mfdev=0
	subordinatebus=0 	secondarybus=0
chip0: <Intel 82424ZX (Saturn) cache DRAM controller> rev 0x04 on pci0.0.0
	CPU: 486DX, bus=33MHz, CPU->Memory posting ON
	Warning: NO DRAM parity!
	Cache: 256KB writeback, cache clocks=2-1-1-1
	DRAM: page mode code fetch, read and write, memory clocks=X-2-2-2
	CPU->PCI: posting ON, burst mode ON
	PCI->Memory: posting OFF
found->	vendor=0x1000, dev=0x0001, revid=0x01
	class=00-00-00, hdrtype=0x00, mfdev=0
	subordinatebus=0 	secondarybus=0
	intpin=a, irq=9
	map[0]: type 4, range 32, base 0000e800, size  8
	map[1]: type 1, range 32, base fbfef000, size  8
ncr0: <ncr 53c810 fast10 scsi> rev 0x01 int a irq 9 on pci0.1.0
ncr0: minsync=25, maxsync=206, maxoffs=8, 16 dwords burst, normal dma fifo
ncr0: single-ended, open drain IRQ driver
found->	vendor=0x8086, dev=0x0484, revid=0x03
	class=00-00-00, hdrtype=0x00, mfdev=0
	subordinatebus=0 	secondarybus=0
chip1: <Intel 82378ZB PCI to ISA bridge> rev 0x03 on pci0.2.0
	Bus Modes: Bus Park, Bus Lock,
	Coprocessor errors enabled
	Keyboard controller: 60h,62h,64h,66h
	RTC: 70h-77h
found->	vendor=0x5333, dev=0x8811, revid=0x00
	class=03-00-00, hdrtype=0x00, mfdev=0
	subordinatebus=0 	secondarybus=0
	intpin=a, irq=0
	map[0]: type 1, range 32, base fb000000, size 23
vga0: <S3 Trio graphics accelerator> rev 0x00 int a irq 0 on pci0.6.0
Probing for devices on the ISA bus:
atkbd: the current kbd controller command byte 0045
atkbd: keyboard ID 0x41ab (2)
kbdc: RESET_KBD return code:00fa
kbdc: RESET_KBD status:00aa
sc0 on isa
sc0: fb0 kbd0
sc0: VGA color <16 virtual consoles, flags=0x0>
atkbdc0 at 0x60-0x6f on motherboard
atkbd0 irq 1 on isa
kbd0: atkbd0, AT 101/102 (2), config:0x10000, flags:0x3d0000
psm0: disabled, not probed.
sio0: irq maps: 0x9 0x19 0x9 0x9
sio0 at 0x3f8-0x3ff irq 4 flags 0x10 on isa
sio0: type 16550A
sio1: irq maps: 0x1 0x9 0x1 0x1
sio1 at 0x2f8-0x2ff irq 3 on isa
sio1: type 16550A
sio2: disabled, not probed.
pca0 on motherboard
pca0: PC speaker audio driver
fdc0 at 0x3f0-0x3f7 irq 6 drq 2 on isa
fdc0: FIFO enabled, 8 bytes threshold
fd0: 1.44MB 3.5in
wdc0 at 0x1f0-0x1f7 irq 14 on isa
wdc0: unit 0 (wd0): <QUANTUM FIREBALL ST6.4A>
wd0: 6149MB (12594960 sectors), 13328 cyls, 15 heads, 63 S/T, 512 B/S
wd0: ATA INQUIRE valid = 0007, dmamword = 0407, apio = 0003, udma = 0007
ppc: parallel port found at 0x378
PC873xx FER=0x4f FAR=0x10 PTR=0x8 FCR=0x0 PCR=0x0 PMC=0x0 TUP=0x0 SID=0x1a PNP0=0xff PNP1=0xff LPTBA=0xff
PC873xx irq 7 at 0x378
PC873xx irq set to 0
PC873xx unlocked, NIBBLE
ppc0 at 0x378 irq 7 flags 0x20 on isa
ppc0: PC87332 chipset (NIBBLE-only) in COMPATIBLE mode
nlpt0: <generic printer> on ppbus 0
ppi0: <generic parallel i/o> on ppbus 0
isic0 at 0xd80 irq 12 flags 0x3 on isa
isic0: Teles S0/16.3
isic0: ISAC 2085 Version A1/A2 or 2086/2186 Version 1.1 (IOM-2) (Addr=0x960)
isic0: HSCX 82525 or 21525 Version 2.1 (AddrA=0x160, AddrB=0x560)
vga0 at 0x3b0-0x3df maddr 0xa0000 msize 131072 on isa
fb0: vga0, vga, type:VGA (5), flags:0x700ff
fb0: port:0x3b0-0x3df, crtc:0x3d4, mem:0xa0000 0x20000
fb0: init mode:24, bios mode:3, current mode:24
fb0: window:0xf00b8000 size:32k gran:32k, buf:0x0 size:0k
VGA parameters upon power-up
50 18 10 00 00 00 03 00 02 67 5f 4f 50 82 55 81 
bf 1f 00 4f 0e 0f 00 00 07 80 9c 8e 8f 28 1f 96 
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff 
VGA parameters in BIOS for mode 24
50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81 
bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96 
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff 
EGA/VGA parameters to be used for mode 24
50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81 
bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96 
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff 
VESA: v1.2, 2048k memory, flags:0x0, mode table:0xf00c4f57 (c0004f57)
VESA: ELSA WINNER 1000TRIO
npx0 on motherboard
npx0: INT 16 interface
apm0: disabled, not probed.
imasks: bio c0084040, tty c003001a, net c0061080
BIOS Geometries:
 0:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 1:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 2:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 3:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 4:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 5:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 6:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 7:00000000 0..0=1 cylinders, 0..0=1 heads, 1..0=0 sectors
 0 accounted for
Device configuration finished.
IP packet filtering initialized, divert disabled, rule-based forwarding disabled, unlimited logging
bpf: tun0 attached
bpf: lo0 attached
i4b: ISDN call control device attached
i4bisppp: 4 ISDN SyncPPP device(s) attached
bpf: isp0 attached
bpf: isp1 attached
bpf: isp2 attached
bpf: isp3 attached
i4bctl: ISDN system control port attached
i4bipr: 4 IP over raw HDLC ISDN device(s) attached (VJ header compression)
bpf: ipr0 attached
bpf: ipr1 attached
bpf: ipr2 attached
bpf: ipr3 attached
i4btel: 2 ISDN telephony interface device(s) attached
i4brbch: 4 raw B channel access device(s) attached
i4btrc: 4 ISDN trace device(s) attached
Linux-ELF exec handler installed
Waiting 5 seconds for SCSI devices to settle
ncr0: restart (scsi reset).
(probe4:ncr0:0:4:0): INQUIRY. CDB: 12 1 80 0 ff 0 
(probe4:ncr0:0:4:0): ILLEGAL REQUEST asc:24,0
(probe4:ncr0:0:4:0): Invalid field in CDB
(probe5:ncr0:0:5:0): INQUIRY. CDB: 12 1 80 0 ff 0 
(probe5:ncr0:0:5:0): ILLEGAL REQUEST asc:24,0
(probe5:ncr0:0:5:0): Invalid field in CDB sks:c8,1
sa0 at ncr0 bus 0 target 5 lun 0
sa0: <WANGTEK 5525ES SCSI 73Y1> Removable Sequential Access SCSI-2 device 
sa0: 4.901MB/s transfers (4.901MHz, offset 8)
pass0 at ncr0 bus 0 target 0 lun 0
pass0: <IBM DPES-31080 S31K> Fixed Direct Access SCSI-2 device 
pass0: Serial Number 1C176285        
pass0: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
pass1 at ncr0 bus 0 target 1 lun 0
pass1: <CONNER CFP2105S  2.14GB 2B4B> Fixed Direct Access SCSI-2 device 
pass1: Serial Number EF90YAE 
pass1: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
pass2 at ncr0 bus 0 target 2 lun 0
pass2: <IBM DORS-32160W WA6A> Fixed Direct Access SCSI-2 device 
pass2: Serial Number 5U2R4677        
pass2: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
pass3 at ncr0 bus 0 target 3 lun 0
pass3: <IBM DPES-31080 S31K> Fixed Direct Access SCSI-2 device 
pass3: Serial Number 1C071239        
pass3: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
pass4 at ncr0 bus 0 target 4 lun 0
pass4: <SANYO CRD-254S 1.02> Removable CD-ROM SCSI-2 device 
pass4: 3.300MB/s transfers
pass5 at ncr0 bus 0 target 5 lun 0
pass5: <WANGTEK 5525ES SCSI 73Y1> Removable Sequential Access SCSI-2 device 
pass5: 4.901MB/s transfers (4.901MHz, offset 8)
da1 at ncr0 bus 0 target 1 lun 0
da1: <CONNER CFP2105S  2.14GB 2B4B> Fixed Direct Access SCSI-2 device 
da1: Serial Number EF90YAE 
da1: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
da1: 2048MB (4194304 512 byte sectors: 255H 63S/T 261C)
da0 at ncr0 bus 0 target 0 lun 0
da0: <IBM DPES-31080 S31K> Fixed Direct Access SCSI-2 device 
da0: Serial Number 1C176285        
da0: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
da0: 1034MB (2118144 512 byte sectors: 255H 63S/T 131C)
da3 at ncr0 bus 0 target 3 lun 0
da3: <IBM DPES-31080 S31K> Fixed Direct Access SCSI-2 device 
da3: Serial Number 1C071239        
da3: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
da3: 1034MB (2118144 512 byte sectors: 255H 63S/T 131C)
cd0 at ncr0 bus 0 target 4 lun 0
cd0: <SANYO CRD-254S 1.02> Removable CD-ROM SCSI-2 device 
cd0: 3.300MB/s transfers
cd0: cd present [323944 x 2048 byte records]
da2 at ncr0 bus 0 target 2 lun 0
da2: <IBM DORS-32160W WA6A> Fixed Direct Access SCSI-2 device 
da2: Serial Number 5U2R4677        
da2: 10.000MB/s transfers (10.000MHz, offset 8), Tagged Queueing Enabled
da2: 2063MB (4226725 512 byte sectors: 255H 63S/T 263C)
Considering MFS root f/s.
No MFS image available as root f/s.
Considering FFS root f/s.
changing root device to da2s1a
da2s1: type 0xa5, start 63, end = 4224527, size 4224465 : OK
Start pid=2 <pagedaemon>
Start pid=3 <vmdaemon>
Start pid=4 <syncer>
wd0s1: type 0x6, start 63, end = 2056319, size 2056257 : OK
wd0s2: type 0xa5, start 2056320, end = 12594959, size 10538640 : OK
da3s1: type 0xa5, start 63, end = 2104514, size 2104452 : OK
da1s1: type 0xa5, start 261954, end = 4191263, size 3929310 : OK
da1s4: type 0x6, start 63, end = 261953, size 261891 : OK
da0s1: type 0x5, start 261954, end = 523907, size 261954 : OK
da0s3: type 0xa5, start 523908, end = 2118143, size 1594236 : OK
da0s4: type 0x6, start 63, end = 261953, size 261891 : OK
da3s1: type 0xa5, start 63, end = 2104514, size 2104452 : OK
da0s1: type 0x5, start 261954, end = 523907, size 261954 : OK
da0s3: type 0xa5, start 523908, end = 2118143, size 1594236 : OK
da0s4: type 0x6, start 63, end = 261953, size 261891 : OK
da0s1: type 0x5, start 261954, end = 523907, size 261954 : OK
da0s3: type 0xa5, start 523908, end = 2118143, size 1594236 : OK
da0s4: type 0x6, start 63, end = 261953, size 261891 : OK
da3s1: type 0xa5, start 63, end = 2104514, size 2104452 : OK
da1s1: type 0xa5, start 261954, end = 4191263, size 3929310 : OK
da1s4: type 0x6, start 63, end = 261953, size 261891 : OK
da0s1: type 0x5, start 261954, end = 523907, size 261954 : OK
da0s3: type 0xa5, start 523908, end = 2118143, size 1594236 : OK
da0s4: type 0x6, start 63, end = 261953, size 261891 : OK
splash: image decoder found: green_saver

 Regards,


To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-scsi" in the body of the message




Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?19990427212815.A674>