From owner-freebsd-scsi  Wed Jul 10 16:52:58 1996
Return-Path: owner-freebsd-scsi
Received: (from root@localhost)
          by freefall.freebsd.org (8.7.5/8.7.3) id QAA27720
          for freebsd-scsi-outgoing; Wed, 10 Jul 1996 16:52:58 -0700 (PDT)
Received: from minotaur.labyrinth.net.au (minotaur.labyrinth.net.au [203.9.148.2])
          by freefall.freebsd.org (8.7.5/8.7.3) with ESMTP id QAA27682
          for <freebsd-scsi@freebsd.org>; Wed, 10 Jul 1996 16:52:48 -0700 (PDT)
Received: (from mail@localhost) by minotaur.labyrinth.net.au (8.7.2/8.7.2) id JAA25478 for <freebsd-scsi@freebsd.org>; Thu, 11 Jul 1996 09:52:38 +1000 (EST)
Date: Thu, 11 Jul 1996 09:52:38 +1000 (EST)
Message-Id: <199607102352.JAA25478@minotaur.labyrinth.net.au>
X-Authentication-Warning: minotaur.labyrinth.net.au: mail set sender to <jbh@labyrinth.net.au> using -f
Received: from portal-as18.labyrinth.net.au(203.9.148.28) by minotaur.labyrinth.net.au via smap (V1.3)
	id sma025473; Thu Jul 11 09:52:03 1996
X-Sender: jbh@labyrinth.net.au
X-Mailer: Windows Eudora Light Version 1.5.2
Mime-Version: 1.0
Content-Type: text/plain; charset="us-ascii"
To: freebsd-scsi@freebsd.org
From: John Hartley <jbh@labyrinth.net.au>
Subject: Re: tandberg scsi tape + FreeBSD 2.1/2.0.5
Sender: owner-freebsd-scsi@freebsd.org
X-Loop: FreeBSD.org
Precedence: bulk

The following logs provide extra information on the problem I am
having with my tandberg 4100 scsi tape drive and freebsd 2.1.

Firstly here are the boot up logs:
(I have a recomiled kernel with
extraneous devices removed + 2 3COM Etherlink Cards + Mouse port +
options SCSCIDEBUG)

Jul 10 21:24:41 qwiff /kernel: FreeBSD 2.1.0-RELEASE #0: Wed Jul 10 21:10:45
1996
Jul 10 21:24:41 qwiff /kernel:
jbh@qwiff.graphica.com.au:/usr/src/sys/compile/TP_SIMPLE
Jul 10 21:24:41 qwiff /kernel: CPU: i486 DX4 (486-class CPU)
Jul 10 21:24:42 qwiff /kernel:   Origin = "GenuineIntel"  Id = 0x480  Stepping=0
Jul 10 21:24:42 qwiff /kernel:   Features=0x3<FPU,VME>
Jul 10 21:24:42 qwiff /kernel: real memory  = 33554432 (32768K bytes)
Jul 10 21:24:42 qwiff /kernel: avail memory = 31035392 (30308K bytes)
Jul 10 21:24:42 qwiff /kernel: Probing for devices on the ISA bus:
Jul 10 21:24:42 qwiff /kernel: sc0 at 0x60-0x6f irq 1 on motherboard
Jul 10 21:24:42 qwiff /kernel: sc0: VGA color <16 virtual consoles, flags=0x0>
Jul 10 21:24:43 qwiff /kernel: sio0 at 0x3f8-0x3ff irq 4 on isa
Jul 10 21:24:43 qwiff /kernel: sio0: type 16550A
Jul 10 21:24:43 qwiff /kernel: sio1 at 0x2f8-0x2ff irq 3 on isa
Jul 10 21:24:43 qwiff /kernel: sio1: type 16550A
Jul 10 21:24:43 qwiff /kernel: lpt0 at 0x3bc-0x3c3 irq 7 on isa
Jul 10 21:24:43 qwiff /kernel: lpt0: Interrupt-driven port
Jul 10 21:24:43 qwiff /kernel: lp0: TCP/IP capable interface
Jul 10 21:24:43 qwiff /kernel: psm0 at 0x60-0x63 irq 12 on motherboard
Jul 10 21:24:43 qwiff /kernel: fdc0 at 0x3f0-0x3f7 irq 6 drq 2 on isa
Jul 10 21:24:44 qwiff /kernel: fdc0: NEC 72065B
Jul 10 21:24:44 qwiff /kernel: fd0: 1.44MB 3.5in
Jul 10 21:24:44 qwiff /kernel: 2 3C5x9 board(s) on ISA found at 0x310 0x300
Jul 10 21:24:44 qwiff /kernel: ep0 at 0x300-0x30f irq 10 on isa
Jul 10 21:24:44 qwiff /kernel: ep0: aui/bnc[*BNC*] address 00:60:8c:60:ac:0e
irq 10
Jul 10 21:24:44 qwiff /kernel: ep1 at 0x310-0x31f irq 11 on isa
Jul 10 21:24:44 qwiff /kernel: ep1: aui/bnc[*BNC*] address 00:20:af:0f:6c:0a
irq 11
Jul 10 21:24:44 qwiff /kernel: npx0 on motherboard
Jul 10 21:24:44 qwiff /kernel: npx0: INT 16 interface
Jul 10 21:24:45 qwiff /kernel: Probing for devices on the PCI bus:
Jul 10 21:24:45 qwiff /kernel: ahc0 <Adaptec 2940 Ultra SCSI host adapter>
rev 0 int a irq 5 on pci0:12
Jul 10 21:24:45 qwiff /kernel: ahc0: aic7870 Ultra Single Channel, SCSI
Id=7, aic7870, 255 SCBs
Jul 10 21:24:45 qwiff /kernel: ahc0 waiting for scsi devices to settle
Jul 10 21:24:45 qwiff /kernel: (ahc0:0:0): "FUJITSU M1606S-512 6234" type 0
fixed SCSI 2
Jul 10 21:24:45 qwiff /kernel: sd0(ahc0:0:0): Direct-Access 1041MB (2131992
512 byte sectors)
Jul 10 21:24:45 qwiff /kernel: (ahc0:1:0): "MATSHITA CD-ROM CR-5XX 1.0b"
type 5 removable SCSI 1
Jul 10 21:24:46 qwiff /kernel: cd0(ahc0:1:0): CD-ROM cd present.[326402 x
2048 byte records]
Jul 10 21:24:46 qwiff /kernel: (ahc0:2:0): "TANDBERG  TDC 4100 J04:" type 1
removable SCSI 2
Jul 10 21:24:46 qwiff /kernel: st0(ahc0:2:0): Sequential-Access density code
0x0, 512-byte blocks, write-enabled
Jul 10 21:24:46 qwiff /kernel: pci0:16: OPTI, device=0xc822, class=bridge
(host) [no driver assigned]
Jul 10 21:24:41 qwiff named[64]: starting.  named LOCAL-951116.090057 Thu
Nov 16 09:00:57  1995 	jkh@westhill.cdrom.com:/usr/src/usr.sbin/named
Jul 10 21:24:42 qwiff named[65]: Ready to answer queries. 
Jul 10 21:24:45 qwiff lpd[87]: restarted


Here is the log from performing a "tar tvf /dev/rst0", after first turning on
SCSI debugging:
scsi -f /dev/st0ctl.0 -d 0xff
tar tvf /dev/rst0

Jul 10 21:41:47 qwiff /kernel: st0(ahc0:2:0): stclose:  Closing device
Jul 10 21:41:58 qwiff /kernel: st0(ahc0:2:0): scsi_cmd
Jul 10 21:41:58 qwiff /kernel: st0(ahc0:2:0): get_xs
Jul 10 21:41:58 qwiff /kernel: st0(ahc0:2:0): returning
Jul 10 21:41:58 qwiff /kernel: xs(0xf0859980):
flg(0x60)sc_link(0xf0859780)retr(0x2)timo(0x186a0)cmd(0xf08599d8)len(0x6)dat
a(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:2:0): command: 0,0,0,0,0,0-[0
bytes]
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): ahc_scsi_cmd
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): start scb(0xf0a59000)
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): cmd_sent
Jul 10 21:41:59 qwiff /kernel: command: 1b,0,0,0,1,0-[0 bytes]
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): back in cmd()
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): sc_err1,err = 0x0 
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): free_xs
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): calling private start()
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): ststart st0(ahc0:2:0): scsi_cmd
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): get_xs
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): returning
Jul 10 21:41:59 qwiff /kernel: xs(0xf0aaff80):
flg(0x60)sc_link(0xf0859780)retr(0x2)timo(0x186a0)cmd(0xf0aaffd8)len(0x6)dat
a(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:2:0): command: 0,0,0,0,0,0-[0
bytes]
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): ahc_scsi_cmd
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): start scb(0xf0a59000)
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): cmd_sent
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): ahc_done
Jul 10 21:41:59 qwiff /kernel: st0(ahc0:2:0): scsi_done
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): command: 0,0,0,0,0,0-[0 bytes]
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): back in cmd()
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): sc_err1,err = 0x0 
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): free_xs
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): calling private start()
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): ststart st0(ahc0:2:0): scsi_cmd
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): get_xs
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): returning
Jul 10 21:42:00 qwiff /kernel: xs(0xf0aaff80):
flg(0x420)sc_link(0xf0859780)retr(0x4)timo(0x1388)cmd(0xf0aaffd8)len(0x6)dat
a(0xf2772000)len(0x6)res(0x0)err(0x0)bp(0x0)st0(ahc0:2:0): command:
5,0,0,0,0,0-[6 bytes]
Jul 10 21:42:00 qwiff /kernel: ------------------------------
Jul 10 21:42:00 qwiff /kernel: 000: 00 00 00 00 00 00 
Jul 10 21:42:00 qwiff /kernel: ------------------------------
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): ahc_scsi_cmd
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): start scb(0xf0a59000)
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): 6 @0xf2772000:- 0x4b000(0x6)
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): cmd_sent
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): ahc_done
Jul 10 21:42:00 qwiff /kernel: st0(ahc0:2:0): scsi_done
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): command: 5,0,0,0,0,0-[6 bytes]
Jul 10 21:42:01 qwiff /kernel: ------------------------------
Jul 10 21:42:01 qwiff /kernel: 000: 00 ff ff ff 00 01 
Jul 10 21:42:01 qwiff /kernel: ------------------------------
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): back in cmd()
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): sc_err1,err = 0x0 
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): free_xs
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): calling private start()
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): ststart st0(ahc0:2:0): (1 <=
blksiz <= 16777215)
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): scsi_cmd
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): get_xs
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): returning
Jul 10 21:42:01 qwiff /kernel: xs(0xf0aaff80):
flg(0x420)sc_link(0xf0859780)retr(0x4)timo(0x1388)cmd(0xf0aaffd8)len(0x6)dat
a(0xf2772000)len(0xc)res(0x0)err(0x0)bp(0x0)st0(ahc0:2:0): command:
1a,0,0,0,c,0-[12 bytes]
Jul 10 21:42:01 qwiff /kernel: ------------------------------
Jul 10 21:42:01 qwiff /kernel: 000: 00 00 00 00 00 00 00 00 00 00 00 00 
Jul 10 21:42:01 qwiff /kernel: ------------------------------
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): ahc_scsi_cmd
Jul 10 21:42:01 qwiff /kernel: st0(ahc0:2:0): start scb(0xf0a59000)
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): 12 @0xf2772000:- 0x4b000(0xc)
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): cmd_sent
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): ahc_done
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): scsi_done
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): command: 1a,0,0,0,c,0-[12 bytes]
Jul 10 21:42:02 qwiff /kernel: ------------------------------
Jul 10 21:42:02 qwiff /kernel: 000: 2f 25 10 08 00 00 00 00 00 00 02 00 
Jul 10 21:42:02 qwiff /kernel: ------------------------------
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): back in cmd()
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): sc_err1,err = 0x0 
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): free_xs
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): calling private start()
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): ststart st0(ahc0:2:0): density
code 0x0, 512-byte blocks, write-enabled, st0(ahc0:2:0): buffered
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): starting block mode decision
Jul 10 21:42:02 qwiff /kernel: st0(ahc0:2:0): Give up and default to
variable mode
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): scsi_cmd
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): get_xs
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): returning
Jul 10 21:42:03 qwiff /kernel: xs(0xf0859980):
flg(0x820)sc_link(0xf0859780)retr(0x4)timo(0x1388)cmd(0xf08599d8)len(0x6)dat
a(0xf2772000)len(0xc)res(0x0)err(0x0)bp(0x0)st0(ahc0:2:0): command:
15,0,0,0,c,0-[12 bytes]
Jul 10 21:42:03 qwiff /kernel: ------------------------------
Jul 10 21:42:03 qwiff /kernel: 000: 00 00 10 08 00 00 00 00 00 00 00 00 
Jul 10 21:42:03 qwiff /kernel: ------------------------------
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): ahc_scsi_cmd
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): start scb(0xf0ab0000)
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): 12 @0xf2772000:- 0x4b000(0xc)
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): cmd_sent
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): ahc_done
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): scsi_done
Jul 10 21:42:03 qwiff /kernel: st0(ahc0:2:0): command: 15,0,0,0,c,0-[12 bytes]
Jul 10 21:42:03 qwiff /kernel: ------------------------------
Jul 10 21:42:04 qwiff /kernel: 000: 00 00 10 08 00 00 00 00 00 00 00 00 
Jul 10 21:42:04 qwiff /kernel: ------------------------------
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): back in cmd()
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): sc_err1,err = 0x1 
Jul 10 21:42:04 qwiff /kernel: code70 valid0 seg0 key5 ili0 eom0 fmark0
Jul 10 21:42:04 qwiff /kernel: info: 0 0 0 0 followed by 10 extra bytes
Jul 10 21:42:04 qwiff /kernel: extra: 0 8 0 0 24 0 2 0 0 0 
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): calling private err_handler()
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): private err_handler() returned -1
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): ILLEGAL REQUEST csi:0,8,0,0
asc:24,0 Invalid field in CDB field replaceable unit: 2
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): scsi_interpret_sense (no bp)
returned 22
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): free_xs
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): calling private start()
Jul 10 21:42:04 qwiff /kernel: st0(ahc0:2:0): ststart st0: Cannot set
selected modest0(ahc0:2:0): Open complete
Jul 10 21:42:05 qwiff /kernel: st0(ahc0:2:0): stopen: dev=0xe00 (unit 0)
result 0
Jul 10 21:42:05 qwiff /kernel: st0(ahc0:2:0): 
Jul 10 21:42:05 qwiff /kernel: ststrategy st0(ahc0:2:0): 10240 bytes @ blk0
Jul 10 21:42:05 qwiff /kernel: st0(ahc0:2:0): ststart st0: oops not queued
Jul 10 21:42:05 qwiff /kernel: st0(ahc0:2:0): stclose:  Closing device

Finally here is the output from an mt status request:
mt -f /dev/rst0 status

Present Mode:   Density = ECMA TC17    Blocksize variable
---------available modes---------
Mode 0:         Density = 0x00         Blocksize variable
Mode 1:         Density = 0x00         Blocksize variable
Mode 2:         Density = 0x00         Blocksize variable
Mode 3:         Density = 0x00         Blocksize variable


Finally thanks for investigating this problem.
If you wish me to perform further testing please let me know.

Regards.

John Hartley                         jbh@labyrinth.net.au
Graphica Software Pty. Ltd.