Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

FS#1661 - After to install 18.06rc2 mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512 - LinksysWRT3200 #8495

Closed
openwrt-bot opened this issue Jul 16, 2018 · 3 comments
Labels

Comments

@openwrt-bot
Copy link

fredlubrano:

For linksysWRT3200 , After to install 18.06rc2 (OpenWrt 18.06.0-rc2 r7141-e4d0ee5af5 / LuCI openwrt-18.06 branch (git-18.196.56128-9112198))
The message :

Mon Jul 16 18:00:17 2018 kern.info kernel: [ 1282.616384] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:00:17 2018 kern.info kernel: [ 1282.622511] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:00:17 2018 kern.info kernel: [ 1282.628634] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:00:17 2018 kern.info kernel: [ 1282.634760] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:00:17 2018 kern.info kernel: [ 1282.640883] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512

case number :

FS#1074
FS#1220

root@link:# dmesg
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.14.54 (buildbot@builds-03.infra.lede-project.org) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7101-a63e38b)) #0 SMP Sat Jul 14 11:10:08 2018
[ 0.000000] CPU: ARMv7 Processor [414fc091] revision 1 (ARMv7), cr=10c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[ 0.000000] OF: fdt: Machine model: Linksys WRT3200ACM
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] On node 0 totalpages: 131072
[ 0.000000] free_area_init_node: node 0, pgdat c0930d80, node_mem_map dfbf9000
[ 0.000000] Normal zone: 1024 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 131072 pages, LIFO batch:31
[ 0.000000] random: get_random_bytes called from 0xc08008d0 with crng_init=0
[ 0.000000] percpu: Embedded 12 pages/cpu @dfbd0000 s18892 r8192 d22068 u49152
[ 0.000000] pcpu-alloc: s18892 r8192 d22068 u49152 alloc=124096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 130048
[ 0.000000] Kernel command line: console=ttyS0,115200 root=/dev/mtdblock8 ro rootdelay=1 rootfstype=jffs2 earlyprintk mtdparts=armada-nand:2048K(uboot)ro,128K(u_env),256K(s_env),256K@8064K(devinfo),1920K@8320K(sysdiag),80m@10m(kernel),74m@16m(rootfs),80m@90m(alt_kernel),74m@96m(alt_rootfs),160m@10m(ubifs),-@170m(syscfg)
[ 0.000000] Bootloader command line (ignored): console=ttyS0,115200 root=/dev/mtdblock8 ro rootdelay=1 rootfstype=jffs2 earlyprintk mtdparts=armada-nand:2048K(uboot)ro,128K(u_env),256K(s_env),256K@8064K(devinfo),1920K@8320K(sysdiag),80m@10m(kernel),74m@16m(rootfs),80m@90m(alt_kernel),74m@96m(alt_rootfs),160m@10m(ubifs),-@170m(syscfg)
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Memory: 510920K/524288K available (6144K kernel code, 197K rwdata, 804K rodata, 1024K init, 248K bss, 13368K reserved, 0K cma-reserved, 0K highmem)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[ 0.000000] vmalloc : 0xe0800000 - 0xff800000 ( 496 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xe0000000 ( 512 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0xc0008000 - 0xc0700000 (7136 kB)
[ 0.000000] .init : 0xc0800000 - 0xc0900000 (1024 kB)
[ 0.000000] .data : 0xc0900000 - 0xc0931680 ( 198 kB)
[ 0.000000] .bss : 0xc0931680 - 0xc096f774 ( 249 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] L2C-310 enabling early BRESP for Cortex-A9
[ 0.000000] L2C-310 full line of zeros enabled for Cortex-A9
[ 0.000000] L2C-310 D prefetch enabled, offset 1 lines
[ 0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled
[ 0.000000] L2C-310 Coherent cache controller enabled, 16 ways, 1024 kB
[ 0.000000] L2C-310 Coherent: CACHE_ID 0x410054c9, AUX_CTRL 0x56070001
[ 0.000006] sched_clock: 64 bits at 933MHz, resolution 1ns, wraps every 4398046511103ns
[ 0.000014] clocksource: arm_global_timer: mask: 0xffffffffffffffff max_cycles: 0x1ae5b571769, max_idle_ns: 881590513431 ns
[ 0.000025] Switching to timer-based delay loop, resolution 1ns
[ 0.000122] Ignoring duplicate/late registration of read_current_timer delay
[ 0.000128] clocksource: armada_370_xp_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 76450417870 ns
[ 0.000242] Calibrating delay loop (skipped), value calculated using timer frequency.. 1866.00 BogoMIPS (lpj=9330000)
[ 0.000249] pid_max: default: 32768 minimum: 301
[ 0.000295] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.000299] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.000484] CPU: Testing write buffer coherency: ok
[ 0.000576] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.000667] Setting up static identity map for 0x100000 - 0x100060
[ 0.000726] mvebu-soc-id: MVEBU SoC ID=0x6820, Rev=0x4
[ 0.000786] mvebu-pmsu: Initializing Power Management Service Unit
[ 0.000821] Hierarchical SRCU implementation.
[ 0.000966] smp: Bringing up secondary CPUs ...
[ 0.001060] Booting CPU 1
[ 0.001197] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.001230] smp: Brought up 1 node, 2 CPUs
[ 0.001235] SMP: Total of 2 processors activated (3732.00 BogoMIPS).
[ 0.001238] CPU: All CPU(s) started in SVC mode.
[ 0.002555] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
[ 0.002596] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.002603] futex hash table entries: 512 (order: 3, 32768 bytes)
[ 0.002643] pinctrl core: initialized pinctrl subsystem
[ 0.002914] NET: Registered protocol family 16
[ 0.003350] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.003893] cpuidle: using governor ladder
[ 0.004023] mvebu-pmsu: CPU hotplug support is currently broken on Armada 38x: disabling
[ 0.004028] mvebu-pmsu: CPU idle is currently broken on Armada 38x: disabling
[ 0.008695] reg-fixed-voltage usb3_1-vbus: could not find pctldev for node /soc/internal-regs/pinctrl@18000/usb3_1-vbus-pins, deferring probe
[ 0.008816] SCSI subsystem initialized
[ 0.008957] libata version 3.00 loaded.
[ 0.009033] usbcore: registered new interface driver usbfs
[ 0.009053] usbcore: registered new interface driver hub
[ 0.009074] usbcore: registered new device driver usb
[ 0.009534] clocksource: Switched to clocksource arm_global_timer
[ 0.009830] NET: Registered protocol family 2
[ 0.010047] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.010094] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.010122] TCP: Hash tables configured (established 4096 bind 4096)
[ 0.010156] UDP hash table entries: 256 (order: 1, 8192 bytes)
[ 0.010170] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[ 0.010233] NET: Registered protocol family 1
[ 0.010244] PCI: CLS 0 bytes, default 64
[ 0.010981] Crashlog allocated RAM at address 0x3f00000
[ 0.011026] workingset: timestamp_bits=30 max_order=17 bucket_order=0
[ 0.012358] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.012363] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.013343] io scheduler noop registered
[ 0.013348] io scheduler deadline registered (default)
[ 0.013708] armada-38x-pinctrl f1018000.pinctrl: registered pinctrl driver
[ 0.014542] mvebu-pcie soc:pcie: PCI host bridge to bus 0000:00
[ 0.014551] pci_bus 0000:00: root bus resource [io 0x1000-0xfffff]
[ 0.014555] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xe7ffffff]
[ 0.014559] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.014574] pci 0000:00:01.0: [11ab:6820] type 01 class 0x060400
[ 0.014649] pci 0000:00:02.0: [11ab:6820] type 01 class 0x060400
[ 0.014708] PCI: bus0: Fast back to back transfers disabled
[ 0.014714] pci 0000:00:01.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 0.014719] pci 0000:00:02.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 0.014761] pci 0000:01:00.0: [11ab:2b40] type 00 class 0x020000
[ 0.014782] pci 0000:01:00.0: reg 0x10: [mem 0x40000000-0x401fffff 64bit pref]
[ 0.014792] pci 0000:01:00.0: reg 0x18: [mem 0x40200000-0x402fffff 64bit pref]
[ 0.014850] pci 0000:01:00.0: supports D1 D2
[ 0.014852] pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold
[ 0.014901] PCI: bus1: Fast back to back transfers disabled
[ 0.014907] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 0.014951] pci 0000:02:00.0: [11ab:2b40] type 00 class 0x020000
[ 0.014977] pci 0000:02:00.0: reg 0x10: [mem 0x42000000-0x421fffff 64bit pref]
[ 0.014988] pci 0000:02:00.0: reg 0x18: [mem 0x42200000-0x422fffff 64bit pref]
[ 0.015068] pci 0000:02:00.0: supports D1 D2
[ 0.015070] pci 0000:02:00.0: PME# supported from D0 D1 D3hot D3cold
[ 0.015119] PCI: bus2: Fast back to back transfers disabled
[ 0.015124] pci_bus 0000:02: busn_res: [bus 02-ff] end is updated to 02
[ 0.015139] pci 0000:00:01.0: BAR 8: assigned [mem 0xe0000000-0xe02fffff]
[ 0.015144] pci 0000:00:02.0: BAR 8: assigned [mem 0xe0400000-0xe06fffff]
[ 0.015150] pci 0000:01:00.0: BAR 0: assigned [mem 0xe0000000-0xe01fffff 64bit pref]
[ 0.015159] pci 0000:01:00.0: BAR 2: assigned [mem 0xe0200000-0xe02fffff 64bit pref]
[ 0.015167] pci 0000:00:01.0: PCI bridge to [bus 01]
[ 0.015172] pci 0000:00:01.0: bridge window [mem 0xe0000000-0xe02fffff]
[ 0.015179] pci 0000:02:00.0: BAR 0: assigned [mem 0xe0400000-0xe05fffff 64bit pref]
[ 0.015188] pci 0000:02:00.0: BAR 2: assigned [mem 0xe0600000-0xe06fffff 64bit pref]
[ 0.015197] pci 0000:00:02.0: PCI bridge to [bus 02]
[ 0.015201] pci 0000:00:02.0: bridge window [mem 0xe0400000-0xe06fffff]
[ 0.015397] mv_xor f1060800.xor: Marvell shared XOR driver
[ 0.079912] mv_xor f1060800.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[ 0.080013] mv_xor f1060900.xor: Marvell shared XOR driver
[ 0.139893] mv_xor f1060900.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[ 0.140029] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[ 0.140589] console [ttyS0] disabled
[ 0.160651] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 22, base_baud = 12500000) is a 16550A
[ 0.930129] console [ttyS0] enabled
[ 0.934890] loop: module loaded
[ 0.938298] ahci-mvebu f10a8000.sata: AHCI 0001.0000 32 slots 2 ports 6 Gbps 0x3 impl platform mode
[ 0.947414] ahci-mvebu f10a8000.sata: flags: 64bit ncq sntf led only pmp fbs pio slum part sxs
[ 0.956529] scsi host0: ahci-mvebu
[ 0.960066] scsi host1: ahci-mvebu
[ 0.963533] ata1: SATA max UDMA/133 mmio [mem 0xf10a8000-0xf10a9fff] port 0x100 irq 41
[ 0.971494] ata2: SATA max UDMA/133 mmio [mem 0xf10a8000-0xf10a9fff] port 0x180 irq 41
[ 0.979789] pxa3xx-nand f10d0000.flash: This platform can't do DMA on this device
[ 0.987554] nand: device found, Manufacturer ID: 0x01, Chip ID: 0xda
[ 0.993945] nand: AMD/Spansion S34ML02G2
[ 0.997885] nand: 256 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 128
[ 1.005586] pxa3xx-nand f10d0000.flash: ECC strength 16, ECC step size 2048
[ 1.012766] Bad block table found at page 131008, version 0x01
[ 1.018954] Bad block table found at page 130944, version 0x01
[ 1.025076] 11 fixed-partitions partitions found on MTD device pxa3xx_nand-0
[ 1.032161] Creating 11 MTD partitions on "pxa3xx_nand-0":
[ 1.037671] 0x000000000000-0x000000200000 : "u-boot"
[ 1.042858] 0x000000200000-0x000000220000 : "u_env"
[ 1.047911] 0x000000220000-0x000000260000 : "s_env"
[ 1.052967] 0x0000007e0000-0x000000820000 : "devinfo"
[ 1.058188] 0x000000820000-0x000000a00000 : "sysdiag"
[ 1.063421] 0x000000a00000-0x000005a00000 : "kernel1"
[ 1.068789] 0x000001000000-0x000005a00000 : "rootfs1"
[ 1.074148] 0x000005a00000-0x00000aa00000 : "kernel2"
[ 1.079538] 0x000006000000-0x00000aa00000 : "ubi"
[ 1.084545] 0x00000aa00000-0x000010000000 : "syscfg"
[ 1.089844] 0x000000260000-0x000000820000 : "unused_area"
[ 1.095683] libphy: Fixed MDIO Bus: probed
[ 1.100048] libphy: orion_mdio_bus: probed
[ 1.104297] mvneta_bm f10c8000.bm: Buffer Manager for network controller enabled
[ 1.112892] mvneta f1070000.ethernet eth0: Using hardware mac address 60:38:e0:be:19:c8
[ 1.122057] mvneta f1034000.ethernet eth1: Using random mac address 92:b9:2d:7c:38:27
[ 1.130060] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.136616] ehci-pci: EHCI PCI platform driver
[ 1.141105] ehci-platform: EHCI generic platform driver
[ 1.146407] ehci-orion: EHCI orion driver
[ 1.150509] orion-ehci f1058000.usb: EHCI Host Controller
[ 1.155943] orion-ehci f1058000.usb: new USB bus registered, assigned bus number 1
[ 1.163591] orion-ehci f1058000.usb: irq 38, io mem 0xf1058000
[ 1.189528] orion-ehci f1058000.usb: USB 2.0 started, EHCI 1.00
[ 1.195652] hub 1-0:1.0: USB hub found
[ 1.199429] hub 1-0:1.0: 1 port detected
[ 1.203752] usbcore: registered new interface driver usb-storage
[ 1.209934] i2c /dev entries driver
[ 1.224652] (NULL device ): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
[ 1.237052] orion_wdt: Initial timeout 171 sec
[ 1.241724] sdhci: Secure Digital Host Controller Interface driver
[ 1.247930] sdhci: Copyright(c) Pierre Ossman
[ 1.309551] mmc0: SDHCI controller on f10d8000.sdhci [f10d8000.sdhci] using ADMA
[ 1.310638] ata1: SATA link down (SStatus 0 SControl 300)
[ 1.310665] ata2: SATA link down (SStatus 0 SControl 300)
[ 1.327903] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.339587] marvell-cesa f1090000.crypto: CESA device successfully registered
[ 1.347128] NET: Registered protocol family 10
[ 1.352001] Segment Routing with IPv6
[ 1.355713] NET: Registered protocol family 17
[ 1.360197] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[ 1.373347] 8021q: 802.1Q VLAN Support v1.8
[ 1.377603] Registering SWP/SWPB emulation handler
[ 1.383704] xhci-hcd f10f8000.usb3: xHCI Host Controller
[ 1.389050] xhci-hcd f10f8000.usb3: new USB bus registered, assigned bus number 2
[ 1.396637] xhci-hcd f10f8000.usb3: hcc params 0x0a000990 hci version 0x100 quirks 0x00010010
[ 1.405225] xhci-hcd f10f8000.usb3: irq 44, io mem 0xf10f8000
[ 1.411206] hub 2-0:1.0: USB hub found
[ 1.414995] hub 2-0:1.0: 1 port detected
[ 1.419031] xhci-hcd f10f8000.usb3: xHCI Host Controller
[ 1.424384] xhci-hcd f10f8000.usb3: new USB bus registered, assigned bus number 3
[ 1.431920] xhci-hcd f10f8000.usb3: Host supports USB 3.0 SuperSpeed
[ 1.438453] usb usb3: We don't know the algorithms for LPM for this host, disabling LPM.
[ 1.446741] hub 3-0:1.0: USB hub found
[ 1.450531] hub 3-0:1.0: 1 port detected
[ 1.454809] UBI: auto-attach mtd8
[ 1.458144] ubi0: attaching mtd8
[ 1.467207] random: fast init done
[ 1.484653] mmc0: new high speed SDIO card at address 0001
[ 1.668919] ubi0: scanning is finished
[ 1.677013] ubi0: attached mtd8 (name "ubi", size 74 MiB)
[ 1.682443] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[ 1.689347] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[ 1.696168] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[ 1.703161] ubi0: good PEBs: 592, bad PEBs: 0, corrupted PEBs: 0
[ 1.709193] ubi0: user volume: 2, internal volumes: 1, max. volumes count: 128
[ 1.716449] ubi0: max/mean erase counter: 3/1, WL threshold: 4096, image sequence number: 1598095361
[ 1.725623] ubi0: available PEBs: 0, total reserved PEBs: 592, PEBs reserved for bad PEB handling: 40
[ 1.734889] ubi0: background thread "ubi_bgt0d" started, PID 711
[ 1.741294] block ubiblock0_0: created from ubi0:0(rootfs)
[ 1.746804] ubiblock: device ubiblock0_0 (rootfs) set to be root filesystem
[ 1.753987] mvsw61xx 10.mvsw61xx: Found MV88E6352 at f1072004.mdio-mii:10
[ 1.760812] mvsw61xx 10.mvsw61xx: Using direct addressing
[ 1.773644] hctosys: unable to open rtc device (rtc0)
[ 1.778963] Waiting 1 sec before mounting root device...
[ 2.810985] VFS: Mounted root (squashfs filesystem) readonly on device 254:0.
[ 2.818618] Freeing unused kernel memory: 1024K
[ 2.980600] init: Console is alive
[ 2.984072] init: - watchdog -
[ 3.222828] kmodloader: loading kernel modules from /etc/modules-boot.d/

[ 3.253371] kmodloader: done loading kernel modules from /etc/modules-boot.d/

[ 3.269202] init: - preinit -
[ 3.387811] mvneta f1070000.ethernet tmp0: renamed from eth0
[ 3.394072] random: procd: uninitialized urandom read (4 bytes read)
[ 3.440750] mvneta f1034000.ethernet eth0: renamed from eth1
[ 3.480716] mvneta f1070000.ethernet eth1: renamed from tmp0
[ 3.689314] random: jshn: uninitialized urandom read (4 bytes read)
[ 3.705200] random: jshn: uninitialized urandom read (4 bytes read)
[ 3.866957] mvneta f1034000.ethernet eth0: configuring for fixed/sgmii link mode
[ 3.874751] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 3.880785] mvneta f1034000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[ 3.888676] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 6.941274] UBIFS (ubi0:1): background thread "ubifs_bgt0_1" started, PID 822
[ 6.962388] UBIFS (ubi0:1): recovery needed
[ 7.027188] UBIFS (ubi0:1): recovery completed
[ 7.031680] UBIFS (ubi0:1): UBIFS: mounted UBI device 0, volume 1, name "rootfs_data"
[ 7.039551] UBIFS (ubi0:1): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[ 7.049510] UBIFS (ubi0:1): FS size: 65138688 bytes (62 MiB, 513 LEBs), journal size 3301376 bytes (3 MiB, 26 LEBs)
[ 7.059995] UBIFS (ubi0:1): reserved for root: 3076660 bytes (3004 KiB)
[ 7.066638] UBIFS (ubi0:1): media format: w4/r0 (latest is w5/r0), UUID 70C2FC4C-3BD0-4304-98F7-93F9F3EB483C, small LPT model
[ 7.081802] mount_root: switching to ubifs overlay
[ 7.127874] ubi1: attaching mtd9
[ 7.215272] random: crng init done
[ 7.218689] random: 3 urandom warning(s) missed due to ratelimiting
[ 7.367912] ubi1: scanning is finished
[ 7.376065] ubi1: attached mtd9 (name "syscfg", size 86 MiB)
[ 7.381760] ubi1: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[ 7.388664] ubi1: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[ 7.395485] ubi1: VID header offset: 2048 (aligned 2048), data offset: 4096
[ 7.402480] ubi1: good PEBs: 680, bad PEBs: 8, corrupted PEBs: 0
[ 7.408512] ubi1: user volume: 1, internal volumes: 1, max. volumes count: 128
[ 7.415768] ubi1: max/mean erase counter: 2/0, WL threshold: 4096, image sequence number: 172982578
[ 7.424856] ubi1: available PEBs: 0, total reserved PEBs: 680, PEBs reserved for bad PEB handling: 32
[ 7.434122] ubi1: background thread "ubi_bgt1d" started, PID 832
[ 7.444494] UBIFS (ubi1:0): background thread "ubifs_bgt1_0" started, PID 836
[ 7.497115] UBIFS (ubi1:0): UBIFS: mounted UBI device 1, volume 0, name "syscfg"
[ 7.504554] UBIFS (ubi1:0): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[ 7.514518] UBIFS (ubi1:0): FS size: 80375808 bytes (76 MiB, 633 LEBs), journal size 4063232 bytes (3 MiB, 32 LEBs)
[ 7.525002] UBIFS (ubi1:0): reserved for root: 3796347 bytes (3707 KiB)
[ 7.531649] UBIFS (ubi1:0): media format: w4/r0 (latest is w5/r0), UUID 60CBF8E8-0C14-4415-AAA6-A4C4F8F8B3B0, small LPT model
[ 7.544453] urandom-seed: Seeding with /etc/urandom.seed
[ 7.576976] mvneta f1034000.ethernet eth0: Link is Down
[ 7.642338] procd: - early -
[ 7.645251] procd: - watchdog -
[ 8.243789] procd: - watchdog -
[ 8.247022] procd: - ubus -
[ 8.300767] procd: - init -
[ 8.392540] kmodloader: loading kernel modules from /etc/modules.d/*
[ 8.400889] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 8.750399] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[ 8.758440] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[ 8.771723] <<Marvell Mac80211 Wireless PCIE Network Driver version 10.3.4.0-20180330>>
[ 8.779792] pci 0000:00:01.0: enabling device (0140 -> 0142)
[ 8.785569] ieee80211 phy0: priv->pcmd_buf = de270000 priv->pphys_cmd_buf = 1e27000
[ 8.838883] ieee80211 phy0: fw download start
[ 8.838889] ieee80211 phy0: ddr init: download start
[ 8.839267] ieee80211 phy0: ddr init: download complete
[ 8.977023] ieee80211 phy0: FwSize = 450732 downloaded Size = 450732 curr_iteration 65522
[ 11.849521] ieee80211 phy0: fw download complete
[ 11.849553] ieee80211 phy0: device node: /soc/pcie/pcie@1,0
[ 11.864724] ieee80211 phy0: pcmd = de270000
[ 11.882764] ieee80211 phy0: firmware version: 0x9030206
[ 12.189522] ieee80211 phy0: firmware region code: 30
[ 12.220128] ieee80211 phy0: 2G disabled, 5G enabled
[ 12.225029] ieee80211 phy0: 4 TX antennas, 4 RX antennas
[ 12.230507] pci 0000:00:02.0: enabling device (0140 -> 0142)
[ 12.236304] ieee80211 phy1: priv->pcmd_buf = d9888000 priv->pphys_cmd_buf = 19888000
[ 12.243678] ieee80211 phy1: fw download start
[ 12.243684] ieee80211 phy1: ddr init: download start
[ 12.244065] ieee80211 phy1: ddr init: download complete
[ 12.385549] ieee80211 phy1: FwSize = 450732 downloaded Size = 450732 curr_iteration 65527
[ 13.579526] ieee80211 phy1: fw download complete
[ 13.579553] ieee80211 phy1: device node: /soc/pcie/pcie@2,0
[ 13.594795] ieee80211 phy1: pcmd = d9888000
[ 13.612837] ieee80211 phy1: firmware version: 0x9030206
[ 13.919523] ieee80211 phy1: firmware region code: 30
[ 13.943308] ieee80211 phy1: 2G enabled, 5G disabled
[ 13.948217] ieee80211 phy1: 4 TX antennas, 4 RX antennas
[ 13.955649] hidraw: raw HID events driver (C) Jiri Kosina
[ 13.964271] Bluetooth: Core ver 2.22
[ 13.967909] NET: Registered protocol family 31
[ 13.972396] Bluetooth: HCI device and connection manager initialized
[ 13.978787] Bluetooth: HCI socket layer initialized
[ 13.983709] Bluetooth: L2CAP socket layer initialized
[ 13.988797] Bluetooth: SCO socket layer initialized
[ 13.994228] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 13.999585] Bluetooth: BNEP filters: protocol multicast
[ 14.004845] Bluetooth: BNEP socket layer initialized
[ 14.011008] Bluetooth: vendor=0x2df, device=0x9136, class=255, fn=2
[ 14.793832] Bluetooth: FW download over, size 695532 bytes
[ 15.389552] sdio platform data not available
[ 15.390259] usbcore: registered new interface driver btusb
[ 15.400625] Bluetooth: HCI UART driver ver 2.3
[ 15.405091] Bluetooth: HCI UART protocol H4 registered
[ 15.410263] Bluetooth: HCI UART protocol BCSP registered
[ 15.415600] Bluetooth: HCI UART protocol ATH3K registered
[ 15.421438] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 15.427389] Bluetooth: HIDP socket layer initialized
[ 15.432837] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 15.753628] nf_conntrack version 0.5.0 (8192 buckets, 32768 max)
[ 15.760757] mwifiex_sdio mmc0:0001:1: WLAN FW already running! Skip FW dnld
[ 15.767754] mwifiex_sdio mmc0:0001:1: WLAN FW is active
[ 15.797226] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0x242 error, result=0x2
[ 15.804230] mwifiex_sdio mmc0:0001:1: mwifiex_process_cmdresp: cmd 0x242 failed during initialization
[ 15.823429] mwifiex_sdio mmc0:0001:1: info: MWIFIEX VERSION: mwifiex 1.0 (15.68.7.p71)
[ 15.831485] mwifiex_sdio mmc0:0001:1: driver_version = mwifiex 1.0 (15.68.7.p71)
[ 15.937391] Bluetooth: RFCOMM TTY layer initialized
[ 15.942320] Bluetooth: RFCOMM socket layer initialized
[ 15.947486] Bluetooth: RFCOMM ver 1.11
[ 15.955307] xt_time: kernel timezone is -0000
[ 16.140601] PPP generic driver version 2.4.2
[ 16.145195] NET: Registered protocol family 24
[ 16.150243] kmodloader: done loading kernel modules from /etc/modules.d/*
[ 17.759063] mvneta f1034000.ethernet eth0: configuring for fixed/sgmii link mode
[ 17.766597] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 17.772506] mvneta f1034000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[ 17.779101] br-vlan200: port 1(eth0.200) entered blocking state
[ 17.779104] br-vlan200: port 1(eth0.200) entered disabled state
[ 17.779200] device eth0.200 entered promiscuous mode
[ 17.779201] device eth0 entered promiscuous mode
[ 17.779649] br-vlan200: port 1(eth0.200) entered blocking state
[ 17.779652] br-vlan200: port 1(eth0.200) entered forwarding state
[ 17.779685] IPv6: ADDRCONF(NETDEV_UP): br-vlan200: link is not ready
[ 17.790796] br-vlan201: port 1(eth0.201) entered blocking state
[ 17.790799] br-vlan201: port 1(eth0.201) entered disabled state
[ 17.790877] device eth0.201 entered promiscuous mode
[ 17.791185] br-vlan201: port 1(eth0.201) entered blocking state
[ 17.791188] br-vlan201: port 1(eth0.201) entered forwarding state
[ 17.858890] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 18.680860] IPv6: ADDRCONF(NETDEV_UP): wlan2: link is not ready
[ 18.888599] ieee80211 phy0: change: 0xffffffff
[ 18.994774] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 19.183551] ieee80211 phy1: change: 0xffffffff
[ 19.274727] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[ 19.280876] IPv6: ADDRCONF(NETDEV_CHANGE): br-vlan200: link becomes ready
[ 19.289107] br-vlan200: port 2(wlan2) entered blocking state
[ 19.294807] br-vlan200: port 2(wlan2) entered disabled state
[ 19.300601] device wlan2 entered promiscuous mode
[ 19.305402] br-vlan200: port 3(wlan0) entered blocking state
[ 19.311104] br-vlan200: port 3(wlan0) entered disabled state
[ 19.316873] device wlan0 entered promiscuous mode
[ 19.321674] br-vlan201: port 2(wlan1) entered blocking state
[ 19.327360] br-vlan201: port 2(wlan1) entered disabled state
[ 19.333127] device wlan1 entered promiscuous mode
[ 24.346169] ieee80211 phy1: change: 0x100
[ 24.359245] ieee80211 phy1: change: 0x40
[ 24.359351] ieee80211 phy0: change: 0x100
[ 24.368429] ieee80211 phy0: change: 0x40
[ 24.569526] ieee80211 phy1: change: 0x40
[ 24.579551] ieee80211 phy0: change: 0x40
[ 24.769522] ieee80211 phy1: change: 0x40
[ 24.789527] ieee80211 phy0: change: 0x40
[ 24.889529] ieee80211 phy0: change: 0x100
[ 24.969528] ieee80211 phy1: change: 0x40
[ 25.049454] IPv6: ADDRCONF(NETDEV_CHANGE): wlan2: link becomes ready
[ 25.055923] br-vlan200: port 2(wlan2) entered blocking state
[ 25.061616] br-vlan200: port 2(wlan2) entered forwarding state
[ 25.067603] ieee80211 phy0: change: 0x100
[ 25.079527] ieee80211 phy0: change: 0x42
[ 25.169525] ieee80211 phy1: change: 0x40
[ 25.248564] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 25.255002] br-vlan200: port 3(wlan0) entered blocking state
[ 25.260693] br-vlan200: port 3(wlan0) entered forwarding state
[ 25.287635] br-vlan201: port 3(wlan2-1) entered blocking state
[ 25.293552] br-vlan201: port 3(wlan2-1) entered disabled state
[ 25.299487] device wlan2-1 entered promiscuous mode
[ 25.323099] IPv6: ADDRCONF(NETDEV_UP): wlan2-1: link is not ready
[ 25.329236] br-vlan201: port 3(wlan2-1) entered blocking state
[ 25.335119] br-vlan201: port 3(wlan2-1) entered forwarding state
[ 25.342071] br-vlan201: port 4(wlan0-1) entered blocking state
[ 25.347934] br-vlan201: port 4(wlan0-1) entered disabled state
[ 25.353883] device wlan0-1 entered promiscuous mode
[ 25.359559] ieee80211 phy1: change: 0x40
[ 25.432896] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[ 25.439045] br-vlan201: port 4(wlan0-1) entered blocking state
[ 25.444927] br-vlan201: port 4(wlan0-1) entered forwarding state
[ 25.569565] ieee80211 phy1: change: 0x40
[ 25.582060] IPv6: ADDRCONF(NETDEV_CHANGE): wlan2-1: link becomes ready
[ 25.675580] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[ 25.769570] ieee80211 phy1: change: 0x40
[ 25.969527] ieee80211 phy1: change: 0x40
[ 26.159529] ieee80211 phy1: change: 0x40
[ 26.349526] ieee80211 phy1: change: 0x40
[ 26.539528] ieee80211 phy1: change: 0x40
[ 26.729631] ieee80211 phy1: change: 0x40
[ 26.929529] ieee80211 phy1: change: 0x40
[ 27.008544] ieee80211 phy1: change: 0x100
[ 27.109680] ieee80211 phy1: change: 0x100
[ 27.122731] ieee80211 phy1: change: 0x42
[ 27.265642] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 27.272084] br-vlan201: port 2(wlan1) entered blocking state
[ 27.277769] br-vlan201: port 2(wlan1) entered forwarding state
[ 27.319758] br-vlan200: port 4(wlan1-1) entered blocking state
[ 27.325634] br-vlan200: port 4(wlan1-1) entered disabled state
[ 27.331725] device wlan1-1 entered promiscuous mode
[ 27.407947] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
[ 27.414112] br-vlan200: port 4(wlan1-1) entered blocking state
[ 27.419989] br-vlan200: port 4(wlan1-1) entered forwarding state
[ 27.609665] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
root@link:
# logread -f
Mon Jul 16 18:52:23 2018 daemon.notice hostapd: handle_probe_req: send failed
Mon Jul 16 18:52:23 2018 daemon.notice hostapd: handle_probe_req: send failed
Mon Jul 16 18:52:24 2018 daemon.notice hostapd: handle_probe_req: send failed
Mon Jul 16 18:52:24 2018 daemon.notice hostapd: handle_probe_req: send failed
Mon Jul 16 18:52:24 2018 daemon.info hostapd: wlan2: STA ec:10:7b:25:01:d7 IEEE 802.11: disassociated
Mon Jul 16 18:52:27 2018 daemon.info hostapd: wlan2: STA 54:60:09:2f:43:0e IEEE 802.11: associated
Mon Jul 16 18:52:27 2018 daemon.notice hostapd: wlan2: AP-STA-CONNECTED 54:60:09:2f:43:0e
Mon Jul 16 18:52:27 2018 daemon.info hostapd: wlan2: STA 54:60:09:2f:43:0e RADIUS: starting accounting session EB243674169EB07C
Mon Jul 16 18:52:27 2018 daemon.info hostapd: wlan2: STA 54:60:09:2f:43:0e WPA: pairwise key handshake completed (RSN)
Mon Jul 16 18:52:30 2018 daemon.info hostapd: wlan1-1: STA 18:b4:30:2c:04:4d IEEE 802.11: authenticated
Mon Jul 16 18:52:36 2018 daemon.err uhttpd[2091]: luci: accepted login on /admin/network/wireless for root from 172.31.10.70
Mon Jul 16 18:52:41 2018 daemon.notice hostapd: handle_probe_req: send failed
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.881412] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.887573] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.893702] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.899831] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.905961] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.912092] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.918214] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.924341] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.930465] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.936591] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.942720] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.948842] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.954970] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.961092] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.967219] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.973347] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.979469] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.985596] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.991723] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512
Mon Jul 16 18:52:51 2018 kern.info kernel: [ 91.997845] mwifiex_sdio mmc0:0001:1: cmd_wait_q terminated: -512

@openwrt-bot
Copy link
Author

fredlubrano:

[ 15.760757] mwifiex_sdio mmc0:0001:1: WLAN FW already running! Skip FW dnld
[ 15.767754] mwifiex_sdio mmc0:0001:1: WLAN FW is active
[ 15.797226] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0x242 error, result=0x2
[ 15.804230] mwifiex_sdio mmc0:0001:1: mwifiex_process_cmdresp: cmd 0x242 failed during initialization

[ 15.823429] mwifiex_sdio mmc0:0001:1: info: MWIFIEX VERSION: mwifiex 1.0 (15.68.7.p71)
[ 15.831485] mwifiex_sdio mmc0:0001:1: driver_version = mwifiex 1.0 (15.68.7.p71)

--
https://github.com/kaloz/mwlwifi

Note for DFS of WRT3200ACM (88W8964):

All WRT3200ACM devices are programmed with device power table. Mwlwifi driver will base on region code to set country code for your device and it will not allow you to change country code. There are another wifi (phy2) on WRT3200ACM which is not mwlwifi. It will allow you to change country code. Under this case, country code setting will be conflicted and it will let DFS can't work.

There are two ways to resolve this problem:

Please don't change country code and let mwlwifi set it for you.

Remove phy2. Under this case, even though you change country code, mwlwifi will reject it. Because phy2 is not existed, country code setting won't be conflicted. To do this, run the following commands (for OpenWrt/LEDE):

opkg remove kmod-mwifiex-sdio
opkg remove mwifiex-sdio-firmware
reboot
The better way is let mwlwifi set country code for you.

@openwrt-bot
Copy link
Author

@openwrt-bot
Copy link
Author

fredlubrano:

seems to be related FS#1417

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

1 participant