[FS#1473] USB connects then disconnects later in boot

LEDE Bugs lede-bugs at lists.infradead.org
Sat Apr 7 14:01:04 PDT 2018


A new Flyspray task has been opened.  Details are below. 

User who did this - Marko Bogosavljevic (markobog) 

Attached to Project - OpenWrt/LEDE Project
Summary - USB connects then disconnects later in boot
Task Type - Bug Report
Category - Other
Status - Unconfirmed
Assigned To - 
Operating System - All
Severity - Low
Priority - Very Low
Reported Version - Trunk
Due in Version - Undecided
Due Date - Undecided
Details - The Device that this problem occurs on is **Netgear WNDR3700v3**. I'm using **17.01.04 release of openwrt**.
Long story made short, the issue is that USB connects, then later in startup of the router it disconnects and when fully booted it can't be seen using 'fdisk -l' nor can it be mounted (/dev/sda1)...

Installed packages are the ones included in the build + usb-core and its dependencies.

=====To reproduce this issue:=====
1.Do standard setup the router ( setup wan, setup lan, switch wifi on).
2.Install packages required for usb functionality.
3.Insert usb and reboot.

You will see during boot that usb light flashes few times and then after boot stops flashing/working. That can be confirmed by dmesg.


[    0.000000] Linux version 4.4.92 (buildbot at builds-02.infra.lede-project.org) (gcc version 5.4.0 (LEDE GCC 5.4.0 r3104-41de9a2) ) #0 Tue Oct 17 17:46:20 2017
[    0.000000] CPU0 revision is: 00019740 (MIPS 74Kc)
[    0.000000] bcm47xx: Using bcma bus
[    0.000000] bcma: bus0: Found chip with id 0x4716, rev 0x01 and package 0x0A
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000]   HighMem  empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000] free_area_init_node: node 0, pgdat 8034e4c0, node_mem_map 81000000
[    0.000000]   Normal zone: 128 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 16256
[    0.000000] Kernel command line:  noinitrd console=ttyS0,115200
[    0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 60940K/65536K available (2979K kernel code, 134K rwdata, 368K rodata, 156K init, 294K bss, 4596K reserved, 0K cma-reserved, 0K highmem)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:128
[    0.000000] bcma: bus0: Core 0 found: ChipCommon (manuf 0x4BF, id 0x800, rev 0x1F, class 0x0)
[    0.000000] bcma: bus0: Core 1 found: IEEE 802.11 (manuf 0x4BF, id 0x812, rev 0x11, class 0x0)
[    0.000000] bcma: bus0: Core 2 found: GBit MAC (manuf 0x4BF, id 0x82D, rev 0x00, class 0x0)
[    0.000000] bcma: bus0: Core 3 found: MIPS 74K (manuf 0x4A7, id 0x82C, rev 0x01, class 0x0)
[    0.000000] bcma: bus0: Core 4 found: USB 2.0 Host (manuf 0x4BF, id 0x819, rev 0x04, class 0x0) 
[    0.000000] bcma: bus0: Core 5 found: PCIe (manuf 0x4BF, id 0x820, rev 0x0E, class 0x0)
[    0.000000] bcma: bus0: Core 6 found: DDR1/DDR2 Memory Controller (manuf 0x4BF, id 0x82E, rev 0x01, class 0x0)
[    0.000000] bcma: bus0: Core 7 found: Internal Memory (manuf 0x4BF, id 0x80E, rev 0x07, class 0x0)
[    0.000000] bcma: bus0: Core 8 found: I2S (manuf 0x4BF, id 0x834, rev 0x00, class 0x0)
[    0.000000] bcma: bus0: Found rev 5 PMU (capabilities 0x11242C05)
[    0.000000] bcma: bus0: Found serial flash
[    0.000000] bcma: bus0: Found M25P64 serial flash (size: 8192KiB, blocksize: 0x10000, blocks: 128)
[    0.000000] bcma: bus0: Early bus registered
[    0.000000] MIPS: machine is Unknown Board
[    0.000000] bcm47xx: Setting up vectored interrupts
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7963585194 ns
[    0.000015] sched_clock: 32 bits at 240MHz, resolution 4ns, wraps every 8947848189ns
[    0.000083] Calibrating delay loop... 239.61 BogoMIPS (lpj=1198080)
[    0.070033] pid_max: default: 32768 minimum: 301
[    0.070283] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.070311] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.074095] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.074144] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.075341] NET: Registered protocol family 16
[    0.076346] Can't analyze schedule() prologue at 800077c8
[    0.095859] clocksource: Switched to clocksource MIPS
[    0.097957] NET: Registered protocol family 2
[    0.099548] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.099608] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.099647] TCP: Hash tables configured (established 1024 bind 1024)
[    0.099818] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.099865] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.100398] NET: Registered protocol family 1
[    0.100494] PCI: CLS 0 bytes, default 32
[    0.515000] bcma: bus0: Using SPROM revision 8 provided by platform.
[    0.515042] bcma: bus0: PMU resource config unknown or not needed for device 0x4716
[    0.517109] bcma: bus0: Workarounds unknown or not needed for device 0x4716
[    0.517134] bcma: bus0: Initializing MIPS core...
[    0.517163] bcma: bus0: set_irq: core 0x0812, irq 3 => 3
[    0.517186] bcma: bus0: set_irq: core 0x082d, irq 4 => 4
[    0.517207] bcma: bus0: set_irq: core 0x0819, irq 5 => 5
[    0.517229] bcma: bus0: set_irq: core 0x0820, irq 6 => 6
[    0.517249] bcma: bus0: set_irq: core 0x0800, irq 2 => 2
[    0.517269] bcma: bus0: set_irq: core 0x0834, irq 2 => 2
[    0.517284] bcma: bus0: IRQ reconfiguration done
[    0.517300] bcma: core 0x0800, irq : 2(S)* 3  4  5  6  D  I
[    0.517350] bcma: core 0x0812, irq : 2(S)  3* 4  5  6  D  I
[    0.517392] bcma: core 0x082d, irq : 2(S)  3  4* 5  6  D  I
[    0.517433] bcma: core 0x082c, irq : 2(S)  3  4  5  6  D  I*
[    0.517475] bcma: core 0x0819, irq : 2(S)  3  4  5* 6  D  I
[    0.517517] bcma: core 0x0820, irq : 2(S)  3  4  5  6* D  I
[    0.517558] bcma: core 0x082e, irq : 2(S)  3  4  5  6  D* I
[    0.517599] bcma: core 0x080e, irq : 2(S)  3  4  5  6  D  I*
[    0.517639] bcma: core 0x0834, irq : 2(S)* 3  4  5  6  D  I
[    0.517683] bcma: bus0: PCIEcore in host mode found
[    0.701176] PCI host bridge to bus 0000:00
[    0.701231] pci_bus 0000:00: root bus resource [mem 0x08000000-0x0bffffff]
[    0.701260] pci_bus 0000:00: root bus resource [io  0x0100-0x07ff]
[    0.701289] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    0.701318] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.701400] pci 0000:00:00.0: [14e4:4716] type 00 class 0x060000
[    0.701443] bcma: PCI: Fixing up bridge 0000:00:00.0
[    0.701464] bcma: PCI: Fixing up device 0000:00:00.0
[    0.701528] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x00003fff 64bit]
[    0.701568] pci 0000:00:00.0: reg 0x18: [mem 0x00000000-0x07ffffff 64bit]
[    0.701674] pci 0000:00:00.0: supports D1 D2
[    0.702056] pci 0000:00:00.1: [14e4:0000] type 00 class 0x060000
[    0.702103] bcma: PCI: Fixing up bridge 0000:00:00.1
[    0.702124] bcma: PCI: Fixing up device 0000:00:00.1
[    0.702187] pci 0000:00:00.1: reg 0x10: [mem 0x00000000-0x00003fff 64bit]
[    0.702227] pci 0000:00:00.1: reg 0x18: [mem 0x00000000-0x07ffffff 64bit]
[    0.702325] pci 0000:00:00.1: supports D1 D2
[    0.702763] pci 0000:00:01.0: [14e4:4331] type 00 class 0x028000
[    0.702894] pci 0000:00:01.0: reg 0x10: [mem 0x00000000-0x00003fff 64bit]
[    0.702991] bcma: PCI: Fixing up addresses 0000:00:01.0
[    0.703037] pci 0000:00:01.0: BAR 0: assigned [mem 0x08000000-0x08003fff 64bit]
[    0.703200] pci 0000:00:01.0: supports D1 D2
[    0.703605] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    0.705142] bcma: bus0: Bus registered
[    0.706323] Crashlog allocated RAM at address 0x3f00000
[    0.737420] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.737451] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.742243] io scheduler noop registered
[    0.742278] io scheduler deadline registered (default)
[    0.742710] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.747752] console [ttyS0] disabled
[    0.767993] serial8250.0: ttyS0 at MMIO 0xb8000300 (irq = 2, base_baud = 1250000) is a U6_16550A
[    1.271345] console [ttyS0] enabled
[    1.383454] 7 bcm47xxpart partitions found on MTD device bcm47xxsflash
[    1.390163] Creating 7 MTD partitions on "bcm47xxsflash":
[    1.395669] 0x000000000000-0x000000040000 : "boot"
[    1.404675] 0x000000040000-0x0000007e0000 : "firmware"
[    1.412942] 0x0000007e0000-0x0000007f0000 : "board_data"
[    1.421384] 0x0000007f0000-0x000000800000 : "nvram"
[    1.429424] 0x00000004001c-0x00000004091c : "loader"
[    1.437592] 0x00000004091c-0x000000166000 : "linux"
[    1.445531] 0x000000166000-0x0000007e0000 : "rootfs"
[    1.453634] mtd: device 6 (rootfs) set to be root filesystem
[    1.459556] 1 squashfs-split partitions found on MTD device rootfs
[    1.465900] 0x0000003a0000-0x0000007e0000 : "rootfs_data"
[    1.477763] libphy: Fixed MDIO Bus: probed
[    1.482172] bgmac_bcma bcma0:2: Found PHY addr: 30 (NOREGS)
[    1.491131] libphy: bcma_mdio mii bus: probed
[    1.495592] bgmac_bcma bcma0:2: Support for Roboswitch not implemented
[    1.656300] b53_common: found switch: BCM53115, rev 8
[    1.662752] bgmac_bcma: Broadcom 47xx GBit MAC driver loaded
[    1.669147] bcm47xx-wdt bcm47xx-wdt.0: BCM47xx Watchdog Timer enabled (30 seconds)
[    1.677188] PCI: Enabling device 0000:00:01.0 (0000 -> 0002)
[    1.682952] bcma: PCI: Fixing up device 0000:00:01.0
[    1.688087] bcma: change PCIe max read request size from 512 to 128
[    1.694521] bcma: bus1: Found chip with id 0x4331, rev 0x02 and package 0x08
[    1.701786] bcma: bus1: Core 0 found: ChipCommon (manuf 0x4BF, id 0x800, rev 0x25, class 0x0)
[    1.710541] bcma: bus1: Core 1 found: IEEE 802.11 (manuf 0x4BF, id 0x812, rev 0x1D, class 0x0)
[    1.719387] bcma: bus1: Core 2 found: PCIe (manuf 0x4BF, id 0x820, rev 0x13, class 0x0)
[    1.727658] bcma: bus1: Found rev 10 PMU (capabilities 0x108C260A)
[    1.727690] bcma: bus1: SPROM offset 0x800
[    1.851848] bcma: bus1: Invalid SPROM read from the PCIe card, trying to use fallback SPROM
[    2.046785] bcma: bus1: Using SPROM revision 9 provided by platform.
[    2.046829] bcma: bus1: PMU resource config unknown or not needed for device 0x4331
[    2.057502] bcma: bus1: Bus registered
[    2.063308] NET: Registered protocol family 10
[    2.072711] NET: Registered protocol family 17
[    2.077480] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    2.090356] 8021q: 802.1Q VLAN Support v1.8
[    2.104187] VFS: Mounted root (squashfs filesystem) readonly on device 31:6.
[    2.112500] Freeing unused kernel memory: 156K
[    3.754063] init: Console is alive
[    3.758057] init: - watchdog -
[    4.878998] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    5.017988] usbcore: registered new interface driver usbfs
[    5.023754] usbcore: registered new interface driver hub
[    5.029472] usbcore: registered new device driver usb
[    5.042546] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    5.051645] ehci-platform: EHCI generic platform driver
[    5.057233] ehci-platform ehci-platform.0: EHCI Host Controller
[    5.063363] ehci-platform ehci-platform.0: new USB bus registered, assigned bus number 1
[    5.071819] ehci-platform ehci-platform.0: irq 5, io mem 0x18004000
[    5.095922] ehci-platform ehci-platform.0: USB 2.0 started, EHCI 1.00
[    5.104198] hub 1-0:1.0: USB hub found
[    5.108771] hub 1-0:1.0: 2 ports detected
[    5.117596] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    5.126043] ohci-platform: OHCI generic platform driver
[    5.131512] ohci-platform ohci-platform.0: Generic Platform OHCI controller
[    5.138753] ohci-platform ohci-platform.0: new USB bus registered, assigned bus number 2
[    5.147160] ohci-platform ohci-platform.0: irq 5, io mem 0x18009000
[    5.211640] hub 2-0:1.0: USB hub found
[    5.216336] hub 2-0:1.0: 2 ports detected
[    5.224214] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    5.242340] init: - preinit -
[    5.608070] usb 1-1: new high-speed USB device number 2 using ehci-platform
[    5.864264] random: procd: uninitialized urandom read (4 bytes read, 10 bits of entropy available)
[    5.889247] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    7.865937] bgmac_bcma bcma0:2 eth0: Link is Up - 1Gbps/Full - flow control off
[    7.873468] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   10.273926] jffs2: notice: (342) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   10.303649] mount_root: loading kmods from internal overlay
[   10.724352] kmodloader: loading kernel modules from /tmp/overlay/upper/etc/modules-boot.d/*
[   11.373272] SCSI subsystem initialized
[   11.419230] uhci_hcd: USB Universal Host Controller Interface driver
[   11.454632] usb-storage 1-1:1.0: USB Mass Storage device detected
[   11.462745] scsi host0: usb-storage 1-1:1.0
[   11.468012] usbcore: registered new interface driver usb-storage
[   11.475786] kmodloader: done loading kernel modules from /tmp/overlay/upper/etc/modules-boot.d/*
[   11.882238] block: attempting to load /tmp/jffs_cfg/upper/etc/config/fstab
[   11.892225] block: extroot: not configured
[   11.897883] mount_root: switching to jffs2 overlay
[   11.912369] urandom-seed: Seeding with /etc/urandom.seed
[   12.092045] procd: - early -
[   12.095211] procd: - watchdog -
[   12.831439] scsi 0:0:0:0: Direct-Access     FLASH    Drive SM_USB20   3000 PQ: 0 ANSI: 0 CCS
[   12.845660] sd 0:0:0:0: [sda] 3932160 512-byte logical blocks: (2.01 GB/1.88 GiB)
[   12.866047] sd 0:0:0:0: [sda] Write Protect is off
[   12.871009] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[   12.872568] sd 0:0:0:0: [sda] No Caching mode page found
[   12.878114] sd 0:0:0:0: [sda] Assuming drive cache: write through
[   12.900092]  sda: sda1 sda2
[   12.912389] sd 0:0:0:0: [sda] Attached SCSI removable disk
[   13.408590] procd: - watchdog -
[   13.413238] procd: - ubus -
[   13.575271] random: ubusd: uninitialized urandom read (4 bytes read, 49 bits of entropy available)
[   13.589064] random: ubusd: uninitialized urandom read (4 bytes read, 49 bits of entropy available)
[   13.599754] random: ubusd: uninitialized urandom read (4 bytes read, 49 bits of entropy available)
[   13.609582] random: ubusd: uninitialized urandom read (4 bytes read, 49 bits of entropy available)
[   13.621827] random: ubusd: uninitialized urandom read (4 bytes read, 49 bits of entropy available)
[   13.631285] random: ubusd: uninitialized urandom read (4 bytes read, 49 bits of entropy available)
[   13.641747] random: ubusd: uninitialized urandom read (4 bytes read, 51 bits of entropy available)
[   13.652513] random: ubusd: uninitialized urandom read (4 bytes read, 51 bits of entropy available)
[   13.664938] procd: - init -
[   14.978977] kmodloader: loading kernel modules from /etc/modules.d/*
[   14.994949] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   15.021509] Loading modules backported from Linux version wt-2017-01-31-0-ge882dff19e7f
[   15.029742] Backport generated by backports.git backports-20160324-13-g24da7d3c
[   15.042765] ip_tables: (C) 2000-2006 Netfilter Core Team
[   15.063214] nf_conntrack version 0.5.0 (954 buckets, 3816 max)
[   15.125836] xt_time: kernel timezone is -0000
[   15.219516] PPP generic driver version 2.4.2
[   15.229060] NET: Registered protocol family 24
[   15.251342] b43-phy0: Broadcom 4716 WLAN found (core revision 17)
[   15.258525] b43-phy0: Found PHY: Analog 8, Type 4 (N), Revision 5
[   15.264740] b43-phy0: Found Radio: Manuf 0x17F, ID 0x2056, Revision 7, Version 0
[   15.273031] b43-phy1: Broadcom 4331 WLAN found (core revision 29)
[   15.279362] bcma: bus1: Switched to core: 0x812
[   15.286208] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   15.306006] b43-phy1: Found PHY: Analog 9, Type 7 (HT), Revision 1
[   15.312319] b43-phy1: Found Radio: Manuf 0x17F, ID 0x2059, Revision 0, Version 1
[   15.319890] b43-phy1 warning: 5 GHz band is unsupported on this PHY
[   15.340847] Broadcom 43xx driver loaded [ Features: NL ]
[   15.350173] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   15.476055] kmodloader: done loading kernel modules from /etc/modules.d/*
[   17.239669] random: jshn: uninitialized urandom read (4 bytes read, 81 bits of entropy available)
[   27.045316] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   27.094907] device eth0.1 entered promiscuous mode
[   27.099903] device eth0 entered promiscuous mode
[   27.140638] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   27.388545] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
[   28.346008] bgmac_bcma bcma0:2 eth0: Link is Up - 1Gbps/Full - flow control off
[   28.353541] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   28.530202] br-lan: port 1(eth0.1) entered forwarding state
[   28.536077] br-lan: port 1(eth0.1) entered forwarding state
[   28.541946] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.2: link becomes ready
[   28.616063] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   30.535925] br-lan: port 1(eth0.1) entered forwarding state
[   32.126060] b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07)
[   32.134511] usb 1-1: USB disconnect, device number 2
[   32.437880] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   32.478559] device wlan0 entered promiscuous mode
[   32.546447] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   32.553223] br-lan: port 2(wlan0) entered forwarding state
[   32.559023] br-lan: port 2(wlan0) entered forwarding state
[   34.555927] br-lan: port 2(wlan0) entered forwarding state
[   37.215918] random: nonblocking pool is initialized


More information can be found at the following URL:
https://bugs.openwrt.org/index.php?do=details&task_id=1473



More information about the lede-bugs mailing list