OpenWrt/LEDE Project

  • Status Closed
  • Percent Complete
    100%
  • Task Type Bug Report
  • Category Kernel
  • Assigned To No-one
  • Operating System All
  • Severity High
  • Priority Very Low
  • Reported Version openwrt-18.06
  • Due in Version Undecided
  • Due Date Undecided
  • Private
Attached to Project: OpenWrt/LEDE Project
Opened by Zuzia - 13.08.2018
Last edited by Mathias Kresin - 16.08.2018

FS#1773 - dwc2 on Arcadyan ARV752DPW - The router restarts after inserting the modem E3372h to the usb port

Device problem occurs on Arcadyan ARV752DPW
Software versions of OpenWrt/LEDE release, packages, etc. OpenWrt 18.06-SNAPSHOT r7253-5b1c657 / LuCI openwrt-18.06 branch (git-18.219.52706-a49e4e4)

The router restarts after inserting the modem Huawei E3372h to the usb port. Also router cannot boot up with modem Huawei E3372h.

cat os-release
NAME="OpenWrt"
VERSION="18.06-SNAPSHOT"
ID="openwrt"
ID_LIKE="lede openwrt"
PRETTY_NAME="OpenWrt 18.06-SNAPSHOT"
VERSION_ID="18.06-snapshot"
HOME_URL="http://openwrt.org/"
BUG_URL="http://bugs.openwrt.org/"
SUPPORT_URL="http://forum.lede-project.org/"
BUILD_ID="r7253-5b1c657"
LEDE_BOARD="lantiq/xway"
LEDE_ARCH="mips_24kc"
LEDE_TAINTS=""
LEDE_DEVICE_MANUFACTURER="OpenWrt"
LEDE_DEVICE_MANUFACTURER_URL="http://openwrt.org/"
LEDE_DEVICE_PRODUCT="Generic"
LEDE_DEVICE_REVISION="v0"
LEDE_RELEASE="OpenWrt 18.06-SNAPSHOT r7253-5b1c657"

