From owner-freebsd-usb@FreeBSD.ORG Tue Nov 13 22:00:23 2012 Return-Path: Delivered-To: freebsd-usb@smarthost.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 13624E59 for ; Tue, 13 Nov 2012 22:00:23 +0000 (UTC) (envelope-from gnats@FreeBSD.org) Received: from freefall.freebsd.org (freefall.freebsd.org [IPv6:2001:1900:2254:206c::16:87]) by mx1.freebsd.org (Postfix) with ESMTP id DA7908FC0C for ; Tue, 13 Nov 2012 22:00:22 +0000 (UTC) Received: from freefall.freebsd.org (localhost [127.0.0.1]) by freefall.freebsd.org (8.14.5/8.14.5) with ESMTP id qADM007V043193 for ; Tue, 13 Nov 2012 22:00:00 GMT (envelope-from gnats@freefall.freebsd.org) Received: (from gnats@localhost) by freefall.freebsd.org (8.14.5/8.14.5/Submit) id qADM00Un043189; Tue, 13 Nov 2012 22:00:00 GMT (envelope-from gnats) Resent-Date: Tue, 13 Nov 2012 22:00:00 GMT Resent-Message-Id: <201211132200.qADM00Un043189@freefall.freebsd.org> Resent-From: FreeBSD-gnats-submit@FreeBSD.org (GNATS Filer) Resent-To: freebsd-usb@FreeBSD.org Resent-Reply-To: FreeBSD-gnats-submit@FreeBSD.org, Heikki Suonsivu Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 2753CD99 for ; Tue, 13 Nov 2012 21:58:27 +0000 (UTC) (envelope-from hsu@lelu.suonsivu.net) Received: from lelu.suonsivu.net (lelu.suonsivu.net [212.16.96.66]) by mx1.freebsd.org (Postfix) with ESMTP id 3F4318FC14 for ; Tue, 13 Nov 2012 21:58:25 +0000 (UTC) Received: from lelu.suonsivu.net (localhost [127.0.0.1]) by lelu.suonsivu.net (8.14.5/8.14.5) with ESMTP id qADLq6rb039238 for ; Tue, 13 Nov 2012 23:52:06 +0200 (EET) (envelope-from hsu@lelu.suonsivu.net) Received: (from hsu@localhost) by lelu.suonsivu.net (8.14.5/8.14.5/Submit) id qADLq6j9039168; Tue, 13 Nov 2012 23:52:06 +0200 (EET) (envelope-from hsu) Message-Id: <201211132152.qADLq6j9039168@lelu.suonsivu.net> Date: Tue, 13 Nov 2012 23:52:06 +0200 (EET) From: Heikki Suonsivu To: FreeBSD-gnats-submit@FreeBSD.org X-Send-Pr-Version: 3.114 Subject: usb/173616: LG USB DVD-RW USB STALL X-BeenThere: freebsd-usb@freebsd.org X-Mailman-Version: 2.1.14 Precedence: list Reply-To: Heikki Suonsivu List-Id: FreeBSD support for USB List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 13 Nov 2012 22:00:23 -0000 >Number: 173616 >Category: usb >Synopsis: LG USB DVD-RW USB STALL >Confidential: no >Severity: serious >Priority: medium >Responsible: freebsd-usb >State: open >Quarter: >Keywords: >Date-Required: >Class: sw-bug >Submitter-Id: current-users >Arrival-Date: Tue Nov 13 22:00:00 UTC 2012 >Closed-Date: >Last-Modified: >Originator: Heikki Suonsivu >Release: FreeBSD 9.1-PRERELEASE amd64 >Organization: bbnetworks.net >Environment: System: FreeBSD lelu.suonsivu.net 9.1-PRERELEASE FreeBSD 9.1-PRERELEASE #2: Sat Nov 10 03:23:41 EET 2012 root@lelu.suonsivu.net:/usr/obj/usr/src/sys/HSU9 amd64 dmesg: Copyright (c) 1992-2012 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 9.1-PRERELEASE #2: Sat Nov 10 03:23:41 EET 2012 root@lelu.suonsivu.net:/usr/obj/usr/src/sys/HSU9 amd64 CPU: AMD Phenom(tm) II X4 965 Processor (3411.49-MHz K8-class CPU) Origin = "AuthenticAMD" Id = 0x100f43 Family = 0x10 Model = 0x4 Stepping = 3 Features=0x178bfbff Features2=0x802009 AMD Features=0xee500800 AMD Features2=0x837ff TSC: P-state invariant real memory = 4294967296 (4096 MB) avail memory = 3832315904 (3654 MB) Event timer "LAPIC" quality 400 ACPI APIC Table: <070512 APIC1458> FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs FreeBSD/SMP: 1 package(s) x 4 core(s) cpu0 (BSP): APIC ID: 0 cpu1 (AP): APIC ID: 1 cpu2 (AP): APIC ID: 2 cpu3 (AP): APIC ID: 3 ioapic0 irqs 0-23 on motherboard kbd1 at kbdmux0 ctl: CAM Target Layer loaded acpi0: <070512 XSDT1458> on motherboard acpi0: Power Button (fixed) acpi0: reservation of fee00000, 1000 (3) failed acpi0: reservation of ffb80000, 80000 (3) failed acpi0: reservation of fec10000, 20 (3) failed acpi0: reservation of fed80000, 1000 (3) failed acpi0: reservation of 0, a0000 (3) failed acpi0: reservation of 100000, cfe00000 (3) failed cpu0: on acpi0 cpu1: on acpi0 cpu2: on acpi0 cpu3: on acpi0 attimer0: port 0x40-0x43 irq 0 on acpi0 Timecounter "i8254" frequency 1193182 Hz quality 0 Event timer "i8254" frequency 1193182 Hz quality 100 atrtc0: port 0x70-0x71 irq 8 on acpi0 Event timer "RTC" frequency 32768 Hz quality 0 hpet0: iomem 0xfed00000-0xfed003ff on acpi0 Timecounter "HPET" frequency 14318180 Hz quality 950 Event timer "HPET" frequency 14318180 Hz quality 450 Event timer "HPET1" frequency 14318180 Hz quality 450 Event timer "HPET2" frequency 14318180 Hz quality 450 Timecounter "ACPI-fast" frequency 3579545 Hz quality 900 acpi_timer0: <32-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0 acpi_ec0: port 0x62,0x66 on acpi0 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 pcib1: at device 1.0 on pci0 pci1: on pcib1 vgapci0: port 0xb000-0xb0ff mem 0xd0000000-0xdfffffff,0xfe6f0000-0xfe6fffff,0xfe500000-0xfe5fffff irq 18 at device 5.0 on pci1 hdac0: mem 0xfe6e8000-0xfe6ebfff irq 19 at device 5.1 on pci1 pcib2: irq 17 at device 9.0 on pci0 pci2: on pcib2 xhci0: mem 0xfe7fe000-0xfe7fffff irq 17 at device 0.0 on pci2 xhci0: 32 byte context size. usbus0 on xhci0 pcib3: irq 18 at device 10.0 on pci0 pci3: on pcib3 atapci0: port 0xcc00-0xcc07,0xc880-0xc883,0xc800-0xc807,0xc480-0xc483,0xc400-0xc40f mem 0xfe8ffc00-0xfe8ffdff irq 18 at device 0.0 on pci3 ahci0: at channel -1 on atapci0 ahci0: AHCI v1.10 with 1 3Gbps ports, Port Multiplier supported ahcich0: at channel 0 on ahci0 ata2: at channel 0 on atapci0 ahci1: port 0xa000-0xa007,0x9000-0x9003,0x8000-0x8007,0x7000-0x7003,0x6000-0x600f mem 0xfe4ffc00-0xfe4fffff irq 19 at device 17.0 on pci0 ahci1: AHCI v1.20 with 6 6Gbps ports, Port Multiplier supported ahcich1: at channel 0 on ahci1 ahcich2: at channel 1 on ahci1 ahcich3: at channel 2 on ahci1 ahcich4: at channel 3 on ahci1 ahcich5: at channel 4 on ahci1 ahcich6: at channel 5 on ahci1 ohci0: mem 0xfe4fe000-0xfe4fefff irq 18 at device 18.0 on pci0 usbus1 on ohci0 ehci0: mem 0xfe4ff800-0xfe4ff8ff irq 17 at device 18.2 on pci0 usbus2: EHCI version 1.0 usbus2 on ehci0 ohci1: mem 0xfe4fd000-0xfe4fdfff irq 18 at device 19.0 on pci0 usbus3 on ohci1 ehci1: mem 0xfe4ff400-0xfe4ff4ff irq 17 at device 19.2 on pci0 usbus4: EHCI version 1.0 usbus4 on ehci1 pci0: at device 20.0 (no driver attached) hdac1: mem 0xfe4f4000-0xfe4f7fff irq 16 at device 20.2 on pci0 isab0: at device 20.3 on pci0 isa0: on isab0 pcib4: at device 20.4 on pci0 pci4: on pcib4 fwohci0: port 0xdc00-0xdc7f mem 0xfe9fb800-0xfe9fbfff irq 22 at device 7.0 on pci4 fwohci0: OHCI version 1.10 (ROM=1) fwohci0: No. of Isochronous channels is 4. fwohci0: EUI64 00:1e:8c:00:00:e4:87:16 fwohci0: Phy 1394a available S400, 2 ports. fwohci0: Link S400, max_rec 2048 bytes. firewire0: on fwohci0 fwe0: on firewire0 if_fwe0: Fake Ethernet address: 02:1e:8c:e4:87:16 fwe0: Ethernet address: 02:1e:8c:e4:87:16 fwip0: on firewire0 fwip0: Firewire address: 00:1e:8c:00:00:e4:87:16 @ 0xfffe00000000, S400, maxrec 2048 dcons_crom0: on firewire0 dcons_crom0: bus_addr 0xc76d0000 fwohci0: Initiate bus reset fwohci0: fwohci_intr_core: BUS reset fwohci0: fwohci_intr_core: node_id=0x00000000, SelfID Count=1, CYCLEMASTER mode ohci2: mem 0xfe4fc000-0xfe4fcfff irq 18 at device 20.5 on pci0 usbus5 on ohci2 pcib5: at device 21.0 on pci0 pci5: on pcib5 re0: port 0xe800-0xe8ff mem 0xfdfff000-0xfdffffff,0xfdff8000-0xfdffbfff irq 16 at device 0.0 on pci5 re0: Using 1 MSI-X message re0: Chip rev. 0x2c000000 re0: MAC rev. 0x00000000 miibus0: on re0 rgephy0: PHY 1 on miibus0 rgephy0: none, 10baseT, 10baseT-FDX, 10baseT-FDX-flow, 100baseTX, 100baseTX-FDX, 100baseTX-FDX-flow, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, 1000baseT-FDX-flow, 1000baseT-FDX-flow-master, auto, auto-flow re0: Ethernet address: f4:6d:04:15:87:ff ohci3: mem 0xfe4f3000-0xfe4f3fff irq 18 at device 22.0 on pci0 usbus6 on ohci3 ehci2: mem 0xfe4ff000-0xfe4ff0ff irq 17 at device 22.2 on pci0 usbus7: EHCI version 1.0 usbus7 on ehci2 acpi_button0: on acpi0 uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 orm0: at iomem 0xd3000-0xd5fff on isa0 sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 atkbdc0: at port 0x60,0x64 on isa0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] ppc0: cannot reserve I/O port range acpi_throttle0: on cpu0 hwpstate0: on cpu0 Timecounters tick every 1.000 msec firewire0: 1 nodes, maxhop <= 0 cable IRM irm(0) (me) firewire0: bus manager 0 IP Filter: v4.1.28 initialized. Default = pass all, Logging = enabled ipfw2 (+ipv6) initialized, divert enabled, nat enabled, rule-based forwarding enabled, default to accept, logging disabled DUMMYNET 0 with IPv6 initialized (100409) load_dn_sched dn_sched FIFO loaded load_dn_sched dn_sched PRIO loaded load_dn_sched dn_sched QFQ loaded load_dn_sched dn_sched RR loaded load_dn_sched dn_sched WF2Q+ loaded hdacc0: at cad 0 on hdac0 hdaa0: at nid 1 on hdacc0 pcm0: at nid 3 on hdaa0 hdacc1: at cad 0 on hdac1 hdaa1: at nid 1 on hdacc1 pcm1: at nid 20,22,21,23 and 24,26 on hdaa1 pcm2: at nid 27 and 25 on hdaa1 pcm3: at nid 30 on hdaa1 pcm4: at nid 17 on hdaa1 usbus0: 5.0Gbps Super Speed USB v3.0 usbus1: 12Mbps Full Speed USB v1.0 usbus2: 480Mbps High Speed USB v2.0 usbus3: 12Mbps Full Speed USB v1.0 usbus4: 480Mbps High Speed USB v2.0 usbus5: 12Mbps Full Speed USB v1.0 usbus6: 12Mbps Full Speed USB v1.0 usbus7: 480Mbps High Speed USB v2.0 ugen0.1: <0x1033> at usbus0 uhub0: <0x1033 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0 ugen1.1: at usbus1 uhub1: on usbus1 ugen2.1: at usbus2 uhub2: on usbus2 ugen3.1: at usbus3 uhub3: on usbus3 ugen4.1: at usbus4 uhub4: on usbus4 ugen5.1: at usbus5 uhub5: on usbus5 ugen6.1: at usbus6 uhub6: on usbus6 ugen7.1: at usbus7 uhub7: on usbus7 uhub5: 2 ports with 2 removable, self powered uhub6: 4 ports with 4 removable, self powered uhub1: 5 ports with 5 removable, self powered uhub3: 5 ports with 5 removable, self powered uhub0: 4 ports with 4 removable, self powered ada0 at ahcich1 bus 0 scbus2 target 0 lun 0 ada0: ATA-8 SATA 2.x device ada0: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes) ada0: Command Queueing enabled ada0: 2861588MB (5860533168 512 byte sectors: 16H 63S/T 16383C) ada0: Previously was known as ad8 ada1 at ahcich2 bus 0 scbus3 target 0 lun 0 ada1: ATA-8 SATA 2.x device ada1: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes) ada1: Command Queueing enabled ada1: 1907729MB (3907029168 512 byte sectors: 16H 63S/T 16383C) ada1: Previously was known as ad10 ada2 at ahcich3 bus 0 scbus4 target 0 lun 0 ada2: ATA-8 SATA 2.x device ada2: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes) ada2: Command Queueing enabled ada2: 1907729MB (3907029168 512 byte sectors: 16H 63S/T 16383C) ada2: Previously was known as ad12 ada3 at ahcich4 bus 0 scbus5 target 0 lun 0 ada3: ATA-8 SATA 2.x device ada3: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes) ada3: Command Queueing enabled ada3: 1907729MB (3907029168 512 byte sectors: 16H 63S/T 16383C) ada3: Previously was known as ad14 ada4 at ahcich6 bus 0 scbus7 target 0 lun 0 ada4: ATA-8 SATA 3.x device ada4: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 8192bytes) ada4: Command Queueing enabled ada4: 2861588MB (5860533168 512 byte sectors: 16H 63S/T 16383C) ada4: Previously was known as ad18 SMP: AP CPU #2 Launched! SMP: AP CPU #1 Launched! cd0 at ata2 bus 0 scbus1 target 0 lun 0 cd0: Removable CD-ROM SCSI-0 device cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes) SMP: AP CPU #3 Launched! cd0: Attempt to query device size failed: NOT READY, Medium not present Timecounter "TSC-low" frequency 13326119 Hz quality 800 uhub7: 4 ports with 4 removable, self powered GEOM_MIRROR: Device mirror/leluroot launched (1/2). GEOM_MIRROR: Device leluroot: rebuilding provider ada1p3. GEOM_MIRROR: Device mirror/lelu-root launched (1/1). GEOM_MIRROR: Device mirror/lelu-u launched (2/2). GEOM_JOURNAL: Journal 3960267491: mirror/lelu-root contains data. GEOM_JOURNAL: Journal 3960267491: mirror/lelu-root contains journal. GEOM_JOURNAL: Journal mirror/lelu-root clean. GEOM_JOURNAL: Journal 769953320: mirror/lelu-u contains data. GEOM_JOURNAL: Journal 769953320: mirror/lelu-u contains journal. GEOM_JOURNAL: Journal mirror/lelu-u clean. Root mount waiting for: GJOURNAL GJOURNAL usbus4 usbus2 uhub4: 5 ports with 5 removable, self powered uhub2: 5 ports with 5 removable, self powered ugen2.2: at usbus2 uhub8: on usbus2 uhub8: MTT enabled Root mount waiting for: usbus2 uhub8: 4 ports with 4 removable, self powered Root mount waiting for: usbus2 ugen2.3: at usbus2 uhub9: on usbus2 uhub9: 4 ports with 4 removable, self powered Root mount waiting for: usbus2 ugen2.4: at usbus2 uhub10: on usbus2 uhub10: MTT enabled Root mount waiting for: usbus2 uhub10: 4 ports with 4 removable, self powered ugen2.5: at usbus2 uhub11: on usbus2 uhub11: MTT enabled Root mount waiting for: usbus2 uhub11: 7 ports with 7 removable, self powered Root mount waiting for: usbus2 ugen2.6: at usbus2 ums0: on usbus2 ums0: 5 buttons and [XYZ] coordinates ID=0 Root mount waiting for: usbus2 ugen2.7: at usbus2 ukbd0: on usbus2 kbd2 at ukbd0 usbd_set_config_index: could not read device status: USB_ERR_SHORT_XFER Root mount waiting for: usbus2 ugen2.8: at usbus2 umass0: on usbus2 umass0: 8070i (ATAPI) over Bulk-Only; quirks = 0x0100 umass0:9:0:-1: Attached to scbus9 Trying to mount root from ufs:/dev/mirror/leluroot [rw,async]... cd1 at umass-sim0 bus 0 scbus9 target 0 lun 0 cd1: Removable CD-ROM SCSI-0 device cd1: 40.000MB/s transfers cd1: cd present [278530 x 2048 byte records] usbd_set_config_index: could not read device status: USB_ERR_SHORT_XFER ugen2.9: at usbus2 umass1: on usbus2 umass1: 8070i (ATAPI) over Bulk-Only; quirks = 0x0100 umass1:10:1:-1: Attached to scbus10 cd2 at umass-sim1 bus 1 scbus10 target 0 lun 0 cd2: Removable CD-ROM SCSI-0 device cd2: 40.000MB/s transfers cd2: Attempt to query device size failed: NOT READY, Medium not present - tray closed re0: link state changed to UP >Description: LG external USB DVD-RW fails. ugen2.3: at usbus2, cfg=0 md=HOST spd=HIGH (480Mbps) pwr=SAVE ugen2.9: at usbus2, cfg=0 md=HOST spd=HIGH (480Mbps) pwr=ON Kernel says the following: ugen2.3: at usbus2 uhub9: on usbus2 uhub9: 2 ports with 2 removable, self powered ugen2.9: at usbus2 umass1: on usbus2 umass1: SCSI over Bulk-Only; quirks = 0x0100 umass1:10:1:-1: Attached to scbus10 cd2 at umass-sim1 bus 1 scbus10 target 0 lun 0 cd2: Removable CD-ROM SCSI-0 device cd2: 40.000MB/s transfers cd2: Attempt to query device size failed: NOT READY, Medium not present usb_alloc_device: set address 10 failed (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED usbd_req_re_enumerate: addr=10, set address failed! (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED usbd_req_re_enumerate: addr=10, set address failed! (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED ugen2.10: at usbus2 (disconnected) uhub_reattach_port: could not allocate new device usb_alloc_device: set address 10 failed (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED usbd_req_re_enumerate: addr=10, set address failed! (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED usbd_req_re_enumerate: addr=10, set address failed! (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED ugen2.10: at usbus2 (disconnected) uhub_reattach_port: could not allocate new device usb_alloc_device: set address 10 failed (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED usbd_req_re_enumerate: addr=10, set address failed! (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED usbd_req_re_enumerate: addr=10, set address failed! (USB_ERR_STALLED, ignored) usbd_setup_device_desc: getting device descriptor at addr 10 failed, USB_ERR_STALLED ugen2.10: at usbus2 (disconnected) uhub_reattach_port: could not allocate new device uhub_reattach_port: port 2 reset failed, error=USB_ERR_STALLED uhub_reattach_port: device problem (USB_ERR_STALLED), disabling port 2 ugen2.3: at usbus2 (disconnected) uhub9: at uhub8, port 4, addr 3 (disconnected) ugen2.9: at usbus2 (disconnected) umass1: at uhub9, port 1, addr 9 (disconnected) (cd2:umass-sim1:1:0:0): lost device, 1 refs (cd2:(pass7:umass-sim1:1:umass-sim1:1:0:0:0): removing device entry 0): passdevgonecb: devfs entry is gone When trying to access the drive, device not configured error results. All usbconfig commands become very slow when the drive is plugged in. It seems that some other devices may fail at the same moment (grip ripping a cd on another dvd drive might hang, for example). I have tried some USB quirks UQ_MSC_NO_GETMAXLUN (just a guess) UQ_OPEN_CLEARSTALL (some discussion referred to this) UQ_MSC_FORCE_PROTO_ATAPI (device seems to default to scsi) None of these had any effect, other than last one changing protocol to ATAPI. This device may have worked with past FreeBSD versions. Drive is externally powered, not USB powered. >How-To-Repeat: Seems repeatable on 9-Stable. Has been for at least two months (host was 8.x before that). >Fix: No idea. >Release-Note: >Audit-Trail: >Unformatted: