Subject: st.c broken ? (may have been: Re: CVS commit: src/sys - remove b_flags
To: None <ad@netbsd.org>
From: Frank Kardel <kardel@netbsd.org>
List: source-changes
Date: 08/25/2007 10:24:01
This is a multi-part message in MIME format.
--------------040300050409040102090403
Content-Type: text/plain; charset=ISO-8859-1; format=flowed
Content-Transfer-Encoding: 7bit

Hi Andrew, * !

I am experiencing quite a few strange error with my DAT tape since about
this checkin. A kernel from the beginning of July works flawlessly with the
drive and the tape.

Symptoms:
     I/O errors claimed where no real I/O error where. Possibly
    triggered by length indicator mismatches reported by the drive. These
   are ok for variable block size formatted media. Those indications where
   handled properly at the beginning of July and years before.

The dmesg output shows a READ that is terminated with an I/O error,
but data was transferred (with the size mismatch indication).
The requested block was just too big - a smaller block was read.
The second attempt with a block too small was terminated with a panic 
instead
of an I/O error.

Could somebody check what broke st.c between beginning of July and now ?
Currently st.c is unusable for variable block size tape I/O.

dmesg atteched.

Regards,
  Frank

Andrew Doran wrote:
> Module Name:	src
> Committed By:	ad
> Date:		Sun Jul 29 13:31:18 UTC 2007
>
> Modified Files:
> 	src/sys/arch/amiga/dev: fd.c
> 	src/sys/arch/dreamcast/dev/maple: mmemcard.c
> 	src/sys/dev/ieee1394: firewirereg.h fwdev.c fwmem.c
> 	src/sys/fs/cd9660: cd9660_vnops.c
> 	src/sys/fs/efs: efs_vnops.c
> 	src/sys/fs/ntfs: ntfs_vnops.c
> 	src/sys/fs/puffs: puffs_subr.c puffs_vnops.c
> 	src/sys/fs/smbfs: smbfs_io.c
> 	src/sys/fs/sysvbfs: sysvbfs_vnops.c
> 	src/sys/fs/udf: udf_subr.c
> 	src/sys/miscfs/deadfs: dead_vnops.c
> 	src/sys/miscfs/specfs: spec_vnops.c
> 	src/sys/nfs: nfs_bio.c
> 	src/sys/ufs/ffs: ffs_softdep.c
> 	src/sys/ufs/lfs: lfs_bio.c lfs_segment.c lfs_vfsops.c lfs_vnops.c
> 	src/sys/ufs/mfs: mfs_vnops.c
> 	src/sys/ufs/ufs: ufs_vnops.c
> 	src/sys/uvm: uvm_pager.c uvm_swap.c
>
> Log Message:
> It's not a good idea for device drivers to modify b_flags, as they don't
> need to understand the locking around that field. Instead of setting
> B_ERROR, set b_error instead. b_error is 'owned' by whoever completes
> the I/O request.
>
>
> To generate a diff of this commit:
> cvs rdiff -r1.71 -r1.72 src/sys/arch/amiga/dev/fd.c
> cvs rdiff -r1.11 -r1.12 src/sys/arch/dreamcast/dev/maple/mmemcard.c
> cvs rdiff -r1.5 -r1.6 src/sys/dev/ieee1394/firewirereg.h
> cvs rdiff -r1.10 -r1.11 src/sys/dev/ieee1394/fwdev.c
> cvs rdiff -r1.4 -r1.5 src/sys/dev/ieee1394/fwmem.c
> cvs rdiff -r1.26 -r1.27 src/sys/fs/cd9660/cd9660_vnops.c
> cvs rdiff -r1.3 -r1.4 src/sys/fs/efs/efs_vnops.c
> cvs rdiff -r1.34 -r1.35 src/sys/fs/ntfs/ntfs_vnops.c
> cvs rdiff -r1.42 -r1.43 src/sys/fs/puffs/puffs_subr.c
> cvs rdiff -r1.93 -r1.94 src/sys/fs/puffs/puffs_vnops.c
> cvs rdiff -r1.25 -r1.26 src/sys/fs/smbfs/smbfs_io.c
> cvs rdiff -r1.12 -r1.13 src/sys/fs/sysvbfs/sysvbfs_vnops.c
> cvs rdiff -r1.35 -r1.36 src/sys/fs/udf/udf_subr.c
> cvs rdiff -r1.43 -r1.44 src/sys/miscfs/deadfs/dead_vnops.c
> cvs rdiff -r1.102 -r1.103 src/sys/miscfs/specfs/spec_vnops.c
> cvs rdiff -r1.163 -r1.164 src/sys/nfs/nfs_bio.c
> cvs rdiff -r1.95 -r1.96 src/sys/ufs/ffs/ffs_softdep.c
> cvs rdiff -r1.102 -r1.103 src/sys/ufs/lfs/lfs_bio.c
> cvs rdiff -r1.202 -r1.203 src/sys/ufs/lfs/lfs_segment.c
> cvs rdiff -r1.242 -r1.243 src/sys/ufs/lfs/lfs_vfsops.c
> cvs rdiff -r1.209 -r1.210 src/sys/ufs/lfs/lfs_vnops.c
> cvs rdiff -r1.44 -r1.45 src/sys/ufs/mfs/mfs_vnops.c
> cvs rdiff -r1.154 -r1.155 src/sys/ufs/ufs/ufs_vnops.c
> cvs rdiff -r1.84 -r1.85 src/sys/uvm/uvm_pager.c
> cvs rdiff -r1.128 -r1.129 src/sys/uvm/uvm_swap.c
>
> Please note that diffs are not public domain; they are subject to the
> copyright notices on the relevant files.
>   


--------------040300050409040102090403
Content-Type: text/plain;
 name="dmesg"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline;
 filename="dmesg"

Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005,
    2006, 2007
    The NetBSD Foundation, Inc.  All rights reserved.
Copyright (c) 1982, 1986, 1989, 1991, 1993
    The Regents of the University of California.  All rights reserved.

NetBSD 4.99.29 (PIPADJ.MP) #24: Sat Aug 25 09:14:59 MEST 2007
	kardel@pip.kardel.name:/fs/raid1a/src/NetBSD/tc-merge/src/sys/arch/i386/compile/obj.i386/PIPADJ.MP
total memory = 2047 MB
avail memory = 2004 MB
timecounter: Timecounters tick every 10.000 msec
timecounter: Timecounter "i8254" frequency 1193182 Hz quality 100
BIOS32 rev. 0 found at 0xf0010
mainbus0 (root)
cpu0 at mainbus0 apid 0: (boot processor)
cpu0: AMD Dual-Core Opteron or Athlon 64 X2 (686-class), 2203.01 MHz, id 0x20f32
cpu0: features f7dbfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu0: features f7dbfbff<PGE,MCA,CMOV,PAT,PSE36,MPC,NOX,MMXX,MMX>
cpu0: features f7dbfbff<FXSR,SSE,SSE2,HTT,LONG,3DNOW2,3DNOW>
cpu0: features2 1<SSE3>
cpu0: "AMD Athlon(tm) 64 X2 Dual Core Processor 4400+"
cpu0: I-cache 64 KB 64B/line 2-way, D-cache 64 KB 64B/line 2-way
cpu0: L2 cache 1 MB 64B/line 16-way
cpu0: ITLB 32 4 KB entries fully associative, 8 4 MB entries fully associative
cpu0: DTLB 32 4 KB entries fully associative, 8 4 MB entries fully associative
cpu0: AMD Power Management features: f<TTP,VID,FID,TS>
cpu0: calibrating local timer
cpu0: apic clock running at 200 MHz
cpu0: 16 page colors
cpu1 at mainbus0 apid 1: (application processor)
cpu1: AMD Dual-Core Opteron or Athlon 64 X2 (686-class), 2202.81 MHz, id 0x20f32
cpu1: features f7dbfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu1: features f7dbfbff<PGE,MCA,CMOV,PAT,PSE36,MPC,NOX,MMXX,MMX>
cpu1: features f7dbfbff<FXSR,SSE,SSE2,HTT,LONG,3DNOW2,3DNOW>
cpu1: features2 1<SSE3>
cpu1: "AMD Athlon(tm) 64 X2 Dual Core Processor 4400+"
cpu1: I-cache 64 KB 64B/line 2-way, D-cache 64 KB 64B/line 2-way
cpu1: L2 cache 1 MB 64B/line 16-way
cpu1: ITLB 32 4 KB entries fully associative, 8 4 MB entries fully associative
cpu1: DTLB 32 4 KB entries fully associative, 8 4 MB entries fully associative
cpu1: AMD Power Management features: f<TTP,VID,FID,TS>
ioapic0 at mainbus0 apid 2
ioapic0: pa 0xfec00000, version 3, 24 pins
acpi0 at mainbus0: Advanced Configuration and Power Interface
acpi0: using Intel ACPI CA subsystem version 20060217
acpi0: X/RSDT: OemId <A M I ,OEMXSDT ,05000519>, AslId <MSFT,00000097>
acpi0: SCI interrupting at int 9
acpi0: fixed-feature power button present
timecounter: Timecounter "ACPI-Fast" frequency 3579545 Hz quality 1000
ACPI-Fast 24-bit timer
CPU1 (ACPI Object Type 'Processor' [0x0c]) at acpi0 not configured
CPU2 (ACPI Object Type 'Processor' [0x0c]) at acpi0 not configured
acpi: activated PNP0C0F
acpi: activated PNP0C0F
acpi: activated PNP0C0F
acpi: activated PNP0C0F
PCI0 (PNP0A03) at acpi0 not configured
PIC (PNP0000) at acpi0 not configured
DMAD (PNP0200) at acpi0 not configured
attimer0 at acpi0 (TMR, PNP0100): AT Timer
attimer0: io 0x40-0x43 irq 0
RTC0 (PNP0B00) at acpi0 not configured
pckbc0 at acpi0 (PS2K, PNP0303): kbd port
pckbc0: io 0x60,0x64 irq 1
pcppi0 at acpi0 (SPKR, PNP0800)
pcppi0: io 0x61
midi0 at pcppi0: PC speaker (CPU-intensive output)
sysbeep0 at pcppi0
npx0 at acpi0 (COPR, PNP0C04)
npx0: io 0xf0-0xff irq 13
npx0: reported by CPUID; using exception 16
FDC (PNP0700) at acpi0 not configured
lpt0 at acpi0 (LPTE, PNP0400-1)
lpt0: io 0x378-0x37f irq 7
SIOR (PNP0C02) at acpi0 not configured
RMSC (PNP0C02) at acpi0 not configured
OMSC (PNP0C02) at acpi0 not configured
com0 at acpi0 (UAR2, PNP0501-2)
com0: io 0x2f8-0x2ff irq 3
com0: ns16550a, working fifo
com1 at acpi0 (UAR1, PNP0501-1)
com1: io 0x3f8-0x3ff irq 4
com1: ns16550a, working fifo
RMEM (PNP0C01) at acpi0 not configured
acpibut0 at acpi0 (PWRB, PNP0C0C-170): ACPI Power Button
LNKA (PNP0C0F) at acpi0 not configured
LNKB (PNP0C0F) at acpi0 not configured
LNKC (PNP0C0F) at acpi0 not configured
LNKD (PNP0C0F) at acpi0 not configured
LNKE (PNP0C0F) at acpi0 not configured
LNKF (PNP0C0F) at acpi0 not configured
LNKG (PNP0C0F) at acpi0 not configured
LNKH (PNP0C0F) at acpi0 not configured
acpibut1 at acpi0 (SLPB, PNP0C0E): ACPI Sleep Button
pckbd0 at pckbc0 (kbd slot)
pckbc0: using irq 1 for kbd slot
wskbd0 at pckbd0: console keyboard
pcppi0: attached to attimer0
pci0 at mainbus0 bus 0: configuration mode 1
pci0: i/o space, memory space enabled, rd/line, rd/mult, wr/inv ok
pchb0 at pci0 dev 0 function 0
pchb0: VIA Technologies K8HTB Host (rev. 0x00)
agp0 at pchb0 (v3): aperture at 0xd0000000, size 0x10000000
pchb1 at pci0 dev 0 function 1
pchb1: VIA Technologies product 0x1282 (rev. 0x00)
pchb2 at pci0 dev 0 function 2
pchb2: VIA Technologies product 0x2282 (rev. 0x00)
pchb3 at pci0 dev 0 function 3
pchb3: VIA Technologies product 0x3282 (rev. 0x00)
pchb4 at pci0 dev 0 function 4
pchb4: VIA Technologies product 0x4282 (rev. 0x00)
pchb5 at pci0 dev 0 function 7
pchb5: VIA Technologies product 0x7282 (rev. 0x00)
ppb0 at pci0 dev 1 function 0: VIA Technologies K8HTB AGP (rev. 0x00)
pci1 at ppb0 bus 1
pci1: i/o space, memory space enabled
vga0 at pci1 dev 0 function 0: ATI Technologies Radeon 9600 AP (rev. 0x00)
wsdisplay0 at vga0 kbdmux 1: console (80x25, vt100 emulation), using wskbd0
wsmux1: connecting to wsdisplay0
radeondrm0 at vga0
info: [drm] ATI Radeon AP 9600 (unit 0)
[drm:pid0:drm_load] 
[drm:pid0:radeon_driver_load] AGP card detected
[drm:pid0:drm_agp_init] agp_available = 1
info: [drm] AGP at 0xd0000000 256MB
[drm:pid0:drm_mtrr_add] offset=d0000000 size=268435456
[drm:pid0:drm_ctxbitmap_next] drm_ctxbitmap_next bit : 0
[drm:pid0:drm_ctxbitmap_init] drm_ctxbitmap_init : 0
info: [drm] Initialized radeon 1.25.0 20060524
ATI Technologies product 0x4170 (miscellaneous display) at pci1 dev 0 function 1 not configured
fwohci0 at pci0 dev 7 function 0: VIA Technologies VT6306 IEEE 1394 Host Controller (rev. 0x80)
fwohci0: interrupting at ioapic0 pin 16 (irq 5)
fwohci0: OHCI version 1.0 (ROM=1)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 00:11:d8:00:00:58:8f:51
fwohci0: Phy 1394a available S400, 2 ports.
fwohci0: Link S400, max_rec 2048 bytes.
ieee1394if0 at fwohci0: IEEE1394 bus
fwip0 at ieee1394if0: IP over IEEE1394
fwohci0: Initiate bus reset
pdcsata0 at pci0 dev 8 function 0
pdcsata0: Promise PDC20378 SATA150 controller (rev. 0x02)
pdcsata0: interrupting at ioapic0 pin 18 (irq 10)
pdcsata0: bus-master DMA support present
atabus0 at pdcsata0 channel 0
atabus1 at pdcsata0 channel 1
atabus2 at pdcsata0 channel 2
ex0 at pci0 dev 9 function 0: 3Com 3c905C-TX 10/100 Ethernet with mngmt (rev. 0x6c)
ex0: interrupting at ioapic0 pin 16 (irq 5)
ex0: MAC address 00:50:da:ee:ca:2e
bmtphy0 at ex0 phy 24: Broadcom 3c905C internal PHY, rev. 4
bmtphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
skc0 at pci0 dev 10 function 0: ioapic0 pin 17 (irq 11)
skc0: interrupt moderation is 0 us
skc0: Marvell Yukon Lite Gigabit Ethernet rev. (0x9)
sk0 at skc0 port A: Ethernet address 00:13:d4:9b:67:09
makphy0 at sk0 phy 0: Marvell 88E1011 Gigabit PHY, rev. 5
makphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
ahc0 at pci0 dev 11 function 0: Adaptec 2940 Pro Ultra SCSI adapter
ahc0: interrupting at ioapic0 pin 16 (irq 5)
ahc0: aic7880: Ultra Wide Channel A, SCSI Id=7, 16/253 SCBs
scsibus0 at ahc0: 16 targets, 8 luns per target
ath0 at pci0 dev 12 function 0
ath0: interrupting at ioapic0 pin 17 (irq 11)
ath0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps
ath0: 11g rates: 1Mbps 2Mbps 5.5Mbps 11Mbps 6Mbps 9Mbps 12Mbps 18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
ath0: mac 5.9 phy 4.3 radio 4.6
puc0 at pci0 dev 13 function 0: NetMos NM9845 6 UART (com, com, com, com, com, com)
com2 at puc0 port 0: interrupting at ioapic0 pin 18 (irq 10)
com2: ns16550a, working fifo
com3 at puc0 port 1: interrupting at ioapic0 pin 18 (irq 10)
com3: ns16550a, working fifo
com4 at puc0 port 2: interrupting at ioapic0 pin 18 (irq 10)
com4: ns16550a, working fifo
com5 at puc0 port 3: interrupting at ioapic0 pin 18 (irq 10)
com5: ns16550a, working fifo
com6 at puc0 port 4: interrupting at ioapic0 pin 18 (irq 10)
com6: ns16550a, working fifo
com7 at puc0 port 5: interrupting at ioapic0 pin 18 (irq 10)
com7: ns16550a, working fifo
pdcide0 at pci0 dev 14 function 0
pdcide0: Promise Ultra133/ATA Bus Master IDE Accelerator (rev. 0x02)
pdcide0: bus-master DMA support present
pdcide0: primary channel configured to native-PCI mode
pdcide0: using ioapic0 pin 19 (irq 10) for native-PCI interrupt
atabus3 at pdcide0 channel 0
pdcide0: secondary channel configured to native-PCI mode
atabus4 at pdcide0 channel 1
viaide0 at pci0 dev 15 function 0
viaide0: VIA Technologies VT8237 SATA Controller (rev. 0x80)
viaide0: bus-master DMA support present
viaide0: primary channel wired to native-PCI mode
viaide0: using ioapic0 pin 20 (irq 11) for native-PCI interrupt
atabus5 at viaide0 channel 0
viaide0: secondary channel wired to native-PCI mode
atabus6 at viaide0 channel 1
viaide1 at pci0 dev 15 function 1
viaide1: VIA Technologies VT8237 ATA133 controller
viaide1: bus-master DMA support present
viaide1: primary channel configured to compatibility mode
viaide1: primary channel interrupting at ioapic0 pin 14 (irq 14)
atabus7 at viaide1 channel 0
viaide1: secondary channel configured to compatibility mode
viaide1: secondary channel interrupting at ioapic0 pin 15 (irq 15)
atabus8 at viaide1 channel 1
uhci0 at pci0 dev 16 function 0: VIA Technologies VT83C572 USB Controller (rev. 0x81)
uhci0: interrupting at ioapic0 pin 21 (irq 5)
usb0 at uhci0: USB revision 1.0
uhub0 at usb0
uhub0: VIA Technologies UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhci1 at pci0 dev 16 function 1: VIA Technologies VT83C572 USB Controller (rev. 0x81)
uhci1: interrupting at ioapic0 pin 21 (irq 5)
usb1 at uhci1: USB revision 1.0
uhub1 at usb1
uhub1: VIA Technologies UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2 at pci0 dev 16 function 2: VIA Technologies VT83C572 USB Controller (rev. 0x81)
uhci2: interrupting at ioapic0 pin 21 (irq 11)
usb2 at uhci2: USB revision 1.0
uhub2 at usb2
uhub2: VIA Technologies UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
uhci3 at pci0 dev 16 function 3: VIA Technologies VT83C572 USB Controller (rev. 0x81)
uhci3: interrupting at ioapic0 pin 21 (irq 11)
usb3 at uhci3: USB revision 1.0
uhub3 at usb3
uhub3: VIA Technologies UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub3: 2 ports with 2 removable, self powered
ehci0 at pci0 dev 16 function 4: VIA Technologies VT8237 EHCI USB Controller (rev. 0x86)
ehci0: interrupting at ioapic0 pin 21 (irq 10)
ehci0: dropped intr workaround enabled
ehci0: BIOS has given up ownership
ehci0: EHCI version 1.0
ehci0: companion controllers, 2 ports each: uhci0 uhci1 uhci2 uhci3
usb4 at ehci0: USB revision 2.0
uhub4 at usb4
uhub4: VIA Technologies EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub4: 8 ports with 8 removable, self powered
viapcib0 at pci0 dev 17 function 0
viapcib0: VIA Technologies VT8237 (Apollo KT600) PCI-ISA Bridge (rev. 0x00)
viapcib0: SMBus found at 0x400 (revision 0x0)
iic0 at viapcib0: I2C bus
spdmem0 at iic0 addr 0x50
spdmem0: DDR SDRAM memory, no parity or ECC, 512MB, 400MHz, PC3200
spdmem0: 13 rows, 10 cols, 2 ranks, 4 banks/chip, 5.0ns cycle time
spdmem0: voltage SSTL 2.5V, refresh time 7.8us (self-refreshing)
spdmem1 at iic0 addr 0x51
spdmem1: DDR SDRAM memory, no parity or ECC, 512MB, 400MHz, PC3200
spdmem1: 13 rows, 10 cols, 2 ranks, 4 banks/chip, 5.0ns cycle time
spdmem1: voltage SSTL 2.5V, refresh time 7.8us (self-refreshing)
spdmem2 at iic0 addr 0x52
spdmem2: DDR SDRAM memory, no parity or ECC, 512MB, 400MHz, PC3200
spdmem2: 13 rows, 10 cols, 2 ranks, 4 banks/chip, 5.0ns cycle time
spdmem2: voltage SSTL 2.5V, refresh time 7.8us (self-refreshing)
spdmem3 at iic0 addr 0x53
spdmem3: DDR SDRAM memory, no parity or ECC, 512MB, 400MHz, PC3200
spdmem3: 13 rows, 10 cols, 2 ranks, 4 banks/chip, 5.0ns cycle time
spdmem3: voltage SSTL 2.5V, refresh time 7.8us (self-refreshing)
auvia0 at pci0 dev 17 function 5: VIA Technologies VT8237 AC'97 Audio (rev 0x60)
auvia0: interrupting at ioapic0 pin 22 (irq 10)
auvia0: ac97: Avance Logic ALC850 codec; no 3D stereo
auvia0: ac97: ext id 9c6<AC97_23,LDAC,SDAC,CDAC,SPDIF,DRA>
audio0 at auvia0: full duplex, independent
pchb6 at pci0 dev 24 function 0
pchb6: Advanced Micro Devices K8 AMD64 HyperTransport configuration (rev. 0x00)
pchb7 at pci0 dev 24 function 1
pchb7: Advanced Micro Devices K8 AMD64 Address Map configuration (rev. 0x00)
pchb8 at pci0 dev 24 function 2
pchb8: Advanced Micro Devices K8 AMD64 DRAM configuration (rev. 0x00)
pchb9 at pci0 dev 24 function 3
pchb9: Advanced Micro Devices K8 AMD64 Miscellaneous configuration (rev. 0x00)
isa at viapcib0 not configured
isa at mainbus0 not configured
fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER mode
ieee1394if0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
ieee1394if0: bus manager 0 (me)
timecounter: Timecounter "clockinterrupt" frequency 100 Hz quality 0
Kernelized RAIDframe activated
IPsec: Initialized Security Association Processing.
scsibus0: waiting 2 seconds for devices to settle...
pdcsata0 port 1: device present, speed: 1.5Gb/s
viaide0 port 0: device present, speed: 1.5Gb/s
viaide0 port 1: device present, speed: 1.5Gb/s
wd0 at atabus1 drive 0ehci0: handing over full speed device on port 1 to uhci0
uhub4: port 1, device disappeared after reset
ehci0: handing over full speed device on port 2 to uhci0
uhub4: port 2, device disappeared after reset
ehci0: handing over full speed device on port 4 to uhci1
uhub4: port 4, device disappeared after reset
scsipi_inqmatch: 2/5/1 <, , >
cd0 at scsibus0 target 5 lun 0: <YAMAHA, CRW-F1S, 1.0g> cdrom removable
cd0: sync (50.00ns offset 15), 8-bit (20.000MB/s) transfers
cd0(ahc0:0:5:0):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x6 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x29 0x0 0x0 0x0 0x0 0x0 
cd0(ahc0:0:5:0):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x2 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x3a 0x1 0x0 0x0 0x0 0x0 
ubt0 at uhub1 port 2
ubt0: Broadcom Corp BT EDR Dongle, rev 2.00/0.00, addr 2
scsipi_inqmatch: 2/1/1 <, , >
st0 at scsibus0 target 8 lun 0: <SEAGATE, DAT    9SP40-000, 911D> tape removable
st0: st0(ahc0:0:8:0):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x6 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x29 0x0 0x0 0x0 0x1 0x1 
density code 38, 512-byte blocks, write-enabled
st0: sync (50.00ns offset 8), 16-bit (40.000MB/s) transfers
pdcsata0:1:0: lost interrupt
	type: ata tc_bcount: 512 tc_skip: 0
: <WDC WD1500ADFD-00NLR1>
wd0: drive supports 16-sector PIO transfers, LBA48 addressing
wd0: 139 GB, 290721 cyl, 16 head, 63 sec, 512 bytes/sect x 293046768 sectors
wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 6 (Ultra/133)
wd0(pdcsata0:1:0): using PIO mode 4, Ultra-DMA mode 6 (Ultra/133) (using DMA)
MAGIC == 0x00
wd1 at atabus3 drive 0: <IC35L180AVV207-1>
wd1: drive supports 16-sector PIO transfers, LBA48 addressing
wd1: 172 GB, 359010 cyl, 16 head, 63 sec, 512 bytes/sect x 361882080 sectors
wd1: 32-bit data port
wd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd2 at atabus3 drive 1: <IC35L180AVV207-1>
wd2: drive supports 16-sector PIO transfers, LBA48 addressing
wd2: 172 GB, 359010 cyl, 16 head, 63 sec, 512 bytes/sect x 361882080 sectors
wd2: 32-bit data port
wd2: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd1(pdcide0:0:0): using PIO mode 4, Ultra-DMA mode 5 (Ultra/100) (using DMA)
wd2(pdcide0:0:1): using PIO mode 4, Ultra-DMA mode 5 (Ultra/100) (using DMA)
wd3 at atabus5 drive 0: <WDC WD1500ADFD-00NLR1>
wd3: drive supports 16-sector PIO transfers, LBA48 addressing
wd3: 139 GB, 290721 cyl, 16 head, 63 sec, 512 bytes/sect x 293046768 sectors
wd3: 32-bit data port
wd3: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 6 (Ultra/133)
wd3(viaide0:0:0): using PIO mode 4, Ultra-DMA mode 6 (Ultra/133) (using DMA)
MAGIC == 0x00
wd4 at atabus6 drive 0: <WDC WD1500ADFD-00NLR1>
wd4: drive supports 16-sector PIO transfers, LBA48 addressing
wd4: 139 GB, 290721 cyl, 16 head, 63 sec, 512 bytes/sect x 293046768 sectors
wd4: 32-bit data port
wd4: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 6 (Ultra/133)
wd4(viaide0:1:0): using PIO mode 4, Ultra-DMA mode 6 (Ultra/133) (using DMA)
MAGIC == 0x00
wd5 at atabus7 drive 0: <IBM-DTLA-305040>
wd5: drive supports 16-sector PIO transfers, LBA addressing
wd5: 39266 MB, 79780 cyl, 16 head, 63 sec, 512 bytes/sect x 80418240 sectors
wd5: 32-bit data port
wd5: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd5(viaide1:0:0): using PIO mode 4, Ultra-DMA mode 5 (Ultra/100) (using DMA)
atapibus0 at atabus8: 2 targets
scsipi_inqmatch: 2/5/1 <, , >
cd1 at atapibus0 drive 0: <HL-DT-ST DVDRAM GSA-4120B, K1146SC2838, A102> cdrom removable
cd1: 32-bit data port
cd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 2 (Ultra/33)
cd1(viaide1:1:0): using PIO mode 4, Ultra-DMA mode 2 (Ultra/33) (using DMA)
umodem0 at uhub0 port 1 configuration 2 interface 0boot device: wd0
root on wd0a dumps on wd0b
root file system type: ffs

umodem0: Lucent Technologies, Inc. ELSA Modem Board, rev 1.00/1.00, addr 2, iclass 2/2
umodem0: data interface 1, has CM over data, has break
umodem0: status change notification available
ucom0 at umodem0
raid0: Component /dev/wd1g being configured at col: 0
         Column: 0 Num Columns: 2
         Version: 2 Serial Number: 20070429 Mod Counter: 1092629549
         Clean: Yes Status: 0
raid0: Component /dev/wd2g being configured at col: 1
         Column: 1 Num Columns: 2
         Version: 2 Serial Number: 20070429 Mod Counter: 1092629549
         Clean: Yes Status: 0
raid0: RAID Level 1
raid0: Components: /dev/wd1g /dev/wd2g
raid0: Total Sectors: 104857472 (51199 MB)
raid1: Component /dev/wd0e being configured at col: 0
         Column: 0 Num Columns: 2
         Version: 2 Serial Number: 20070501 Mod Counter: 1128870370
         Clean: Yes Status: 0
raid1: Component /dev/wd4e being configured at col: 1
         Column: 1 Num Columns: 2
         Version: 2 Serial Number: 20070501 Mod Counter: 1128870370
         Clean: Yes Status: 0
raid1: RAID Level 1
raid1: Components: /dev/wd0e /dev/wd4e
raid1: Total Sectors: 263686400 (128753 MB)
uhub5 at uhub0 port 2
uhub5: Texas Instruments UT-USB41 hub, class 9/0, rev 1.10/1.10, addr 3
uhub5: 4 ports with 4 removable, self powered
uhidev0 at uhub5 port 2 configuration 1 interface 0
uhidev0: Microsoft Basic Optical Mouse, rev 1.10/0.00, addr 4, iclass 3/1
ums0 at uhidev0: 3 buttons and Z dir.
wsmouse0 at ums0 mux 0
skc0: interrupt moderation is 1000 us
wsdisplay0: screen 1 added (80x25, vt100 emulation)
wsdisplay0: screen 2 added (80x25, vt100 emulation)
wsdisplay0: screen 3 added (80x50, vt100 emulation)
wsdisplay0: screen 4 added (80x50, vt100 emulation)
wsdisplay0: screen 5 added (80x50, vt100 emulation)
wsdisplay0: screen 6 added (80x25, vt100 emulation)
wsdisplay0: screen 7 added (80x25, vt100 emulation)
bthub0 at ubt0 local-bdaddr 00:0b:0d:4a:4b:c8
Connection attempt to TCP [::0001]:113 from [::0001]:65530
Connection attempt to TCP [::0001]:631 from [::0001]:65528
Connection attempt to TCP 127.0.0.1:631 from 127.0.0.1:65527
Connection attempt to TCP [::0001]:631 from [::0001]:65526
Connection attempt to TCP 127.0.0.1:631 from 127.0.0.1:65525
Connection attempt to TCP [::0001]:631 from [::0001]:65524
Connection attempt to TCP 127.0.0.1:631 from 127.0.0.1:65523
Connection attempt to TCP [::0001]:631 from [::0001]:65522
Connection attempt to TCP 127.0.0.1:631 from 127.0.0.1:65521
set{u,g}id pid 957 (faxgetty) was invoked by uid 0 ppid 1 (init) with fd 0,1,2 closed
umass0 at uhub4 port 5 configuration 1 interface 0
umass0: Genesys Logic USB Storage, rev 2.00/96.01, addr 2
umass0: using SCSI over Bulk-Only
scsibus1 at umass0: 2 targets, 4 luns per target
scsipi_inqmatch: 2/0/1 <, , >
sd0 at scsibus1 target 0 lun 0: <Generic, STORAGE DEVICE, 9601> disk removable
sd0(umass0:0:0:0):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x6 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x28 0x0 0x0 0x0 0x0 0x0 
sd0: fabricating a geometry
sd0: 1938 MB, 1938 cyl, 64 head, 32 sec, 512 bytes/sect x 3970048 sectors
scsipi_inqmatch: 2/0/1 <, , >
sd1 at scsibus1 target 0 lun 1: <Generic, STORAGE DEVICE, 9601> disk removable
sd1(umass0:0:0:1):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x2 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x3a 0x0 0x0 0x0 0x0 0x0 
sd1: drive offline
scsipi_inqmatch: 2/0/1 <, , >
sd2 at scsibus1 target 0 lun 2: <Generic, STORAGE DEVICE, 9601> disk removable
sd2: fabricating a geometry
sd2: 1938 MB, 1938 cyl, 64 head, 32 sec, 512 bytes/sect x 3970048 sectors
scsipi_inqmatch: 2/0/1 <, , >
sd3 at scsibus1 target 0 lun 3: <Generic, STORAGE DEVICE, 9601> disk removable
sd3(umass0:0:0:3):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x2 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x3a 0x0 0x0 0x0 0x0 0x0 
sd3: drive offline
sd0(umass0:0:0:0):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x2 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x3a 0x0 0x0 0x0 0x0 0x0 
st0: already open
st0: already open
st0(ahc0:0:8:0):  sense debug information:
	code 0x70 valid 0
	seg 0x0 key 0x6 ili 0x0 eom 0x0 fmark 0x0

info: 0x0 0x0 0x0 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x28 0x0 0x0 0x0 0x0 0x0 
st0(ahc0:0:8:0):  sense debug information:
	code 0x70 valid 1
	seg 0x0 key 0x0 ili 0x1 eom 0x0 fmark 0x0

info: 0x0 0x0 0x4 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 
st0(ahc0:0:8:0):  Check Condition on CDB: 0x08 00 01 00 00 00
    SENSE KEY:  No Additional Sense
                Incorrect Length Indicator Set
   INFO FIELD:  1024
     ASC/ASCQ:  No Additional Sense Information

st0(ahc0:0:8:0): closing
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open: dev=0xe03 (unit 0 (of 4))
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x000000a0)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x2710)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open complete
st0(ahc0:0:8:0): [ioctl: op=0x5 count=0x1]
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000000)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0xdbba00)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): closing
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open: dev=0xe03 (unit 0 (of 4))
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x000000a0)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x2710)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open complete
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00401020)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x7530)cmd(0xc28cbf50)len(0x6)data(0xcf49192e)len(0xc)res(0xc)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1a,0x0,0x0,0x0,0xc,0x0-[12 bytes]
------------------------------
000: 49 cf 2a 2f 3a c0 58 fe 07 c3 00 00 
------------------------------

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1a,0x0,0x0,0x0,0xc,0x0-[12 bytes]
------------------------------
000: 0b 34 10 08 26 00 00 00 00 00 00 00 
------------------------------
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): density code 38, 0-byte blocks, write-enabled, st0(ahc0:0:8:0): buffered
st0(ahc0:0:8:0): [ioctl: get status]
st0(ahc0:0:8:0): ststrategy 65536 bytes @ blk 0
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00001009)xs_status(0x00000000)periph(0xc3000400)retr(0x0)timo(0x2bf20)cmd(0xc28cbf50)len(0x6)data(0xcc3a9000)len(0x10000)res(0x10000)err(0x0)bp(0xc28d483c)st0(ahc0:0:8:0):  command: 0x8,0x0,0x1,0x0,0x0,0x0-[65536 bytes]
------------------------------
000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
016: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
032: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
048: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
------------------------------

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x8,0x0,0x1,0x0,0x0,0x0-[65536 bytes]
------------------------------
000: cc f2 e1 8c 00 00 00 d1 00 00 00 00 42 42 30 32 
016: 00 00 00 02 45 f3 d0 33 ff ff ff fe 00 00 00 00 
032: 00 00 00 ad 42 61 63 75 6c 61 20 31 2e 30 20 69 
048: 6d 6d 6f 72 74 61 6c 0a 00 00 00 00 0b 00 03 f1 
------------------------------
st0(ahc0:0:8:0):  sense debug information:
	code 0x70 valid 1
	seg 0x0 key 0x0 ili 0x1 eom 0x0 fmark 0x0

info: 0x0 0x0 0x4 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 
st0(ahc0:0:8:0): calling private err_handler()
st0(ahc0:0:8:0):  Check Condition on CDB: 0x08 00 01 00 00 00
    SENSE KEY:  No Additional Sense
                Incorrect Length Indicator Set
   INFO FIELD:  1024
     ASC/ASCQ:  No Additional Sense Information

st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): closing
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open: dev=0xe03 (unit 0 (of 4))
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x000000a0)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x2710)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open complete
st0(ahc0:0:8:0): [ioctl: op=0x5 count=0x1]
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000000)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0xdbba00)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): closing
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open: dev=0xe03 (unit 0 (of 4))
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x000000a0)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x2710)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x0,0x0,0x0,0x0,0x0,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00000140)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x1388)cmd(0xc28cbf50)len(0x6)data(0x0)len(0x0)res(0x0)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1e,0x0,0x0,0x0,0x1,0x0-[0 bytes]
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): open complete
st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00401020)xs_status(0x00000000)periph(0xc3000400)retr(0x4)timo(0x7530)cmd(0xc28cbf50)len(0x6)data(0xcf4c792e)len(0xc)res(0xc)err(0x0)bp(0x0)st0(ahc0:0:8:0):  command: 0x1a,0x0,0x0,0x0,0xc,0x0-[12 bytes]
------------------------------
000: 4c cf 2a 2f 3a c0 58 fe 07 c3 00 00 
------------------------------

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x1a,0x0,0x0,0x0,0xc,0x0-[12 bytes]
------------------------------
000: 0b 34 10 08 26 00 00 00 00 00 00 00 
------------------------------
st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): density code 38, 0-byte blocks, write-enabled, st0(ahc0:0:8:0): buffered
st0(ahc0:0:8:0): [ioctl: get status]
st0(ahc0:0:8:0): ststrategy 1024 bytes @ blk 0
st0(ahc0:0:8:0): ststart st0(ahc0:0:8:0): scsipi_get_xs
st0(ahc0:0:8:0): calling pool_get
st0(ahc0:0:8:0): returning
st0(ahc0:0:8:0): scsi_scsipi_cmd
scsipi_execute_xs: xs(0xc28cbebc): xs_control(0x00001009)xs_status(0x00000000)periph(0xc3000400)retr(0x0)timo(0x2bf20)cmd(0xc28cbf50)len(0x6)data(0xcc3a9000)len(0x400)res(0x400)err(0x0)bp(0xc28d4b24)st0(ahc0:0:8:0):  command: 0x8,0x0,0x0,0x4,0x0,0x0-[1024 bytes]
------------------------------
000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
016: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
032: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
048: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
------------------------------

st0(ahc0:0:8:0): ahc_action
st0(ahc0:0:8:0): start scb(0xc2f3e310)
st0(ahc0:0:8:0): scsipi_done
st0(ahc0:0:8:0):  command: 0x8,0x0,0x0,0x4,0x0,0x0-[1024 bytes]
------------------------------
000: cc f2 e1 8c 00 00 00 d1 00 00 00 00 42 42 30 32 
016: 00 00 00 02 45 f3 d0 33 ff ff ff fe 00 00 00 00 
032: 00 00 00 ad 42 61 63 75 6c 61 20 31 2e 30 20 69 
048: 6d 6d 6f 72 74 61 6c 0a 00 00 00 00 0b 00 03 f1 
------------------------------
st0(ahc0:0:8:0):  sense debug information:
	code 0x70 valid 1
	seg 0x0 key 0x0 ili 0x1 eom 0x0 fmark 0x0

info: 0xff 0xff 0x8 0x0 followed by 10 extra bytes
	extra: 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 
st0(ahc0:0:8:0): calling private err_handler()
st0: 64512-byte tape record too big for 1024-byte user buffer
st0(ahc0:0:8:0):  Check Condition on CDB: 0x08 00 00 04 00 00
    SENSE KEY:  No Additional Sense
                Incorrect Length Indicator Set
   INFO FIELD:  -63488
     ASC/ASCQ:  No Additional Sense Information

st0(ahc0:0:8:0): scsipi_free_xs
st0(ahc0:0:8:0): calling private start()
st0(ahc0:0:8:0): ststart panic: kernel diagnostic assertion "delta > 0" failed: file "/fs/raid1a/src/NetBSD/tc-merge/src/sys/kern/kern_physio.c", line 429
Begin traceback...
uvm_fault(0xc06ea120, 0xffff0000, 1) -> 0xe
fatal page fault in supervisor mode
trap type 6 code 0 eip c046f700 cs 8 eflags 10246 cr2 ffff0800 ilevel 0
panic: trap
Faulted in mid-traceback; aborting...
dumping to dev 0,33 offset 4195719
dump ....

--------------040300050409040102090403--