uname -a
Linux OpenWrt 4.9.119 #0 Fri Aug 10 18:50:45 2018 mips GNU/Linux
[  197.414113] usb 1-1.1: new high-speed USB device number 3 using dwc2
[  198.981703] usb 1-1.1: USB disconnect, device number 3
[  199.357637] usb 1-1.1: new high-speed USB device number 4 using dwc2
[  199.668822] cdc_ether 1-1.1:1.0 eth1: register 'cdc_ether' at usb-1e101000.ifxhcd-1.1, CDC Ethernet Device
[  199.715769] cdc_ether 1-1.1:1.0 eth1: kevent 12 may have been dropped
[  199.726589] Data bus error, epc == 801c9e60, ra == 83102fa0
[  199.730609] Oops[#1]:
[  199.732812] CPU: 0 PID: 3 Comm: ksoftirqd/0 Not tainted 4.9.119 #0
[  199.738806] task: 8382ca60 task.stack: 83844000
[  199.743186] $ 0   : 00000000 805e0000 9ed8839a fff0ffff
[  199.748255] $ 4   : 9ed883ac 82cd9822 000005c8 00000002
[  199.753323] $ 8   : 00010000 00070000 1002ffff ffff000b
[  199.758393] $12   : 00000001 00050010 00000000 00000002
[  199.763461] $16   : 8399d080 00000200 82cd9800 000005ea
[  199.768530] $20   : 00000000 00000006 00000018 0000000a
[  199.773599] $24   : 00000008 83102fe0                  
[  199.778668] $28   : 83844000 83845d90 00000100 83102fa0
[  199.783740] Hi    : 003013b3
[  199.786528] Lo    : bf400000
[  199.789324] epc   : 801c9e60 0x801c9e60
[  199.793088] ra    : 83102fa0 0x83102fa0 [dwc2@83100000+0x11440]
[  199.798775] Status: 1100fc03	KERNEL EXL IE 
[  199.802835] Cause : 0080001c (ExcCode 07)
[  199.806721] PrId  : 00019641 (MIPS 24KEc)
[  199.810599] Modules linked in: rt2800pci rt2800mmio rt2800lib rt2x00pci rt2x00mmio rt2x00lib pppoe nf_conntrack_ipv6 mac80211 iptable_nat ipt_REJECT ipt_MASQUERADE cfg80211 cdc_ether xt_time xt_tcpudp xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG xt_CT usbnet pppox ppp_async nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack iptable_mangle iptable_filter ip_tables drv_vmmc crc_itu_t crc_ccitt compat drv_dsl_cpe_api ledtrig_usbport drv_mei_cpe ip6t_REJECT nf_reject_ipv6 nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables x_tables ehci_platform pppoatm ppp_generic slhc ehci_hcd br2684 atm drv_tapi eeprom_93cx6 mii drv_ifxos dwc2 gpio_button_hotplug
Process ksoftirqd/0 (pid: 3, threadinfo=83844000, task=8382ca60, tls=00000000)
[  199.889334] Stack : 00000000 8399d080 ffffff7d 83b56a00 00000000 9ed8839a 82c96460 8399d080
[  199.897442]         ffffff7d 83b56a00 00000000 8028ff48 00000000 00000000 8048e240 001e0040
[  199.905552]         82c96460 83b56ae0 83b56ae4 83845df8 00010000 80290518 1100fc00 ffff00fe
[  199.913662]         00000001 82c96564 8399d214 82d03814 83b56aec 8048c65c 80486860 8002a8b0
[  199.921772]         00000002 805e3e64 00000042 805e0000 00000005 805e3e78 00000010 805e0000
[  199.929882]         ...
[  199.932247] Call Trace:[  199.934540] [<8028ff48>] 0x8028ff48
[  199.937898] [<80290518>] 0x80290518
[  199.941276] [<8002a8b0>] 0x8002a8b0
[  199.944600] [<8002a0e0>] 0x8002a0e0
[  199.948028] [<8002a238>] 0x8002a238
[  199.951357] [<80040698>] 0x80040698
[  199.954735] [<80044b98>] 0x80044b98
[  199.958130] [<80044a20>] 0x80044a20
[  199.961552] [<80044a20>] 0x80044a20
[  199.964873] [<80040ae4>] 0x80040ae4
[  199.968327] [<800409cc>] 0x800409cc
[  199.971648] [<80009798>] 0x80009798
[  199.975016] 
[  199.976433] Code: ac880000  ac890004  ac8a0008 <ac8b000c> 24840010  14d8fff0  00000000  10c00019  30d80003 
[  199.985893] 
[  199.987551] ---[ end trace da123a17ded3b9cb ]---
[  199.996403] Kernel panic - not syncing: Fatal exception in interrupt
[  200.003378] Rebooting in 3 seconds..
 


Closed by  Mathias Kresin
16.08.2018 21:36
Reason for closing:  Duplicate
Additional comments about closing:  

 FS#1367 

Project Manager
Mathias Kresin commented on 13.08.2018 19:16

Unfortunately release image have the debug information stripped, which make the stack trace not that useful.

Would it be possible that you try a master/snapshot image or build an 18.06 image with the following options set:

CONFIG_KERNEL_KALLSYMS=y
CONFIG_COLLECT_KERNEL_DEBUG=y
Zuzia commented on 14.08.2018 20:02

Thank you for your comments. Following your advice, I get a new crash log with OpenWrt 18.06-SNAPSHOT, r7254-75e4d2d18c:

## Booting kernel from Legacy Image at b0020000 ...
   Image Name:   MIPS OpenWrt Linux-4.9.119
   Created:      2018-08-13  13:10:30 UTC
   Image Type:   MIPS Linux Kernel Image (lzma compressed)
   Data Size:    1511962 Bytes =  1.4 MB
   Load Address: 80002000
   Entry Point:  80002000
   Verifying Checksum ... OK
   Uncompressing Kernel Image ... OK

Starting kernel ...

[    0.000000] Linux version 4.9.119 (zuzia@poland) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7254-75e4d2d18c) ) #0 Mon Aug 13 13:10:30 2018
[    0.000000] SoC: Danube rev 1.5
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019641 (MIPS 24KEc)
[    0.000000] MIPS: machine is Arcor 802
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Primary instruction cache 16kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 16kB, 4-way, VIPT, no aliases, linesize 32 bytes
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
[    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] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 16256
[    0.000000] Kernel command line: console=ttyLTQ0,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=00000008
[    0.000000] Readback ErrCtl register=00000008
[    0.000000] Memory: 58848K/65536K available (3552K kernel code, 159K rwdata, 880K rodata, 1216K init, 211K bss, 6688K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:256
[    0.000000] CPU Clock: 333MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 11467562657 ns
[    0.000019] sched_clock: 32 bits at 166MHz, resolution 6ns, wraps every 12884901885ns
[    0.007965] Calibrating delay loop... 221.18 BogoMIPS (lpj=442368)
[    0.050611] pid_max: default: 32768 minimum: 301
[    0.055591] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.062140] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.078928] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.088634] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.094890] pinctrl core: initialized pinctrl subsystem
[    0.101163] NET: Registered protocol family 16
[    0.113577] pinctrl-xway 1e100b10.pinmux: Init done
[    0.120161] dma-xway 1e104100.dma: Init done - hw rev: 3, ports: 5, channels: 20
[    0.188816] PCI host bridge /fpi@10000000/pci@E105400 ranges:
[    0.194502]  MEM 0x0000000018000000..0x0000000019ffffff
[    0.199791]   IO 0x000000001ae00000..0x000000001affffff
[    0.253893] usbcore: registered new interface driver usbfs
[    0.259512] usbcore: registered new interface driver hub
[    0.264941] usbcore: registered new device driver usb
[    0.270472] PCI host bridge to bus 0000:00
[    0.274526] pci_bus 0000:00: root bus resource [mem 0x18000000-0x19ffffff]
[    0.281451] pci_bus 0000:00: root bus resource [io  0x1ae00000-0x1affffff]
[    0.288392] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    0.295247] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.304135] pci 0000:00:0e.0: BAR 0: assigned [mem 0x18000000-0x1800ffff]
[    0.312203] clocksource: Switched to clocksource MIPS
[    0.319737] NET: Registered protocol family 2
[    0.325732] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.332693] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.339082] TCP: Hash tables configured (established 1024 bind 1024)
[    0.345677] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.351500] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.358184] NET: Registered protocol family 1
[    0.376115] gptu: totally 6 16-bit timers/counters
[    0.381088] gptu: misc_register on minor 63
[    0.385227] gptu: succeeded to request irq 126
[    0.389730] gptu: succeeded to request irq 127
[    0.394243] gptu: succeeded to request irq 128
[    0.398757] gptu: succeeded to request irq 129
[    0.403271] gptu: succeeded to request irq 130
[    0.407785] gptu: succeeded to request irq 131
[    0.419011] lantiq,vmmc 1f103000.vmmc: requested GPIO 465
[    0.424368] lantiq,vmmc 1f103000.vmmc: reserved 1MB at 0x03a00000
[    0.432102] Crashlog allocated RAM at address 0x3f00000
[    0.440780] workingset: timestamp_bits=30 max_order=14 bucket_order=0
[    0.462714] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.468489] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.504362] io scheduler noop registered
[    0.508128] io scheduler deadline registered (default)
[    0.514423] 1e100c00.serial: ttyLTQ0 at MMIO 0x1e100c00 (irq = 112, base_baud = 0) is a lantiq,asc
[    0.523396] console [ttyLTQ0] enabled
[    0.523396] console [ttyLTQ0] enabled
[    0.530495] bootconsole [early0] disabled
[    0.530495] bootconsole [early0] disabled
[    0.539815] lantiq nor flash device: 00800000 at 10000000
[    0.544096] ltq_nor: Found 1 x16 devices at 0x0 in 16-bit bank. Manufacturer ID 0x0000c2 Chip ID 0x0022cb
[    0.553053] Amd/Fujitsu Extended Query Table at 0x0040
[    0.558053]   Amd/Fujitsu Extended Query version 1.1.
[    0.562916] number of CFI chips: 1
[    0.566274] 4 fixed-partitions partitions found on MTD device ltq_nor
[    0.572463] Creating 4 MTD partitions on "ltq_nor":
[    0.577201] 0x000000000000-0x000000010000 : "uboot"
[    0.588366] 0x000000010000-0x000000020000 : "uboot_env"
[    0.595986] 0x000000020000-0x0000007f0000 : "firmware"
[    0.644902] 2 uimage-fw partitions found on MTD device firmware
[    0.649357] 0x000000020000-0x00000019125a : "kernel"
[    0.657266] 0x00000019125a-0x0000007f0000 : "rootfs"
[    0.664684] mtd: device 4 (rootfs) set to be root filesystem
[    0.668990] 1 squashfs-split partitions found on MTD device rootfs
[    0.674886] 0x000000310000-0x0000007f0000 : "rootfs_data"
[    0.684023] 0x0000007f0000-0x000000800000 : "board_config"
[    0.695500] libphy: Fixed MDIO Bus: probed
[    0.719538] libphy: ltq_mii: probed
[    0.822409] Registering RTL8306SDM switch with Chip ID: 0x5988, version: 0x0000
[    0.828293] Realtek RTL8306S 1e180000.etop-ffffffff:00: attached PHY driver [Realtek RTL8306S] (mii_bus:phy_addr=1e180000.etop-ffffffff:00, irq=-1)
[    0.843065] wdt 1f8803f0.watchdog: Init done
[    0.851023] NET: Registered protocol family 17
[    0.854182] 8021q: 802.1Q VLAN Support v1.8
[    0.874142] OF: fdt:not creating '/sys/firmware/fdt': CRC check fa[    0.890880] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
[    0.904084] Freeing unused kernel memory: 1216K
[    0.907185] This architecture does not have kernel memory protection.
[    1.140245] random: fast init done
[    2.785851] init: Console is alive
[    2.788377] init: - watchdog -
[    4.643681] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    4.954702] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    4.962226] ehci-platform: EHCI generic platform driver
[    4.974557] dwc2 1e101000.ifxhcd: requested GPIO 464
[    5.014455] dwc2 1e101000.ifxhcd: DWC OTG Controller
[    5.018076] dwc2 1e101000.ifxhcd: new USB bus registered, assigned bus number 1
[    5.025138] dwc2 1e101000.ifxhcd: irq 62, io mem 0x00000000
[    5.032700] hub 1-0:1.0: USB hub found
[    5.035748] hub 1-0:1.0: 1 port detected
[    5.041367] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    5.051535] init: - preinit -
[    6.265642] dwc2 1e101000.ifxhcd: Mode Mismatch Interrupt: currently in Host mode
[    6.271665] dwc2 1e101000.ifxhcd: Mode Mismatch Interrupt: currently in Host mode
[    6.572366] usb 1-1: new high-speed USB device number 2 using dwc2
[    6.577341] dwc2 1e101000.ifxhcd: Mode Mismatch Interrupt: currently in Host mode
[    6.584362] dwc2 1e101000.ifxhcd: Mode Mismatch Interrupt: currently in Host mode
[    6.638878] random: procd: uninitialized urandom read (4 bytes read)
Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
[    6.802152] hub 1-1:1.0: USB hub found
[    6.804728] hub 1-1:1.0: 4 ports detected
[    7.096323] usb 1-1.3: new high-speed USB device number 3 using dwc2
[    7.724231] random: crng init done
[   10.056513] jffs2: notice: (372) 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.074968] mount_root: switching to jffs2 overlay
[   10.142685] urandom-seed: Seeding with /etc/urandom.seed
[   10.552775] procd: - early -
[   10.554524] procd: - watchdog -
[   11.364059] procd: - watchdog -
[   11.366851] procd: - ubus -
[   12.157627] procd: - init -
Please press Enter to activate this console.
[   14.242444] kmodloader: loading kernel modules from /etc/modules.d/*
[   14.329503] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[   14.336027] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[   14.421644] nf_conntrack version 0.5.0 (1024 buckets, 4096 max)
[   14.758447] xt_time: kernel timezone is -0000
[   14.775099] usbcore: registered new interface driver cdc_ether
[   14.892419] ip_tables: (C) 2000-2006 Netfilter Core Team
[   15.187766] PCI: Enabling device 0000:00:0e.0 (0000 -> 0002)
[   15.193423] rt2800pci 0000:00:0e.0: loaded eeprom from mtd device "board_config"
[   15.199354] ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 2860, rev 0103 detected
[   15.206923] ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 0001 detected
[   15.336672] kmodloader: done loading kernel modules from /etc/modules.d/*
[   34.957583] br-lan: port 1(eth0.1) entered blocking state
[   34.961574] br-lan: port 1(eth0.1) entered disabled state
[   34.967645] device eth0.1 entered promiscuous mode
[   34.971458] device eth0 entered promiscuous mode
[   35.506236] br-wan: port 1(eth0.2) entered blocking state
[   35.510257] br-wan: port 1(eth0.2) entered disabled state
[   35.516422] device eth0.2 entered promiscuous mode
[   35.965111] br-lan: port 1(eth0.1) entered blocking state
[   35.969127] br-lan: port 1(eth0.1) entered forwarding state
[   35.974913] br-wan: port 1(eth0.2) entered blocking state
[   35.979762] br-wan: port 1(eth0.2) entered forwarding state
[  520.520314] usb 1-1.1: new high-speed USB device number 4 using dwc2
[  521.830694] usb 1-1.1: USB disconnect, device number 4
[  522.568352] usb 1-1.1: new high-speed USB device number 5 using dwc2
[  522.864640] cdc_ether 1-1.1:1.0 eth1: register 'cdc_ether' at usb-1e101000.ifxhcd-1.1, CDC Ethernet Device, 0c:5b:8f:77:6a:64
[  522.886161] cdc_ether 1-1.1:1.0 eth1: kevent 12 may have been dropped
[  522.894049] cdc_ether 1-1.1:1.0 eth1: kevent 11 may have been dropped
[  522.910020] cdc_ether 1-1.1:1.0 eth1: kevent 11 may have been dropped
[  522.931905] CPU 0 Unable to handle kernel paging request at virtual address 00001000, epc == 80010220, ra == 80009840
[  522.941054] Oops[#1]:
[  522.943048] CPU: 0 PID: 1363 Comm: usbmode Not tainted 4.9.119 #0
[  522.948955] task: 839c4f90 task.stack: 83336000
[  522.953338] $ 0   : 00000000 76fbb020 7701bc38 00000100
[  522.958408] $ 4   : 83337f28 7fdbc980 76fbb020 00000001
[  522.963476] $ 8   : 1100fc01 1000001f 00051eb8 00000000
[  522.968545] $12   : 00000000 00000000 0047ae14 7c000000
[  522.973614] $16   : 76fbb020 76ffcf30 00000002 00400ee1
[  522.978683] $20   : 7709d000 7709d000 7709d000 00000000
[  522.983752] $24   : 00000000 7701bc38                  
[  522.988820] $28   : 83336000 83337f28 00000000 80009840
[  522.993890] Hi    : 00000000
[  522.996677] Lo    : 00000100
[  522.999488] epc   : 80010220 do_ri+0x14/0x5dc
[  523.003717] ra    : 80009840 ret_from_exception+0x0/0x10
[  523.008843] Status: 1100fc03	KERNEL EXL IE 
[  523.012902] Cause : 00800008 (ExcCode 02)
[  523.016784] BadVA : 00001000
[  523.019574] PrId  : 00019641 (MIPS 24KEc)
[  523.023455] Modules linked in: rt2800pci rt2800mmio rt2800lib rt2x00pci rt2x00mmio rt2x00lib mac80211 iptable_nat iptable_mangle iptable_filter ipt_REJECT ipt_MASQUERADE ip_tables cfg80211 cdc_ether xt_time xt_tcpudp xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG x_tables usbnet nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_log_common nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack crc_itu_t crc_ccitt compat ledtrig_usbport eeprom_93cx6 dwc2 ehci_platform ehci_hcd gpio_button_hotplug mii
[  523.075596] Process usbmode (pid: 1363, threadinfo=83336000, task=839c4f90, tls=770a8dc0)
[  523.083520] Stack : 00000001 77ad0900 77ae9030 00000000 00000068 00000007 770a1e04 77024cb8
[  523.091629]         00000000 7fdbc960 7701bc38 76f9b020 76ffcf30 7fdbc980 76fbb020 00000001
[  523.099739]         80808080 76ffcf39 00051eb8 00000000 00000000 00000000 0047ae14 7c000000
[  523.107849]         76fbb020 76ffcf30 00000002 00400ee1 7709d000 7709d000 7709d000 00000000
[  523.115959]         00000000 7701bc38 76f9f4e1 00000000 76fbb020 7fdbc880 00000000 76fa1313
[  523.124069]         ...
[  523.126438] Call Trace:
[  523.128821] [<80010220>] do_ri+0x14/0x5dc
[  523.132715] [<80009840>] ret_from_exception+0x0/0x10
[  523.137508] Code: 04000108  00000000  00900800 <80001000> 00000100  00440044  00224002  08100000  400a0000 
[  523.146964] 
[  523.148617] ---[ end trace 5a26bf65455c47e4 ]---
[  523.157525] Kernel panic - not syncing: Fatal exception
[  523.163163] Rebooting in 3 seconds..
root@OpenWrt:~# cat /sys/kernel/debug/usb/devices

T:  Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=480  MxCh= 1
B:  Alloc=  0/800 us ( 0%), #Int=  1, #Iso=  0
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=01 MxPS=64 #Cfgs=  1
P:  Vendor=1d6b ProdID=0002 Rev= 4.09
S:  Manufacturer=Linux 4.9.119 dwc2_hsotg
S:  Product=DWC OTG Controller
S:  SerialNumber=1e101000.ifxhcd
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=  0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   4 Ivl=256ms

T:  Bus=01 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#=  2 Spd=480  MxCh= 4
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=02 MxPS=64 #Cfgs=  1
P:  Vendor=0424 ProdID=2514 Rev= 0.00
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=  2mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=01 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   1 Ivl=256ms
I:* If#= 0 Alt= 1 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=02 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   1 Ivl=256ms

T:  Bus=01 Lev=02 Prnt=02 Port=00 Cnt=01 Dev#=  4 Spd=480  MxCh= 0
D:  Ver= 2.10 Cls=00(>ifc ) Sub=00 Prot=00 MxPS=64 #Cfgs=  1
P:  Vendor=12d1 ProdID=1f01 Rev= 1.02
S:  Manufacturer=HUAWEI_MOBILE
S:  Product=HUAWEI_MOBILE
S:  SerialNumber=0123456789ABCDEF
C:* #Ifs= 1 Cfg#= 1 Atr=80 MxPwr=  2mA
I:* If#= 0 Alt= 0 #EPs= 2 Cls=08(stor.) Sub=06 Prot=50 Driver=(none)
E:  Ad=81(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=01(O) Atr=02(Bulk) MxPS= 512 Ivl=125us
Project Manager
Mathias Kresin commented on 15.08.2018 21:40

Dammed, you're already using kernel 4.9.119 which includes 7ff1861f49e64da20a9b9451ebbdc9bbf68b8a4b. I would have though this one fixes your issue.

It might be that the issue is fixed in master with kernel 4.14. There were quite some usb changes in lantiq with the switch to kernel 4.14.

Beside of that, I've no idea what the reason for the crash could be.

Zuzia commented on 16.08.2018 14:08

Module dwc2 on kernel 4.14 (SNAPSHOT, r7847-03e5dcbf10) doesn't work - cannot be initialized:

root@OpenWrt:/etc# uname -a
Linux OpenWrt 4.14.62 #0 Mon Aug 13 19:05:46 2018 mips GNU/Linux
root@OpenWrt:/etc# cat /sys/kernel/debug/usb/devices
root@OpenWrt:/etc#

[    4.979434] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    5.294663] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    5.302269] ehci-platform: EHCI generic platform driver
[    5.315235] dwc2 1e101000.usb: 1e101000.usb supply vusb_d not found, using dummy regulator
[    5.322225] dwc2 1e101000.usb: 1e101000.usb supply vusb_a not found, using dummy regulator
[    5.330418] dwc2 1e101000.usb: dwc2_core_reset() HANG! Soft Reset GRSTCTL=80000001
[    5.474364] dwc2 1e101000.usb: DWC OTG Controller
[    5.477641] dwc2 1e101000.usb: new USB bus registered, assigned bus number 1
[    5.484549] dwc2 1e101000.usb: irq 62, io mem 0x1e101000
[    5.489763] dwc2 1e101000.usb: startup error -517
[    5.494256] dwc2 1e101000.usb: USB bus 1 deregistered
[    5.499185] dwc2 1e101000.usb: dwc2_hcd_init() FAILED, returning -517
[    5.507713] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Zuzia commented on 16.08.2018 15:38

Same behaviour with latest snapshot r7865-d74d6c4, USB not working.

## Booting kernel from Legacy Image at b0020000 ...
   Image Name:   MIPS OpenWrt Linux-4.14.63
   Created:      2018-08-15  20:32:07 UTC
   Image Type:   MIPS Linux Kernel Image (lzma compressed)
   Data Size:    1897075 Bytes =  1.8 MB
   Load Address: 80002000
   Entry Point:  80002000
   Verifying Checksum ... OK
   Uncompressing Kernel Image ... OK

Starting kernel ...

[    0.000000] Linux version 4.14.63 (buildbot@crazyhorse) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7865-d74d6c4)) #0 Wed Aug 15 20:32:07 2018
[    0.000000] SoC: Danube rev 1.5
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019641 (MIPS 24KEc)
[    0.000000] MIPS: machine is Arcor 802
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Primary instruction cache 16kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 16kB, 4-way, VIPT, no aliases, linesize 32 bytes
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
[    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] random: get_random_bytes called from start_kernel+0x8c/0x47c with crng_init=0
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 16256
[    0.000000] Kernel command line: console=ttyLTQ0,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=00000008
[    0.000000] Readback ErrCtl register=00000008
[    0.000000] Memory: 57552K/65536K available (4593K kernel code, 190K rwdata, 1060K rodata, 1244K init, 221K bss, 7984K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS: 256
[    0.000000] CPU Clock: 333MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 11467562657 ns
[    0.000016] sched_clock: 32 bits at 166MHz, resolution 6ns, wraps every 12884901885ns
[    0.007982] Calibrating delay loop... 221.18 BogoMIPS (lpj=442368)
[    0.054262] pid_max: default: 32768 minimum: 301
[    0.059373] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.065929] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.083019] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.092709] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.099041] pinctrl core: initialized pinctrl subsystem
[    0.109721] NET: Registered protocol family 16
[    0.123783] pinctrl-xway 1e100b10.pinmux: Init done
[    0.130524] dma-xway 1e104100.dma: Init done - hw rev: 3, ports: 5, channels: 20
[    0.149770] PCI host bridge /fpi@10000000/pci@e105400 ranges:
[    0.155482]  MEM 0x0000000018000000..0x0000000019ffffff
[    0.160754]   IO 0x000000001ae00000..0x000000001affffff
[    0.224451] usbcore: registered new interface driver usbfs
[    0.230080] usbcore: registered new interface driver hub
[    0.235513] usbcore: registered new device driver usb
[    0.241196] PCI host bridge to bus 0000:00
[    0.245253] pci_bus 0000:00: root bus resource [mem 0x18000000-0x19ffffff]
[    0.252179] pci_bus 0000:00: root bus resource [io  0x1ae00000-0x1affffff]
[    0.259127] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    0.265977] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.274831] pci 0000:00:0e.0: BAR 0: assigned [mem 0x18000000-0x1800ffff]
[    0.293111] clocksource: Switched to clocksource MIPS
[    0.300750] NET: Registered protocol family 2
[    0.306874] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.313838] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.320227] TCP: Hash tables configured (established 1024 bind 1024)
[    0.326923] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.332744] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.339483] NET: Registered protocol family 1
[    0.350345] gptu: totally 6 16-bit timers/counters
[    0.355290] gptu: misc_register on minor 63
[    0.359433] gptu: succeeded to request irq 126
[    0.363938] gptu: succeeded to request irq 127
[    0.368453] gptu: succeeded to request irq 128
[    0.372966] gptu: succeeded to request irq 129
[    0.377482] gptu: succeeded to request irq 130
[    0.381993] gptu: succeeded to request irq 131
[    0.393268] lantiq,vmmc 1f103000.vmmc: requested GPIO 465
[    0.398618] lantiq,vmmc 1f103000.vmmc: reserved 1MB at 0x03a00000
[    0.406611] Crashlog allocated RAM at address 0x3f00000
[    0.416402] workingset: timestamp_bits=30 max_order=14 bucket_order=0
[    0.435146] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.440920] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.479991] io scheduler noop registered
[    0.483859] io scheduler deadline registered (default)
[    0.491819] 1e100c00.serial: ttyLTQ0 at MMIO 0x1e100c00 (irq = 112, base_baud = 0) is a lantiq,asc
[    0.500792] console [ttyLTQ0] enabled
[    0.500792] console [ttyLTQ0] enabled
[    0.507893] bootconsole [early0] disabled
[    0.507893] bootconsole [early0] disabled
[    0.516622] cacheinfo: Failed to find cpu0 device node
[    0.520300] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.526997] lantiq nor flash device: 00800000 at 10000000
[    0.531795] ltq_nor: Found 1 x16 devices at 0x0 in 16-bit bank. Manufacturer ID 0x0000c2 Chip ID 0x0022cb
[    0.540758] Amd/Fujitsu Extended Query Table at 0x0040
[    0.545766]   Amd/Fujitsu Extended Query version 1.1.
[    0.550621] number of CFI chips: 1
[    0.553988] 4 fixed-partitions partitions found on MTD device ltq_nor
[    0.560166] Creating 4 MTD partitions on "ltq_nor":
[    0.564907] 0x000000000000-0x000000010000 : "uboot"
[    0.571421] 0x000000010000-0x000000020000 : "uboot_env"
[    0.576902] 0x000000020000-0x0000007f0000 : "firmware"
[    0.634010] 2 uimage-fw partitions found on MTD device firmware
[    0.638479] 0x000000020000-0x0000001ef2b3 : "kernel"
[    0.644968] 0x0000001ef2b3-0x0000007f0000 : "rootfs"
[    0.650235] mtd: device 4 (rootfs) set to be root filesystem
[    0.654547] 1 squashfs-split partitions found on MTD device rootfs
[    0.660457] 0x0000004a0000-0x0000007f0000 : "rootfs_data"
[    0.667386] 0x0000007f0000-0x000000800000 : "board_config"
[    0.677218] libphy: Fixed MDIO Bus: probed
[    0.711481] libphy: ltq_mii: probed
[    0.815482] Registering RTL8306SDM switch with Chip ID: 0x5988, version: 0x0000
[    0.821318] Realtek RTL8306S 1e180000.etop-ffffffff:00: attached PHY driver [Realtek RTL8306S] (mii_bus:phy_addr=1e180000.etop-ffffffff:00, irq=POLL)
[    0.836711] wdt 1f8803f0.watchdog: Init done
[    0.846577] NET: Registered protocol family 10
[    0.866397] Segment Routing with IPv6
[    0.868729] NET: Registered protocol family 17
[    0.873078] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    0.885527] 8021q: 802.1Q VLAN Support v1.8
[    0.899455] OF: fdt: not creating '/sys/firmware/fdt': CRC check failed
[    0.920774] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
[    0.934216] Freeing unused kernel memory: 1244K
[    0.937319] This architecture does not have kernel memory protection.
[    1.145163] random: fast init done
[    2.790404] init: Console is alive
[    2.792847] init: - watchdog -
[    5.519823] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    5.847542] dwc2 1e101000.usb: 1e101000.usb supply vusb_d not found, using dummy regulator
[    5.854445] dwc2 1e101000.usb: 1e101000.usb supply vusb_a not found, using dummy regulator
[    5.862643] dwc2 1e101000.usb: dwc2_core_reset() HANG! Soft Reset GRSTCTL=80000001
[    6.009339] dwc2 1e101000.usb: DWC OTG Controller
[    6.012651] dwc2 1e101000.usb: new USB bus registered, assigned bus number 1
[    6.019503] dwc2 1e101000.usb: irq 62, io mem 0x1e101000
[    6.024719] dwc2 1e101000.usb: startup error -517
[    6.029211] dwc2 1e101000.usb: USB bus 1 deregistered
[    6.034169] dwc2 1e101000.usb: dwc2_hcd_init() FAILED, returning -517
[    6.042558] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    6.051151] init: - preinit -
[    7.579941] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    7.622112] random: procd: uninitialized urandom read (4 bytes read)
Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
[    8.613304] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   11.100291] mount_root: jffs2 not ready yet, using temporary tmpfs overlay
[   11.155251] urandom-seed: Seed file not found (/etc/urandom.seed)
[   11.296616] procd: - early -
[   11.298367] procd: - watchdog -
[   12.060413] procd: - watchdog -
[   12.063031] procd: - ubus -
[   12.444640] random: ubusd: uninitialized urandom read (4 bytes read)
[   12.451373] random: ubusd: uninitialized urandom read (4 bytes read)
[   12.457289] random: ubusd: uninitialized urandom read (4 bytes read)
[   12.465957] procd: - init -
Please press Enter to activate this console.
[   13.223197] kmodloader: loading kernel modules from /etc/modules.d/*
[   13.233472] IFXOS, Version 1.5.19 (c) Copyright 2009, Lantiq Deutschland GmbH
[   13.253455] Lantiq TAPI device driver, version 3.13.0.2, (c) 2001-2010 Lantiq Deutschland GmbH
[   13.268219] NET: Registered protocol family 8
[   13.271167] NET: Registered protocol family 20
[   13.286547] PPP generic driver version 2.4.2
[   13.302348] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   13.324819] IFX MEI Version 5.00.00
[   13.328259] dwc2 1e101000.usb: 1e101000.usb supply vusb_d not found, using dummy regulator
[   13.335138] dwc2 1e101000.usb: 1e101000.usb supply vusb_a not found, using dummy regulator
[   13.361255] Infineon CPE API Driver version: DSL CPE API V3.24.4.4
[   13.379473] dwc2 1e101000.usb: DWC OTG Controller
[   13.382825] dwc2 1e101000.usb: new USB bus registered, assigned bus number 1
[   13.389631] dwc2 1e101000.usb: irq 62, io mem 0x1e101000
[   13.394836] dwc2 1e101000.usb: startup error -517
[   13.399301] dwc2 1e101000.usb: USB bus 1 deregistered
[   13.404270] dwc2 1e101000.usb: dwc2_hcd_init() FAILED, returning -517
[   13.411303] dwc2 1e101000.usb: 1e101000.usb supply vusb_d not found, using dummy regulator
[   13.418608] dwc2 1e101000.usb: 1e101000.usb supply vusb_a not found, using dummy regulator
[   13.426826] dwc2 1e101000.usb: dwc2_core_reset() HANG! Soft Reset GRSTCTL=80000001
[   13.435442] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[   13.441950] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[   13.467167] Lantiq MIPS24KEc MPS driver, version 2.2.2.5, (c) 2006-2010 Lantiq Deutschland GmbH
[   13.474371] request_timer(3, 0x000001AE, 1)...
[   13.474407] successful!
[   13.481337] Lantiq VMMC device driver, version 1.9.0.3, (c) 2006-2010 Lantiq Deutschland GmbH
[   13.493633] ip_tables: (C) 2000-2006 Netfilter Core Team
[   13.514600] nf_conntrack version 0.5.0 (1024 buckets, 4096 max)
[   13.597392] dwc2 1e101000.usb: DWC OTG Controller
[   13.600673] dwc2 1e101000.usb: new USB bus registered, assigned bus number 1
[   13.607546] dwc2 1e101000.usb: irq 62, io mem 0x1e101000
[   13.612762] dwc2 1e101000.usb: startup error -517
[   13.617232] dwc2 1e101000.usb: USB bus 1 deregistered
[   13.622196] dwc2 1e101000.usb: dwc2_hcd_init() FAILED, returning -517
[   13.633437] NET: Registered protocol family 24
[   13.672087] xt_time: kernel timezone is -0000
[   13.805096] PCI: Enabling device 0000:00:0e.0 (0000 -> 0002)
[   13.810645] rt2800pci 0000:00:0e.0: loaded eeprom from mtd device "board_config"
[   13.816602] ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 2860, rev 0103 detected
[   13.824126] ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 0001 detected
[   13.839577] dwc2 1e101000.usb: 1e101000.usb supply vusb_d not found, using dummy regulator
[   13.846492] dwc2 1e101000.usb: 1e101000.usb supply vusb_a not found, using dummy regulator
[   13.854692] dwc2 1e101000.usb: dwc2_core_reset() HANG! Soft Reset GRSTCTL=80000001
[   13.992087] kmodloader: done loading kernel modules from /etc/modules.d/*
[   14.195962] dwc2 1e101000.usb: DWC OTG Controller
[   14.199323] dwc2 1e101000.usb: new USB bus registered, assigned bus number 1
[   14.206119] dwc2 1e101000.usb: irq 62, io mem 0x1e101000
[   14.211338] dwc2 1e101000.usb: startup error -517
[   14.215807] dwc2 1e101000.usb: USB bus 1 deregistered
[   14.220775] dwc2 1e101000.usb: dwc2_hcd_init() FAILED, returning -517
[   16.207871] urandom_read: 5 callbacks suppressed
[   16.207885] random: jshn: uninitialized urandom read (4 bytes read)
[   31.861698] jffs2_scan_eraseblock(): End of filesystem marker found at 0x0
[   31.895052] jffs2_build_filesystem(): unlocking the mtd device... 
[   31.895070] done.
[   31.901578] jffs2_build_filesystem(): erasing all blocks after the end marker... 
[   39.936731] br-lan: port 1(eth0) entered blocking state
[   39.947759] br-lan: port 1(eth0) entered disabled state
[   39.953600] device eth0 entered promiscuous mode
[   40.033536] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   40.965390] br-lan: port 1(eth0) entered blocking state
[   40.969191] br-lan: port 1(eth0) entered forwarding state
[   40.993247] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   52.649250] random: crng init done
[   73.728612] done.
[   73.729209] jffs2: notice: (1034) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   74.098617] overlayfs: upper fs does not support tmpfile.
[   78.117179] [IFX_MEI_RunAdslModem 1368]: Modem failed to be ready!
[   78.121887] [IFX_MEI_Ioctls 2562]: IFX_MEI_RunAdslModem() error...
[   79.461182] [IFX_MEI_RunAdslModem 1368]: Modem failed to be ready!
[   79.471864] [IFX_MEI_Ioctls 2562]: IFX_MEI_RunAdslModem() error...
[   80.805182] [IFX_MEI_RunAdslModem 1368]: Modem failed to be ready!
[   80.815852] [IFX_MEI_Ioctls 2562]: IFX_MEI_RunAdslModem() error...
[   82.149181] [IFX_MEI_RunAdslModem 1368]: Modem failed to be ready!
[   82.159880] [IFX_MEI_Ioctls 2562]: IFX_MEI_RunAdslModem() error...
[   83.525181] [IFX_MEI_RunAdslModem 1368]: Modem failed to be ready!
Project Manager
Mathias Kresin commented on 16.08.2018 15:49

Yeah, totally forgot about the error. It's already reported as  FS#1634 . Someone needs to check at which point the EPROBE_DEFER is returned. Near to impossible to do it without access to the hardware.

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing