Date: 11 Jul 2009 02:46:22 -0000 From: Andrew Reilly <areilly@bigpond.net.au> To: FreeBSD-gnats-submit@FreeBSD.org Subject: kern/136652: Firewire-attached "WD My Book 1028" does not mount at boot but is OK if connected after boot. Message-ID: <20090711024622.3123.qmail@areilly.bpa.nu> Resent-Message-ID: <200907110250.n6B2o2oS006448@freefall.freebsd.org>
next in thread | raw e-mail | index | archive | help
>Number: 136652
>Category: kern
>Synopsis: Firewire-attached "WD My Book 1028" does not mount at boot but is OK if connected after boot.
>Confidential: no
>Severity: serious
>Priority: high
>Responsible: freebsd-bugs
>State: open
>Quarter:
>Keywords:
>Date-Required:
>Class: sw-bug
>Submitter-Id: current-users
>Arrival-Date: Sat Jul 11 02:50:01 UTC 2009
>Closed-Date:
>Last-Modified:
>Originator: Andrew Reilly
>Release: FreeBSD 7.2-STABLE amd64
>Organization:
>Environment:
System: FreeBSD duncan.reilly.home 7.2-STABLE FreeBSD 7.2-STABLE #68: Sat Jul 11 11:15:30 EST 2009 root@duncan.reilly.home:/usr/obj/usr/src/sys/DUNCAN amd64
The machine is an Athlon-X2 with 3G RAM and firewire on the motherboard.
The kernel has just been re-built from a csup last night, using this kernal config:
include GENERIC
ident DUNCAN
device atapicam
nodevice atapicd # ATAPI CDROM drives
nodevice atapifd # ATAPI floppy drives
nodevice atapist # ATAPI tape drives
and this /etc/make.conf:
CFLAGS+=-g
CXXFLAGS+=-g
KERNCONF=DUNCAN
###### sup #####
SUP_UPDATE=
#
SUP=/usr/bin/csup
SUPFLAGS=-g -L 1
SUPHOST=cvsup3.freebsd.org
SUPFILE=/usr/share/examples/cvsup/standard-supfile
PORTSSUPFILE=/usr/share/examples/cvsup/ports-supfile
NO_LPR=YES
NO_SENDMAIL=YES
WITH_GTK2=yes
WITH_CUPS=yes
#WITH_DEBUG=yes
#PERL_VERSION=5.8.8
A4=yes
# added by use.perl 2009-06-16 17:52:03
PERL_VERSION=5.10.0
DISTDIR=/nb/ports/distfiles/
WRKDIRPREFIX=/nb/ports/work/
TMPDIR=/nb/tmp/
The dmesg is attached.
(I suspect that the whinging about probe:ata1:0:0:0 is
new too, but I don't use the CD drive in this machine
much.)
>Description:
A while ago I had a 300G Maxtor firewire external hard
drive, and it worked perfectly for years until it died
horribly a month ago. I replaced it with a WD My Book
1028 firewire drive, and the system has been unable to
boot properly since. Often it would not even get to
single-user mode, waiting for xpt_config, as has been
previously reported. I'm pleased to report that today's
build seems to have fixed that problem, at least for me.
The Firewire drive is still not recognized though, so
the system falls into single-user mode, because the FW
drive is listed in my /etc/fstab.
While in single-user mode, if I unplug the FW drive and
then plug it back in, the device is scanned properly and
appears as /dev/da1 as intended. I can then mount -a
and ctl-D to continue the boot and everything is nice.
I *could* work around the boot issue by taking the drive
out of /etc/fstab, but I would still have to do the
manual reinsertion dance before the drive showed up in
/dev and was mountable, and that's not good.
>How-To-Repeat:
Not sure how someone who doesn't have this hardware
could repeat the problem, but it does this every time,
for me. I'm happy to run tests and patches if anyone
wants me to.
>Fix:
Manually connecting the drive *after* the system is
booted (at least to single-user shell prompt) works, but
isn't a fix.
----- dmesg.boot ---
Copyright (c) 1992-2009 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 7.2-STABLE #68: Sat Jul 11 11:15:30 EST 2009
root@duncan.reilly.home:/usr/obj/usr/src/sys/DUNCAN
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: AMD Athlon(tm) 64 X2 Dual Core Processor 4200+ (2211.34-MHz K8-class CPU)
Origin = "AuthenticAMD" Id = 0x20fb1 Stepping = 1
Features=0x178bfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,MMX,FXSR,SSE,SSE2,HTT>
Features2=0x1<SSE3>
AMD Features=0xe2500800<SYSCALL,NX,MMX+,FFXSR,LM,3DNow!+,3DNow!>
AMD Features2=0x3<LAHF,CMP>
Cores per package: 2
usable memory = 3207491584 (3058 MB)
avail memory = 3094405120 (2951 MB)
ACPI APIC Table: <Nvidia AWRDACPI>
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
cpu0 (BSP): APIC ID: 0
cpu1 (AP): APIC ID: 1
ioapic0: Changing APIC ID to 2
ioapic0 <Version 1.1> irqs 0-23 on motherboard
kbd1 at kbdmux0
acpi0: <Nvidia AWRDACPI> on motherboard
acpi0: [ITHREAD]
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, bfef0000 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
acpi_button0: <Power Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
pci0: <memory> at device 0.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 1.0 on pci0
isa0: <ISA bus> on isab0
pci0: <serial bus, SMBus> at device 1.1 (no driver attached)
ohci0: <OHCI (generic) USB controller> mem 0xf2102000-0xf2102fff irq 21 at device 2.0 on pci0
ohci0: [GIANT-LOCKED]
ohci0: [ITHREAD]
usb0: OHCI version 1.0, legacy support
usb0: <OHCI (generic) USB controller> on ohci0
usb0: USB revision 1.0
uhub0: <nVidia OHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb0
uhub0: 10 ports with 10 removable, self powered
ehci0: <NVIDIA nForce4 USB 2.0 controller> mem 0xfeb00000-0xfeb000ff irq 22 at device 2.1 on pci0
ehci0: [GIANT-LOCKED]
ehci0: [ITHREAD]
usb1: EHCI version 1.0
usb1: companion controller, 4 ports each: usb0
usb1: <NVIDIA nForce4 USB 2.0 controller> on ehci0
usb1: USB revision 2.0
uhub1: <nVidia EHCI root hub, class 9/0, rev 2.00/1.00, addr 1> on usb1
uhub1: 10 ports with 10 removable, self powered
umass0: <Western Digital External HDD, class 0/0, rev 2.00/1.06, addr 2> on uhub1
pcm0: <nVidia nForce4> port 0xb800-0xb8ff,0xbc00-0xbcff mem 0xf2105000-0xf2105fff irq 23 at device 4.0 on pci0
pcm0: [ITHREAD]
pcm0: <Avance Logic ALC850 AC97 Codec>
atapci0: <nVidia nForce CK804 UDMA133 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xf000-0xf00f at device 6.0 on pci0
ata0: <ATA channel 0> on atapci0
ata0: [ITHREAD]
ata1: <ATA channel 1> on atapci0
ata1: [ITHREAD]
atapci1: <nVidia nForce CK804 SATA300 controller> port 0x9f0-0x9f7,0xbf0-0xbf3,0x970-0x977,0xb70-0xb73,0xd000-0xd00f mem 0xf2100000-0xf2100fff irq 21 at device 7.0 on pci0
atapci1: [ITHREAD]
ata2: <ATA channel 0> on atapci1
ata2: [ITHREAD]
ata3: <ATA channel 1> on atapci1
ata3: [ITHREAD]
atapci2: <nVidia nForce CK804 SATA300 controller> port 0x9e0-0x9e7,0xbe0-0xbe3,0x960-0x967,0xb60-0xb63,0xe400-0xe40f mem 0xf2101000-0xf2101fff irq 22 at device 8.0 on pci0
atapci2: [ITHREAD]
ata4: <ATA channel 0> on atapci2
ata4: [ITHREAD]
ata5: <ATA channel 1> on atapci2
ata5: [ITHREAD]
pcib1: <ACPI PCI-PCI bridge> at device 9.0 on pci0
pci1: <ACPI PCI bus> on pcib1
fwohci0: <Texas Instruments TSB82AA2> mem 0xf2004000-0xf20047ff,0xf2000000-0xf2003fff irq 18 at device 10.0 on pci1
fwohci0: [FILTER]
fwohci0: OHCI version 1.10 (ROM=1)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 00:14:85:56:00:e6:80:b0
fwohci0: invalid speed 7 (fixed to 3).
fwohci0: Phy 1394a available S800, 3 ports.
fwohci0: Link S800, max_rec 4096 bytes.
firewire0: <IEEE1394(FireWire) bus> on fwohci0
fwe0: <Ethernet over FireWire> on firewire0
if_fwe0: Fake Ethernet address: 02:14:85:e6:80:b0
fwe0: Ethernet address: 02:14:85:e6:80:b0
fwip0: <IP over FireWire> on firewire0
fwip0: Firewire address: 00:14:85:56:00:e6:80:b0 @ 0xfffe00000000, S800, maxrec 4096
sbp0: <SBP-2/SCSI over FireWire> on firewire0
dcons_crom0: <dcons configuration ROM> on firewire0
dcons_crom0: bus_addr 0xba52c000
fwohci0: Initiate bus reset
fwohci0: BUS reset
fwohci0: node_id=0xc800ffc1, gen=1, CYCLEMASTER mode
nfe0: <NVIDIA nForce4 CK804 MCP9 Networking Adapter> port 0xe800-0xe807 mem 0xf2103000-0xf2103fff irq 23 at device 10.0 on pci0
miibus0: <MII bus> on nfe0
ciphy0: <Cicada CS8201 10/100/1000TX PHY> PHY 7 on miibus0
ciphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
nfe0: Ethernet address: 00:14:85:e7:78:60
nfe0: [FILTER]
pcib2: <ACPI PCI-PCI bridge> at device 11.0 on pci0
pci2: <ACPI PCI bus> on pcib2
pcib3: <ACPI PCI-PCI bridge> at device 12.0 on pci0
pci3: <ACPI PCI bus> on pcib3
pcib4: <ACPI PCI-PCI bridge> at device 13.0 on pci0
pci4: <ACPI PCI bus> on pcib4
pcib5: <ACPI PCI-PCI bridge> at device 14.0 on pci0
pci5: <ACPI PCI bus> on pcib5
vgapci0: <VGA-compatible display> port 0xa000-0xa0ff mem 0xe0000000-0xefffffff,0xf1000000-0xf100ffff irq 18 at device 0.0 on pci5
pci5: <multimedia, HDA> at device 0.1 (no driver attached)
sio0: configured irq 4 not in bitmap of probed irqs 0
sio0: port may not be enabled
sio0: configured irq 4 not in bitmap of probed irqs 0
sio0: port may not be enabled
sio0: <16550A-compatible COM port> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
sio0: type 16550A
sio0: [FILTER]
sio1: configured irq 3 not in bitmap of probed irqs 0
sio1: port may not be enabled
sio1: configured irq 3 not in bitmap of probed irqs 0
sio1: port may not be enabled
sio1: <Generic IRDA-compatible device> port 0x2f8-0x2ff irq 3 on acpi0
sio1: type 16550A
sio1: [FILTER]
ppc0: <Parallel port> port 0x378-0x37f,0x778-0x77b irq 7 drq 3 on acpi0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/16 bytes threshold
ppbus0: <Parallel port bus> on ppc0
ppbus0: [ITHREAD]
plip0: <PLIP network interface> on ppbus0
plip0: WARNING: using obsoleted IFF_NEEDSGIANT flag
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
ppc0: [GIANT-LOCKED]
ppc0: [ITHREAD]
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
cpu0: <ACPI CPU> on acpi0
powernow0: <Cool`n'Quiet K8> on cpu0
cpu1: <ACPI CPU> on acpi0
powernow1: <Cool`n'Quiet K8> on cpu1
orm0: <ISA Option ROM> at iomem 0xc0000-0xcffff on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
ums0: <Logitech USB-PS/2 Trackball, class 0/0, rev 1.00/2.10, addr 2> on uhub0
ums0: 2 buttons.
uscanner0: <AGFA SNAPSCAN, class 255/255, rev 1.00/1.00, addr 3> on uhub0
Timecounters tick every 1.000 msec
firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
firewire0: bus manager 1 (me)
fwohci0: phy int
ad4: 715403MB <WDC WD7500AACS-00D6B1 01.01A01> at ata2-master SATA150
ad6: 715404MB <WDC WD7500AACS-00D6B1 01.01A01> at ata3-master SATA150
firewire0: New S800 device ID:0090a97488ce53aa
GEOM_MIRROR: Device mirror/gm0 launched (2/2).
GEOM_LABEL: Label for provider mirror/gm0s1a is ufsid/4a34e7954ade199c.
GEOM_LABEL: Label for provider mirror/gm0s1d is ufsid/4a34e7addf0b3850.
GEOM_LABEL: Label for provider mirror/gm0s1e is ufsid/4a34e79577a524cd.
unknown: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00 sks=0x48 0x00 0x01
(probe0:ata1:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0
(probe0:ata1:0:0:0): CAM Status: SCSI Status Error
(probe0:ata1:0:0:0): SCSI Status: Check Condition
(probe0:ata1:0:0:0): UNIT ATTENTION asc:29,0
(probe0:ata1:0:0:0): Power on, reset, or bus device reset occurred
(probe0:ata1:0:0:0): Retrying Command (per Sense Data)
(probe0:ata1:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0
(probe0:ata1:0:0:0): CAM Status: SCSI Status Error
(probe0:ata1:0:0:0): SCSI Status: Check Condition
(probe0:ata1:0:0:0): NOT READY asc:3a,0
(probe0:ata1:0:0:0): Medium not present
(probe0:ata1:0:0:0): Unretryable error
unknown: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00 sks=0x48 0x00 0x01
SMP: AP CPU #1 Launched!
da0 at umass-sim0 bus 0 target 0 lun 0
da0: <WD 7500AAK External 1.06> Fixed Direct Access SCSI-0 device
da0: 40.000MB/s transfers
da0: 715404MB (1465149168 512 byte sectors: 255H 63S/T 91201C)
cd0 at ata1 bus 0 target 0 lun 0
cd0: <PIONEER DVD-RW DVR-110D 1.17> Removable CD-ROM SCSI-0 device
cd0: 3.300MB/s transfers
cd0: Attempt to query device size failed: NOT READY, Medium not present
GEOM_LABEL: Label for provider da0s1a is ufsid/484a3a0a61af3002.
Trying to mount root from ufs:/dev/mirror/gm0s1a
GEOM_LABEL: Label ufsid/4a34e7954ade199c removed.
GEOM_LABEL: Label for provider mirror/gm0s1a is ufsid/4a34e7954ade199c.
GEOM_LABEL: Label ufsid/4a34e7addf0b3850 removed.
GEOM_LABEL: Label for provider mirror/gm0s1d is ufsid/4a34e7addf0b3850.
GEOM_LABEL: Label ufsid/4a34e79577a524cd removed.
GEOM_LABEL: Label for provider mirror/gm0s1e is ufsid/4a34e79577a524cd.
GEOM_LABEL: Label ufsid/4a34e7954ade199c removed.
GEOM_LABEL: Label ufsid/4a34e7addf0b3850 removed.
GEOM_LABEL: Label ufsid/4a34e79577a524cd removed.
fwohci0: BUS reset
fwohci0: node_id=0xc800ffc0, gen=2, CYCLEMASTER mode
firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
firewire0: bus manager 0 (me)
umass0: at uhub1 port 3 (addr 2) disconnected
(da0:umass-sim0:0:0:0):G ElOoMs_tL AdBeEvLi:c Laeb
el( duafs0i:du/m4a8s4sa-3sai0ma0:601:a0f:3000)2: rreemmoovveidn.g
device entry
umass0: detached
umass0: <Western Digital External HDD, class 0/0, rev 2.00/1.06, addr 2> on uhub1
da0 at umass-sim0 bus 0 target 0 lun 0
da0: <WD 7500AAK External 1.06> Fixed Direct Access SCSI-0 device
da0: 40.000MB/s transfers
da0: 715404MB (1465149168 512 byte sectors: 255H 63S/T 91201C)
GEOM_LABEL: Label for provider da0s1a is ufsid/484a3a0a61af3002.
fwohci0: BUS reset
fwohci0: node_id=0xc800ffc1, gen=3, CYCLEMASTER mode
firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
firewire0: bus manager 1 (me)
sbp0:0:0 request timeout(mgm orb:0x01715178) ... reset start
firewire0: split transaction timeout: tl=0xb flag=0x04
send: dst=0x00 tl=0x0b rt=0 tcode=0x0 pri=0x0 src=0x000
sbp0:0:0 sbp_reset_start failed: resp=60
fwohci0: BUS reset
fwohci0: node_id=0xc800ffc1, gen=4, CYCLEMASTER mode
firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
firewire0: bus manager 1 (me)
da1 at sbp0 bus 0 target 0 lun 0
da1: <WD My Book 1028> Fixed Direct Access SCSI-4 device
da1: 50.000MB/s transfers
da1: 953869MB (1953525168 512 byte sectors: 255H 63S/T 121601C)
GEOM_LABEL: Label for provider da1s1a is ufsid/4a324039d6307dd3.
ses0 at sbp0 bus 0 target 0 lun 1
ses0: <WD My Book Device > Fixed Enclosure Services SCSI-4 device
ses0: 50.000MB/s transfers
ses0: SCSI-3 SES Device
sbp0:0:1 login failed
GEOM_LABEL: Label ufsid/4a324039d6307dd3 removed.
GEOM_LABEL: Label ufsid/484a3a0a61af3002 removed.
GEOM_LABEL: Label for provider da0s1a is ufsid/484a3a0a61af3002.
GEOM_LABEL: Label for provider da1s1a is ufsid/4a324039d6307dd3.
GEOM_LABEL: Label for provider mirror/gm0s1e is ufsid/4a34e79577a524cd.
GEOM_LABEL: Label for provider mirror/gm0s1d is ufsid/4a34e7addf0b3850.
GEOM_LABEL: Label ufsid/4a34e7addf0b3850 removed.
GEOM_LABEL: Label ufsid/4a34e79577a524cd removed.
GEOM_LABEL: Label ufsid/4a324039d6307dd3 removed.
GEOM_LABEL: Label ufsid/484a3a0a61af3002 removed.
GEOM_LABEL: Label for provider md0 is ufsid/4a57f6f7b14dcb78.
GEOM_LABEL: Label ufsid/4a57f6f7b14dcb78 removed.
nfe0: link state changed to UP
>Release-Note:
>Audit-Trail:
>Unformatted:
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20090711024622.3123.qmail>