Im not disabling anything in u-boot. Im using yours precompiled.
Running from ext4 do not need block2mtd and that seems to have an issue. My last investigation about this described here:
http://forum.banana-pi.org/t/bpi-r64-quick-start-boot-from-emmc/9809/97?u=niebieski20
I can confirm that eMMC works fine in openwrt with kernel 5.4 and without block2mtd. I’m using old emmc u-boot (2014-04, iirc).
However, your armbian build from [BPI-R64] new image: debian buster for emmc/sd has issue mentioned - I/O error on mount or any action with /dev/mmcblk0
Not sure maybe it’s two different issues
BPI-R64 + Armbian - this armbian build with kernel 4.9 works.
BTW, did you tested internal mt7622 Wi-Fi? It works only for several hours for me, then clients stops connecting until reboot. SSID is visible and clients already connected works normally.
Yes I spotted that too now. I did not run long term tests before. That is a clear OpenWRT package with one small patch on EEPROM. Mt76 git is updating every around month so maybe someone also spot this issue and already fixed.
maybe…i wonder why i’m not stumbling on this issue while creating the image…afair the original one was for emmc…maybe it depends on newer uboot? Afair emmc image was using old uboot,sd-image was created later…maybe with newer uboot… @kosyak which one have you tested (and get emmc-issue)?
Hi. I have the R64 v1.1 board with the BPI mt7615 IPA v1.0 PCIe card installed in CN25 slot
I followed the Readme.md instructions posted at https://github.com/m2LiJu/BPI_R64_OpenWRT
OpenWRT boots up successfully, but I consistently get an error from the mt7615e driver due to a timeout:
[ 33.793354] mt7615e 0000:01:00.0: Failed to get patch semaphore
As a result the firmware does not get loaded and the mt7615 is unfunctional. This was happening even the the 4.19-based Openwrt build that I had built earlier using these instructions: [BPI-R64] loading OpenWRT built files into the board
When using the LEDE version from BPI (http://wiki.banana-pi.org/BPI-R64_%2B_MT7615_function_test), the mt7615 card is fully functional.
So I think it is not any hardware issue with the PCIe card itself.
Upon debugging deeper in the mt76 driver code, I have tentatively concluded that
mt76_mcu_get_response(dev, expires)
function returns NULL because
wait_event_timeout(dev->mcu.wait,
(!skb_queue_empty(&dev->mcu.res_q) ||
test_bit(MT76_MCU_RESET, &dev->phy.state)),
timeout);
times out. The phy.state is 0x1 and mcu.res_q is empty.
Does anyone have any clues or suggestions?
As we have same problem with mt7622 internal wifi (also handled by mt7615 driver) i can give you my current knowledge…
It seems to be caused by bluetooth/rfkill. For mt7622 we defined a soft depency…see https://wiki.fw-web.de/doku.php?id=en:bpi-r64:wifi#internal_wifi maybe this works in similar way for mt7615
Can you attach boot log?
@Mbk_Kamble both mt7622 and mt7615e work fine on my openwrt. please check your wmac node in dts file firstly.
Do you experience the same issue with wifi, described here http://forum.banana-pi.org/t/bpi-r64-openwrt-kernel-5-4-40-running-from-emmc/11087/8?
I think it’s not related to wifi. The issue you guys describe here is more like a MT7622 switch broken issue on kernel 5.4 
. Can you try to use OpenWRT + 4.19 test that again?
I’m constantly getting the same issue: a mt7615e 0000:01:00.0: Failed to get patch semaphore error. Here’s how boot log looks like.
reading bananapi/bpi-r64/linux/uImage
3265176 bytes read in 68 ms (45.8 MiB/s)
reading bananapi/bpi-r64/linux/dtb/image-mt7622-bananapi-bpi-r64.dtb
24877 bytes read in 7 ms (3.4 MiB/s)
bootm flag=0, states=70f
## Loading kernel from FIT Image at 44000000 ...
Using 'config@1' configuration
Trying 'kernel@1' kernel subimage
Description: ARM64 OpenWrt Linux-5.4.39
Type: Kernel Image
Compression: lzma compressed
Data Start: 0x440000e8
Data Size: 3238412 Bytes = 3.1 MiB
Architecture: AArch64
OS: Linux
Load Address: 0x41080000
Entry Point: 0x41080000
Hash algo: crc32
Hash value: b8baf594
Hash algo: sha1
Hash value: 01c05230b7ca622b4276fe320ca6d62ba0aba352
Verifying Hash Integrity ... crc32+ sha1+ OK
## Flattened Device Tree blob at 47000000
Booting using the fdt blob at 0x47000000
Uncompressing Kernel Image ... OK
Loading Device Tree to 6bff6000, end 6bfff12c ... OK
Starting kernel ...
[ATF][ 26.274057]save kernel info
[ATF][ 26.277258]Kernel_EL2
[ATF][ 26.280014]Kernel is 64Bit
[ATF][ 26.283202]pc=0x41080000, r0=0x6bff6000, r1=0x0
INFO: BL3-1: Preparing for EL3 exit to normal world, Kernel
INFO: BL3-1: Next image address = 0x41080000
INFO: BL3-1: Next image spsr = 0x3c9
[ATF][ 26.301468]el3_exit
[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[ 0.000000] Linux version 5.4.39 ([email protected]) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r13203-02656caa7b)) #0 SMP Fri May 8 18:15:0
[ 0.000000] Machine model: Bananapi BPI-R64
[ 0.000000] On node 0 totalpages: 262096
[ 0.000000] DMA32 zone: 4096 pages used for memmap
[ 0.000000] DMA32 zone: 0 pages reserved
[ 0.000000] DMA32 zone: 262096 pages, LIFO batch:63
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv0.2 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: Trusted OS migration not required
[ 0.000000] percpu: Embedded 20 pages/cpu s43608 r8192 d30120 u81920
[ 0.000000] pcpu-alloc: s43608 r8192 d30120 u81920 alloc=20*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1
[ 0.000000] Detected VIPT I-cache on CPU0
[ 0.000000] CPU features: kernel page table isolation disabled by kernel configuration
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 258000
[ 0.000000] Kernel command line: board=bpi-r64 console=ttyS0,115200n1 earlyprintk root=/dev/mmcblk0p2 rootfstype=ext4 rootwait service=linu2
[ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] Memory: 1018396K/1048384K available (6590K kernel code, 432K rwdata, 1896K rodata, 448K init, 285K bss, 29988K reserved, 0K cma)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] rcu: CONFIG_RCU_FANOUT set to non-default value of 32.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] GIC: GICv2 detected, but range too small and irqchip.gicv2_force_probe not set
[ 0.000000] random: get_random_bytes called from start_kernel+0x2d4/0x42c with crng_init=0
[ 0.000000] arch_timer: cp15 timer(s) running at 12.50MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049cda, max_idle_ns: 440795202628 ns
[ 0.000003] sched_clock: 56 bits at 12MHz, resolution 80ns, wraps every 4398046511080ns
[ 0.000166] Calibrating delay loop (skipped), value calculated using timer frequency.. 25.00 BogoMIPS (lpj=50000)
[ 0.000173] pid_max: default: 32768 minimum: 301
[ 0.000261] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[ 0.000272] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[ 0.001153] ASID allocator initialised with 65536 entries
[ 0.001202] rcu: Hierarchical SRCU implementation.
[ 0.001465] smp: Bringing up secondary CPUs ...
[ 0.001788] Detected VIPT I-cache on CPU1
[ 0.001824] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[ 0.001878] smp: Brought up 1 node, 2 CPUs
[ 0.001884] SMP: Total of 2 processors activated.
[ 0.001888] CPU features: detected: 32-bit EL0 Support
[ 0.001892] CPU features: detected: CRC32 instructions
[ 0.001994] CPU: All CPU(s) started at EL2
[ 0.002003] alternatives: patching kernel code
[ 0.005230] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.005243] futex hash table entries: 512 (order: 3, 32768 bytes, linear)
[ 0.005333] pinctrl core: initialized pinctrl subsystem
[ 0.005757] NET: Registered protocol family 16
[ 0.006042] DMA: preallocated 256 KiB pool for atomic allocations
[ 0.017011] GPIO line 499 (asm_sel) hogged as output/high
[ 0.029557] SCSI subsystem initialized
[ 0.029683] libata version 3.00 loaded.
[ 0.029803] usbcore: registered new interface driver usbfs
[ 0.029828] usbcore: registered new interface driver hub
[ 0.029854] usbcore: registered new device driver usb
[ 0.030483] Bluetooth: Core ver 2.22
[ 0.030503] NET: Registered protocol family 31
[ 0.030506] Bluetooth: HCI device and connection manager initialized
[ 0.030513] Bluetooth: HCI socket layer initialized
[ 0.030518] Bluetooth: L2CAP socket layer initialized
[ 0.030528] Bluetooth: SCO socket layer initialized
[ 0.030779] clocksource: Switched to clocksource arch_sys_counter
[ 0.031244] thermal_sys: Registered thermal governor 'fair_share'
[ 0.031246] thermal_sys: Registered thermal governor 'bang_bang'
[ 0.031250] thermal_sys: Registered thermal governor 'step_wise'
[ 0.031254] thermal_sys: Registered thermal governor 'user_space'
[ 0.031257] thermal_sys: Registered thermal governor 'power_allocator'
[ 0.031519] NET: Registered protocol family 2
[ 0.031828] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
[ 0.031846] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.031897] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
[ 0.032017] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.032133] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.032154] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.032256] NET: Registered protocol family 1
[ 0.032277] PCI: CLS 0 bytes, default 64
[ 0.033379] workingset: timestamp_bits=46 max_order=18 bucket_order=0
[ 0.036447] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.036455] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.049368] mtk-tphy 1a0c4000.usb-phy: failed to get ref_clk(id-1)
[ 0.052445] mt-pmic-pwrap 10001000.pwrap: unexpected interrupt int=0x1
[ 0.066456] Serial: 8250/16550 driver, 3 ports, IRQ sharing disabled
[ 0.067127] printk: console [ttyS0] disabled
[ 0.087546] 11002000.serial: ttyS0 at MMIO 0x11002000 (irq = 118, base_baud = 1562500) is a ST16650V2
[ 0.628802] printk: console [ttyS0] enabled
[ 0.653636] 11004000.serial: ttyS1 at MMIO 0x11004000 (irq = 119, base_baud = 1562500) is a ST16650V2
[ 0.663319] 1100c000.serial: ttyS2 at MMIO 0x1100c000 (irq = 125, base_baud = 17499995) is a MediaTek BTIF
[ 0.673046] serial serial0: tty port ttyS2 registered
[ 0.678298] cacheinfo: Unable to detect cache hierarchy for CPU 0
[ 0.685864] libphy: Fixed MDIO Bus: probed
[ 0.713261] libphy: mdio: probed
[ 0.716527] mtk_soc_eth 1b100000.ethernet: generated random MAC address e6:92:e4:25:e8:06
[ 0.724971] mtk_soc_eth 1b100000.ethernet eth0: mediatek frame engine at 0xffffffc011600000, irq 134
[ 0.734123] mtk_soc_eth 1b100000.ethernet: generated random MAC address c6:8b:e7:c0:9f:93
[ 0.742493] mtk_soc_eth 1b100000.ethernet eth1: mediatek frame engine at 0xffffffc011600000, irq 134
[ 0.751704] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 0.758228] ehci-pci: EHCI PCI platform driver
[ 0.762702] ehci-platform: EHCI generic platform driver
[ 0.768033] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 0.774223] ohci-platform: OHCI generic platform driver
[ 0.779994] usbcore: registered new interface driver usb-storage
[ 0.786375] rtc_mt7622 10212800.rtc: registered as rtc0
[ 0.792252] Driver for 1-wire Dallas network protocol.
[ 0.797603] mtk-thermal 1100b000.thermal: Device not calibrated, using default calibration values
[ 0.806794] mtk-wdt 10212000.watchdog: Watchdog enabled (timeout=31 sec, nowayout=0)
[ 0.814628] Bluetooth: HCI UART driver ver 2.3
[ 0.819068] Bluetooth: HCI UART protocol H4 registered
[ 0.824200] Bluetooth: HCI UART protocol BCSP registered
[ 0.829560] Bluetooth: HCI UART protocol Broadcom registered
[ 0.835235] Bluetooth: HCI UART protocol QCA registered
[ 0.867137] mtk-msdc 11240000.mmc: Got CD GPIO
[ 0.897251] NET: Registered protocol family 10
[ 0.902301] Segment Routing with IPv6
[ 0.906011] NET: Registered protocol family 17
[ 0.910472] 8021q: 802.1Q VLAN Support v1.8
[ 0.925308] mtk-pcie 1a140000.pcie: host bridge /pcie@1a140000 ranges:
[ 0.931860] mtk-pcie 1a140000.pcie: Parsing ranges property...
[ 0.937694] mtk-pcie 1a140000.pcie: MEM 0x20000000..0x2fffffff -> 0x20000000
[ 0.987814] mtk-msdc 11230000.mmc: phase: [map:fffffff] [maxlen:28] [final:9]
[ 0.995724] mmc0: new HS200 MMC card at address 0001
[ 1.001820] mmcblk0: mmc0:0001 008G30 7.28 GiB
[ 1.007265] mmcblk0boot0: mmc0:0001 008G30 partition 1 4.00 MiB
[ 1.014073] mmcblk0boot1: mmc0:0001 008G30 partition 2 4.00 MiB
[ 1.020054] mmcblk0rpmb: mmc0:0001 008G30 partition 3 4.00 MiB, chardev (250:0)
[ 1.028576] mmcblk0: p1 p2
[ 1.068777] mtk-pcie 1a140000.pcie: Port1 link down
[ 1.073764] mtk-pcie 1a140000.pcie: PCI host bridge to bus 0000:00
[ 1.079947] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 1.085431] pci_bus 0000:00: root bus resource [mem 0x20000000-0x2fffffff]
[ 1.092304] pci_bus 0000:00: scanning bus
[ 1.096360] pci 0000:00:00.0: [14c3:3258] type 01 class 0x060400
[ 1.102477] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x1ffffffff 64bit pref]
[ 1.111397] pci_bus 0000:00: fixups for bus
[ 1.115581] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 0
[ 1.122279] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 1.130309] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 1
[ 1.137162] pci_bus 0000:01: scanning bus
[ 1.141296] pci 0000:01:00.0: [14c3:7615] type 00 class 0x000280
[ 1.147531] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
[ 1.155272] pci 0000:01:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s x1 link at 0000:00:00.0 (capable of 4.000 Gb/s with )
[ 1.170358] pci_bus 0000:01: fixups for bus
[ 1.174539] pci_bus 0000:01: bus scan returning with max=01
[ 1.180109] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 1.186723] pci_bus 0000:00: bus scan returning with max=01
[ 1.192306] pci 0000:00:00.0: BAR 0: no space for [mem size 0x200000000 64bit pref]
[ 1.199958] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x200000000 64bit pref]
[ 1.207958] pci 0000:00:00.0: BAR 8: assigned [mem 0x20000000-0x200fffff]
[ 1.214744] pci 0000:01:00.0: BAR 0: assigned [mem 0x20000000-0x200fffff 64bit]
[ 1.222133] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 1.227099] pci 0000:00:00.0: bridge window [mem 0x20000000-0x200fffff]
[ 1.234085] mtk_hsdma 1b007000.dma-controller: Using 3 as missing dma-requests property
[ 1.242274] mtk_hsdma 1b007000.dma-controller: MediaTek HSDMA driver registered
[ 1.794810] mt753x gsw@0: LAN/WAN VLAN setting=wllll
[ 1.800254] mt753x gsw@0: Switch is MediaTek MT7531BE rev 0
[ 6.455672] xhci-mtk 1a0c0000.usb: xHCI Host Controller
[ 6.460909] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 1
[ 6.469954] xhci-mtk 1a0c0000.usb: hcc params 0x01403198 hci version 0x96 quirks 0x0000000000210010
[ 6.479025] xhci-mtk 1a0c0000.usb: irq 130, io mem 0x1a0c0000
[ 6.485154] hub 1-0:1.0: USB hub found
[ 6.488920] hub 1-0:1.0: 2 ports detected
[ 6.493140] xhci-mtk 1a0c0000.usb: xHCI Host Controller
[ 6.498370] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 2
[ 6.505770] xhci-mtk 1a0c0000.usb: Host supports USB 3.0 SuperSpeed
[ 6.512063] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 6.520376] hub 2-0:1.0: USB hub found
[ 6.524138] hub 2-0:1.0: 1 port detected
[ 6.528401] rtc_mt7622 10212800.rtc: setting system clock to 2020-05-17T18:42:36 UTC (1589740956)
[ 6.543603] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 6.551755] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 6.558729] Freeing unused kernel memory: 448K
[ 6.578811] Run /sbin/init as init process
[ 6.628176] init: Console is alive
[ 6.631745] init: - watchdog -
[ 6.673791] random: fast init done
[ 6.769358] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[ 6.783368] ehci-fsl: Freescale EHCI Host controller driver
[ 6.793243] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[ 6.809234] init: - preinit -
[ 6.894413] mtk_soc_eth 1b100000.ethernet: PPE started
[ 6.899592] mtk_soc_eth 1b100000.ethernet eth0: configuring for fixed/2500base-x link mode
[ 6.907911] mtk_soc_eth 1b100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control rx/tx
[ 6.918897] usb 1-2: new high-speed USB device number 2 using xhci-mtk
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
[ 7.146538] usb-storage 1-2:1.0: USB Mass Storage device detected
[ 7.152805] random: procd: uninitialized urandom read (4 bytes read)
[ 7.152854] scsi host0: usb-storage 1-2:1.0
[ 8.196488] scsi 0:0:0:0: Direct-Access JetFlash Transcend 4GB 8.07 PQ: 0 ANSI: 2
[ 8.205669] sd 0:0:0:0: [sda] 7847936 512-byte logical blocks: (4.02 GB/3.74 GiB)
[ 8.213314] sd 0:0:0:0: [sda] Write Protect is off
[ 8.218106] sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00
[ 8.223295] sd 0:0:0:0: [sda] No Caching mode page found
[ 8.228609] sd 0:0:0:0: [sda] Assuming drive cache: write through
[ 8.422794] sda: sda1
[ 8.426073] sd 0:0:0:0: [sda] Attached SCSI removable disk
[ 10.948245] mount_root: mounting /dev/root
[ 10.954012] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 10.959568] mount_root: loading kmods from internal overlay
[ 10.973450] kmodloader: loading kernel modules from //etc/modules-boot.d/*
[ 10.981062] kmodloader: done loading kernel modules from //etc/modules-boot.d/*
[ 11.109496] urandom-seed: Seeding with /etc/urandom.seed
[ 11.127439] procd: - early -
[ 11.130347] procd: - watchdog -
[ 11.645926] procd: - watchdog -
[ 11.649233] procd: - ubus -
[ 11.656335] random: ubusd: uninitialized urandom read (4 bytes read)
[ 11.702314] random: ubusd: uninitialized urandom read (4 bytes read)
[ 11.708824] random: ubusd: uninitialized urandom read (4 bytes read)
[ 11.715494] procd: - init -
Please press Enter to activate this console.
[ 11.819810] urngd: v1.0.2 started.
[ 11.848392] random: crng init done
[ 11.851859] random: 6 urandom warning(s) missed due to ratelimiting
[ 11.853249] kmodloader: loading kernel modules from /etc/modules.d/*
[ 11.876059] tun: Universal TUN/TAP device driver, 1.6
[ 11.891253] cryptodev: driver 1.10 loaded.
[ 11.898077] i2c /dev entries driver
[ 11.908146] hidraw: raw HID events driver (C) Jiri Kosina
[ 11.915490] Mirror/redirect action on
[ 11.922348] u32 classifier
[ 11.925078] input device check on
[ 11.928735] Actions configured
[ 11.948778] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 11.954116] Bluetooth: BNEP filters: protocol multicast
[ 11.959346] Bluetooth: BNEP socket layer initialized
[ 11.966091] usbcore: registered new interface driver btusb
[ 11.972407] usbcore: registered new interface driver cdc_wdm
[ 11.978561] Loading modules backported from Linux version v5.4.27-0-g585e0cc08069
[ 11.986045] Backport generated by backports.git v5.4.27-1-0-gf6e8852f
[ 11.993584] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 11.999541] Bluetooth: HIDP socket layer initialized
[ 12.018818] Bluetooth: RFCOMM TTY layer initialized
[ 12.023726] Bluetooth: RFCOMM socket layer initialized
[ 12.028876] Bluetooth: RFCOMM ver 1.11
[ 12.034302] usbcore: registered new interface driver ums-alauda
[ 12.040639] usbcore: registered new interface driver ums-cypress
[ 12.047027] usbcore: registered new interface driver ums-datafab
[ 12.053350] usbcore: registered new interface driver ums-freecom
[ 12.059663] usbcore: registered new interface driver ums-isd200
[ 12.065895] usbcore: registered new interface driver ums-jumpshot
[ 12.072271] usbcore: registered new interface driver ums-karma
[ 12.078501] usbcore: registered new interface driver ums-sddr09
[ 12.084744] usbcore: registered new interface driver ums-sddr55
[ 12.091013] usbcore: registered new interface driver ums-usbat
[ 12.100993] wireguard: WireGuard 1.0.20200506 loaded. See www.wireguard.com for information.
[ 12.109450] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <[email protected]>. All Rights Reserved.
[ 12.128998] xt_time: kernel timezone is -0000
[ 12.133986] usbcore: registered new interface driver cdc_ether
[ 12.231145] mt7615e 0000:01:00.0: assign IRQ: got 139
[ 12.236249] pci 0000:00:00.0: enabling device (0000 -> 0002)
[ 12.241913] pci 0000:00:00.0: enabling bus mastering
[ 12.246887] mt7615e 0000:01:00.0: enabling device (0000 -> 0002)
[ 12.252970] mt7615e 0000:01:00.0: enabling bus mastering
[ 12.261767] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[ 12.284318] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[ 12.306602] PPP generic driver version 2.4.2
[ 12.312430] NET: Registered protocol family 24
[ 12.318433] usbcore: registered new interface driver qmi_wwan
[ 12.324463] mt7622-wmac 18000000.wmac: HW/SW Version: 0x8a108a10, Build Time: 20190801210006a
[ 12.324463]
[ 12.336368] kmodloader: done loading kernel modules from /etc/modules.d/*
[ 12.354691] mt7622-wmac 18000000.wmac: N9 Firmware Version: 2.0, Build Time: 20200131180931
[ 13.702412] mtk_soc_eth 1b100000.ethernet eth0: Link is Down
[ 13.710927] mtk_soc_eth 1b100000.ethernet: 0x100 = 0xffff9070, 0x10c = 0x100808ff
[ 13.718436] mtk_soc_eth 1b100000.ethernet: reset pse
[ 13.810699] mtk_soc_eth 1b100000.ethernet: PPE started
[ 13.815903] debugfs: Directory 'mtk_ppe' with parent '/' already present!
[ 13.822736] mtk_soc_eth 1b100000.ethernet eth0: configuring for fixed/2500base-x link mode
[ 13.831084] mtk_soc_eth 1b100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control rx/tx
[ 13.833137] br-lan: port 1(eth0) entered blocking state
[ 13.845016] br-lan: port 1(eth0) entered disabled state
[ 13.850464] device eth0 entered promiscuous mode
[ 13.859647] br-lan: port 1(eth0) entered blocking state
[ 13.864905] br-lan: port 1(eth0) entered forwarding state
[ 13.877653] mtk_soc_eth 1b100000.ethernet eth1: configuring for fixed/rgmii link mode
[ 13.887134] mtk_soc_eth 1b100000.ethernet eth1: Link is Up - 1Gbps/Full - flow control rx/tx
[ 13.895841] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[ 15.922328] br-lan: port 2(wlan1) entered blocking state
[ 15.927660] br-lan: port 2(wlan1) entered disabled state
[ 15.933126] device wlan1 entered promiscuous mode
[ 15.937893] br-lan: port 2(wlan1) entered blocking state
[ 15.943206] br-lan: port 2(wlan1) entered forwarding state
[ 15.948971] br-lan: port 2(wlan1) entered disabled state
[ 15.968334] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 15.974894] br-lan: port 2(wlan1) entered blocking state
[ 15.980233] br-lan: port 2(wlan1) entered forwarding state
[ 33.762788] mt7615e 0000:01:00.0: Message -4294967280 (seq 1) timeout
[ 33.769238] mt7615e 0000:01:00.0: Failed to get patch semaphoreSeems like mt7622 wifi firmware gets loaded…so i guess semaphore is not unlocked
Is there a way to fix this?
BTW what is this semaphore and how to unlock it?
Looks like internal Wifi is loaded correctly. Mt7622 wmac got its firmware loaded. MT7615 use the same driver and that seems to be the problem. MT7622 gets semaphore and do not give it up on time. Maybe try to disable mt7622 in device tree and check if mt7615 is fine. Im not sure what to do if you need both.
Both mt7622 and mt7615 are loaded correctly and run smoothly on my board.
11.570554] kmodloader: done loading kernel modules from /etc/modules.d/* [ 11.687303] mt7615e 0000:01:00.0: HW/SW Version: 0x8a108a10, Build Time: 20180518100604a [ 11.687303] [ 11.687306] mt7622-wmac 18000000.wmac: HW/SW Version: 0x8a108a10, Build Time: 20190801210006a [ 11.687306] [ 11.963927] mt7622-wmac 18000000.wmac: N9 Firmware Version: 2.0, Build Time: 20200131180931 [ 12.098384] mt7615e 0000:01:00.0: N9 Firmware Version: 2.0, Build Time: 20200131181812 [ 12.126014] mt7615e 0000:01:00.0: CR4 Firmware Version: _reserved_, Build Time: 20190121161307
MT7622 onboard WiFi actually shares the same firmware download procedure with BT. However, the driver currently lacks of mechanism to sync both components that cross the subsystem. Try to disable btif node in dts to keep them mutually exclusive for the moment. Will fix it in spare time.
More specifically, you may need to apply this patch to get BT work. https://patchwork.kernel.org/patch/11504383/
And just for clarification, you are using Openwrt Image generated with steps from first post?
Hi. Yes, I followed the steps exactly as enumerated in post #1. Thus after the build, I am copying the files build_dir/target-aarch64_cortex-a53_musl/linux-mediatek_mt7622/{bpi_bananapi-r64-kernel.bin,image-mt7622-bananapi-bpi-r64.dtb,root.ext4} to r64-boot on the SDcard and mounting the SDcard as /media/sdb1 on R64 and running update.sh to flash to eMMC.
From @niebieski20 and @Ryder.Lee responses, it is not clear if mt7622 is not releasing the semaphore or if BTIF is causing the problem.
In either case, I would like to try to disable both: mt67622-onboard-wifi and btif and enable only the mt7615. How do I do that? I guess I need to edit one or both: mt7622-bananapi-bpi-r64.dts or mt7622.dtsi files.
To respond to earlier messages: my experience is the same as @svintuss as far as the patch semaphore timedout is concerned. Actually, I sometimes get a kernel crash from the mt7615 driver too – but I don’t want to distract this thread. Here is my bootlog (note: there are some extra messages showing from additional dev_info() messages I added into the driver code)
Bootlog where patch semaphore timedout error occurs:
[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[ 0.000000] Linux version 5.4.40 (mikamble@AUS-LX-MIKAMBLE) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r13225-bc0288b768)) #0 SMP Wed May 13 09:16:43 2020
[ 0.000000] Machine model: Bananapi BPI-R64
[ 0.000000] On node 0 totalpages: 262096
[ 0.000000] DMA32 zone: 4096 pages used for memmap
[ 0.000000] DMA32 zone: 0 pages reserved
[ 0.000000] DMA32 zone: 262096 pages, LIFO batch:63
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv0.2 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: Trusted OS migration not required
[ 0.000000] percpu: Embedded 20 pages/cpu s43608 r8192 d30120 u81920
[ 0.000000] pcpu-alloc: s43608 r8192 d30120 u81920 alloc=20*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1
[ 0.000000] Detected VIPT I-cache on CPU0
[ 0.000000] CPU features: kernel page table isolation disabled by kernel configuration
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 258000
[ 0.000000] Kernel command line: board=bpi-r64 console=ttyS0,115200n1 earlyprintk root=/dev/mmcblk0p2 rootfstype=ext4 rootwait service=linux debug=7 initcall_debug=0 androidboot.hardware=mt7622 swiotlb=512
[ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] Memory: 1018404K/1048384K available (6590K kernel code, 432K rwdata, 1896K rodata, 448K init, 285K bss, 29980K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] rcu: CONFIG_RCU_FANOUT set to non-default value of 32.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] GIC: GICv2 detected, but range too small and irqchip.gicv2_force_probe not set
[ 0.000000] random: get_random_bytes called from start_kernel+0x2d4/0x42c with crng_init=0
[ 0.000000] arch_timer: cp15 timer(s) running at 12.50MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049cda, max_idle_ns: 440795202628 ns
[ 0.000003] sched_clock: 56 bits at 12MHz, resolution 80ns, wraps every 4398046511080ns
[ 0.000161] Calibrating delay loop (skipped), value calculated using timer frequency.. 25.00 BogoMIPS (lpj=50000)
[ 0.000168] pid_max: default: 32768 minimum: 301
[ 0.000254] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[ 0.000265] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[ 0.001142] ASID allocator initialised with 65536 entries
[ 0.001191] rcu: Hierarchical SRCU implementation.
[ 0.001447] smp: Bringing up secondary CPUs ...
[ 0.001764] Detected VIPT I-cache on CPU1
[ 0.001800] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[ 0.001853] smp: Brought up 1 node, 2 CPUs
[ 0.001859] SMP: Total of 2 processors activated.
[ 0.001863] CPU features: detected: 32-bit EL0 Support
[ 0.001867] CPU features: detected: CRC32 instructions
[ 0.001968] CPU: All CPU(s) started at EL2
[ 0.001978] alternatives: patching kernel code
[ 0.005113] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.005125] futex hash table entries: 512 (order: 3, 32768 bytes, linear)
[ 0.005216] pinctrl core: initialized pinctrl subsystem
[ 0.005640] NET: Registered protocol family 16
[ 0.005924] DMA: preallocated 256 KiB pool for atomic allocations
[ 0.016850] GPIO line 499 (asm_sel) hogged as output/high
[ 0.029482] SCSI subsystem initialized
[ 0.029639] libata version 3.00 loaded.
[ 0.029778] usbcore: registered new interface driver usbfs
[ 0.029804] usbcore: registered new interface driver hub
[ 0.029831] usbcore: registered new device driver usb
[ 0.030599] Bluetooth: Core ver 2.22
[ 0.030636] NET: Registered protocol family 31
[ 0.030640] Bluetooth: HCI device and connection manager initialized
[ 0.030648] Bluetooth: HCI socket layer initialized
[ 0.030654] Bluetooth: L2CAP socket layer initialized
[ 0.030664] Bluetooth: SCO socket layer initialized
[ 0.031119] clocksource: Switched to clocksource arch_sys_counter
[ 0.031597] thermal_sys: Registered thermal governor 'fair_share'
[ 0.031600] thermal_sys: Registered thermal governor 'bang_bang'
[ 0.031604] thermal_sys: Registered thermal governor 'step_wise'
[ 0.031608] thermal_sys: Registered thermal governor 'user_space'
[ 0.031611] thermal_sys: Registered thermal governor 'power_allocator'
[ 0.031869] NET: Registered protocol family 2
[ 0.032168] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
[ 0.032185] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.032237] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
[ 0.032343] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.032431] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.032456] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.032547] NET: Registered protocol family 1
[ 0.032565] PCI: CLS 0 bytes, default 64
[ 0.033420] workingset: timestamp_bits=46 max_order=18 bucket_order=0
[ 0.036789] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.036799] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.049942] mtk-tphy 1a0c4000.usb-phy: failed to get ref_clk(id-1)
[ 0.053106] mt-pmic-pwrap 10001000.pwrap: unexpected interrupt int=0x1
[ 0.067102] Serial: 8250/16550 driver, 3 ports, IRQ sharing disabled
[ 0.067772] printk: console [ttyS0] disabled
[ 0.088136] 11002000.serial: ttyS0 at MMIO 0x11002000 (irq = 118, base_baud = 1562500) is a ST16650V2
[ 0.629684] printk: console [ttyS0] enabled
[ 0.654530] 11004000.serial: ttyS1 at MMIO 0x11004000 (irq = 119, base_baud = 1562500) is a ST16650V2
[ 0.664206] 1100c000.serial: ttyS2 at MMIO 0x1100c000 (irq = 125, base_baud = 17499995) is a MediaTek BTIF
[ 0.673943] serial serial0: tty port ttyS2 registered
[ 0.679199] cacheinfo: Unable to detect cache hierarchy for CPU 0
[ 0.686695] libphy: Fixed MDIO Bus: probed
[ 0.714135] libphy: mdio: probed
[ 0.717400] mtk_soc_eth 1b100000.ethernet: generated random MAC address 8a:cc:95:2c:91:d5
[ 0.725845] mtk_soc_eth 1b100000.ethernet eth0: mediatek frame engine at 0xffffffc011480000, irq 134
[ 0.735000] mtk_soc_eth 1b100000.ethernet: generated random MAC address 36:b3:8d:b5:6d:e3
[ 0.743378] mtk_soc_eth 1b100000.ethernet eth1: mediatek frame engine at 0xffffffc011480000, irq 134
[ 0.752590] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 0.759114] ehci-pci: EHCI PCI platform driver
[ 0.763584] ehci-platform: EHCI generic platform driver
[ 0.768916] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 0.775111] ohci-platform: OHCI generic platform driver
[ 0.780875] usbcore: registered new interface driver usb-storage
[ 0.787255] rtc_mt7622 10212800.rtc: registered as rtc0
[ 0.793141] Driver for 1-wire Dallas network protocol.
[ 0.798486] mtk-thermal 1100b000.thermal: Device not calibrated, using default calibration values
[ 0.807665] mtk-wdt 10212000.watchdog: Watchdog enabled (timeout=31 sec, nowayout=0)
[ 0.815496] Bluetooth: HCI UART driver ver 2.3
[ 0.819935] Bluetooth: HCI UART protocol H4 registered
[ 0.825067] Bluetooth: HCI UART protocol BCSP registered
[ 0.830426] Bluetooth: HCI UART protocol Broadcom registered
[ 0.836095] Bluetooth: HCI UART protocol QCA registered
[ 0.867482] mtk-msdc 11240000.mmc: Got CD GPIO
[ 0.897596] NET: Registered protocol family 10
[ 0.902660] Segment Routing with IPv6
[ 0.906371] NET: Registered protocol family 17
[ 0.910834] 8021q: 802.1Q VLAN Support v1.8
[ 0.925734] mtk-pcie 1a140000.pcie: host bridge /pcie@1a140000 ranges:
[ 0.932284] mtk-pcie 1a140000.pcie: Parsing ranges property...
[ 0.938121] mtk-pcie 1a140000.pcie: MEM 0x20000000..0x2fffffff -> 0x20000000
[ 0.945537] mtk-pcie mtk_pcie_startup_port_v2. training timeout set to 1000. portbase=10b05000
[ 0.969575] mtk-pcie mtk_pcie_startup_port_v2. training timeout set to 1000. portbase=10b0d000
[ 0.996729] mtk-msdc 11230000.mmc: phase: [map:1fffffff] [maxlen:29] [final:9]
[ 1.013332] mmc0: new HS200 MMC card at address 0001
[ 1.019455] mmcblk0: mmc0:0001 008G30 7.28 GiB
[ 1.024877] mmcblk0boot0: mmc0:0001 008G30 partition 1 4.00 MiB
[ 1.031700] mmcblk0boot1: mmc0:0001 008G30 partition 2 4.00 MiB
[ 1.037679] mmcblk0rpmb: mmc0:0001 008G30 partition 3 4.00 MiB, chardev (250:0)
[ 1.046212] mmcblk0: p1 p2
[ 1.978215] mtk-pcie mtk_pcie_startup_port_v2. poll timeout. err=-110
[ 1.978223] mtk-pcie 1a140000.pcie: Port1 link down
[ 1.989639] mtk-pcie 1a140000.pcie: PCI host bridge to bus 0000:00
[ 1.995823] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 2.001306] pci_bus 0000:00: root bus resource [mem 0x20000000-0x2fffffff]
[ 2.008178] pci_bus 0000:00: scanning bus
[ 2.012214] pci 0000:00:00.0: [14c3:3258] type 01 class 0x060400
[ 2.018257] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x1ffffffff 64bit pref]
[ 2.026871] pci_bus 0000:00: fixups for bus
[ 2.031057] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 0
[ 2.037754] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 2.045764] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 1
[ 2.052549] pci_bus 0000:01: scanning bus
[ 2.056683] pci 0000:01:00.0: [14c3:7615] type 00 class 0x000280
[ 2.062915] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
[ 2.070624] pci 0000:01:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s x1 link at 0000:00:00.0 (capable of 4.000 Gb/s with 5 GT/s x1 link)
[ 2.085605] pci_bus 0000:01: fixups for bus
[ 2.089787] pci_bus 0000:01: bus scan returning with max=01
[ 2.095357] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 2.101971] pci_bus 0000:00: bus scan returning with max=01
[ 2.107553] pci 0000:00:00.0: BAR 0: no space for [mem size 0x200000000 64bit pref]
[ 2.115205] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x200000000 64bit pref]
[ 2.123205] pci 0000:00:00.0: BAR 8: assigned [mem 0x20000000-0x200fffff]
[ 2.129992] pci 0000:01:00.0: BAR 0: assigned [mem 0x20000000-0x200fffff 64bit]
[ 2.137380] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 2.142347] pci 0000:00:00.0: bridge window [mem 0x20000000-0x200fffff]
[ 2.149329] mtk_hsdma 1b007000.dma-controller: Using 3 as missing dma-requests property
[ 2.157513] mtk_hsdma 1b007000.dma-controller: MediaTek HSDMA driver registered
[ 2.723148] mt753x gsw@0: LAN/WAN VLAN setting=wllll
[ 2.728577] mt753x gsw@0: Switch is MediaTek MT7531BE rev 0
[ 7.380242] xhci-mtk 1a0c0000.usb: xHCI Host Controller
[ 7.385477] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 1
[ 7.394521] xhci-mtk 1a0c0000.usb: hcc params 0x01403198 hci version 0x96 quirks 0x0000000000210010
[ 7.403594] xhci-mtk 1a0c0000.usb: irq 130, io mem 0x1a0c0000
[ 7.409725] hub 1-0:1.0: USB hub found
[ 7.413490] hub 1-0:1.0: 2 ports detected
[ 7.417711] xhci-mtk 1a0c0000.usb: xHCI Host Controller
[ 7.422940] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 2
[ 7.430337] xhci-mtk 1a0c0000.usb: Host supports USB 3.0 SuperSpeed
[ 7.436632] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 7.444940] hub 2-0:1.0: USB hub found
[ 7.448701] hub 2-0:1.0: 1 port detected
[ 7.452968] rtc_mt7622 10212800.rtc: setting system clock to 2000-01-01T00:12:31 UTC (946685551)
[ 7.463919] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[ 7.471229] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[ 7.483042] EXT4-fs (mmcblk0p2): recovery complete
[ 7.488892] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 7.497022] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 7.504000] Freeing unused kernel memory: 448K
[ 7.515150] Run /sbin/init as init process
[ 7.580557] init: Console is alive
[ 7.584058] init: - watchdog -
[ 7.622985] random: fast init done
[ 7.721046] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[ 7.734997] ehci-fsl: Freescale EHCI Host controller driver
[ 7.744960] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[ 7.760863] init: - preinit -
[ 7.842281] mtk_soc_eth 1b100000.ethernet: PPE started
[ 7.847473] mtk_soc_eth 1b100000.ethernet eth0: configuring for fixed/2500base-x link mode
[ 7.855791] mtk_soc_eth 1b100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control rx/tx
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
4
[ 10.859270] mount_root: mounting /dev/root
[ 10.864988] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 10.870489] mount_root: loading kmods from internal overlay
[ 10.884545] kmodloader: loading kernel modules from //etc/modules-boot.d/*
[ 10.892162] kmodloader: done loading kernel modules from //etc/modules-boot.d/*
[ 10.966567] urandom-seed: Seeding with /etc/urandom.seed
[ 10.984526] procd: Change state 0 -> 1
[ 10.988329] procd: - early -
[ 10.991225] procd: Watchdog handover: fd=3
[ 10.995334] procd: - watchdog -
[ 10.998487] procd: Ping
[ 11.000958] procd: Opened watchdog with timeout 30s
[ 11.012721] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/mem/full","SUBSYSTEM":"mem","SYNTH_UUID":"0","MAJOR":"1","MINOR":"7","DEVNAME":"full","DEVMODE":"0666","SEQNUM":"612"}}
[ 11.029345] procd: Command: makedev
[ 11.032910] procd: /dev/full
[ 11.036226] procd: 0666
[ 11.038817] procd: Message:
[ 11.041701] procd: ACTION=add
[ 11.044804] procd: DEVPATH=/devices/virtual/mem/full
[ 11.049918] procd: SUBSYSTEM=mem
[ 11.053266] procd: SYNTH_UUID=0
[ 11.056538] procd: MAJOR=1
[ 11.059363] procd: MINOR=7
[ 11.062192] procd: DEVNAME=full
[ 11.065492] procd: DEVMODE=0666
[ 11.068792] procd: SEQNUM=612
[ 11.072011] procd: Finished udevtrigger
[ 11.075948] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/mem/null","SUBSYSTEM":"mem","SYNTH_UUID":"0","MAJOR":"1","MINOR":"3","DEVNAME":"null","DEVMODE":"0666","SEQNUM":"613"}}
[ 11.092345] procd: Command: makedev
[ 11.095873] procd: /dev/null
[ 11.098847] procd: 0666
[ 11.101400] procd: Message:
[ 11.104210] procd: ACTION=add
[ 11.107276] procd: DEVPATH=/devices/virtual/mem/null
[ 11.112339] procd: SUBSYSTEM=mem
[ 11.115666] procd: SYNTH_UUID=0
[ 11.118898] procd: MAJOR=1
[ 11.121704] procd: MINOR=3
[ 11.124510] procd: DEVNAME=null
[ 11.127748] procd: DEVMODE=0666
[ 11.130982] procd: SEQNUM=613
[ 11.134087] procd: Finished hotplug exec instance, pid=741
[ 11.139789] procd: Launched hotplug exec instance, pid=769
[ 11.145415] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/misc/cpu_dma_latency","SUBSYSTEM":"misc","SYNTH_UUID":"0","MAJOR":"10","MINOR":"63","DEVNAME":"cpu_dma_latency","SEQNUM":"614"}}
[ 11.162736] procd: Command: makedev
[ 11.166254] procd: /dev/cpu_dma_latency
[ 11.170192] procd: 0600
[ 11.172740] procd: Message:
[ 11.175547] procd: ACTION=add
[ 11.178607] procd: DEVPATH=/devices/virtual/misc/cpu_dma_latency
[ 11.184711] procd: SUBSYSTEM=misc
[ 11.188124] procd: SYNTH_UUID=0
[ 11.191364] procd: MAJOR=10
[ 11.194251] procd: MINOR=63
[ 11.197143] procd: DEVNAME=cpu_dma_latency
[ 11.201337] procd: SEQNUM=614
[ 11.204503] procd: Finished hotplug exec instance, pid=769
[ 11.210046] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/misc/vhci","SUBSYSTEM":"misc","SYNTH_UUID":"0","MAJOR":"10","MINOR":"137","DEVNAME":"vhci","SEQNUM":"615"}}
[ 11.225402] procd: Command: makedev
[ 11.228903] procd: /dev/vhci
[ 11.231883] procd: 0600
[ 11.234421] procd: Message:
[ 11.237227] procd: ACTION=add
[ 11.240293] procd: DEVPATH=/devices/virtual/misc/vhci
[ 11.245442] procd: SUBSYSTEM=misc
[ 11.248855] procd: SYNTH_UUID=0
[ 11.252093] procd: MAJOR=10
[ 11.254980] procd: MINOR=137
[ 11.257958] procd: DEVNAME=vhci
[ 11.261197] procd: SEQNUM=615
[ 11.264341] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/10212000.watchdog/misc/watchdog","SUBSYSTEM":"misc","SYNTH_UUID":"0","MAJOR":"10","MINOR":"130","DEVNAME":"watchdog","SEQNUM":"616"}}
[ 11.282040] procd: Command: makedev
[ 11.285546] procd: /dev/watchdog
[ 11.288886] procd: 0600
[ 11.291431] procd: Message:
[ 11.294231] procd: ACTION=add
[ 11.297298] procd: DEVPATH=/devices/platform/10212000.watchdog/misc/watchdog
[ 11.304444] procd: SUBSYSTEM=misc
[ 11.307858] procd: SYNTH_UUID=0
[ 11.311092] procd: MAJOR=10
[ 11.313984] procd: MINOR=130
[ 11.316963] procd: DEVNAME=watchdog
[ 11.320550] procd: SEQNUM=616
[ 11.323697] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/tty/ptmx","SUBSYSTEM":"tty","SYNTH_UUID":"0","MAJOR":"5","MINOR":"2","DEVNAME":"ptmx","DEVMODE":"0666","SEQNUM":"617"}}
[ 11.340058] procd: Command: makedev
[ 11.343558] procd: /dev/ptmx
[ 11.346531] procd: 0666
[ 11.349075] procd: Message:
[ 11.351880] procd: ACTION=add
[ 11.354940] procd: DEVPATH=/devices/virtual/tty/ptmx
[ 11.360003] procd: SUBSYSTEM=tty
[ 11.363329] procd: SYNTH_UUID=0
[ 11.366563] procd: MAJOR=5
[ 11.369369] procd: MINOR=2
[ 11.372173] procd: DEVNAME=ptmx
[ 11.375412] procd: DEVMODE=0666
[ 11.378645] procd: SEQNUM=617
[ 11.381751] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/tty/console","SUBSYSTEM":"tty","SYNTH_UUID":"0","MAJOR":"5","MINOR":"1","DEVNAME":"console","SEQNUM":"618"}}
[ 11.397190] procd: Command: makedev
[ 11.400695] procd: /dev/console
[ 11.403944] procd: 0600
[ 11.406482] procd: Message:
[ 11.409288] procd: ACTION=add
[ 11.412354] procd: DEVPATH=/devices/virtual/tty/console
[ 11.417677] procd: SUBSYSTEM=tty
[ 11.421003] procd: SYNTH_UUID=0
[ 11.424242] procd: MAJOR=5
[ 11.427042] procd: MINOR=1
[ 11.429848] procd: DEVNAME=console
[ 11.433347] procd: SEQNUM=618
[ 11.436487] procd: Command: exec
[ 11.439728] procd: /sbin/hotplug-call
[ 11.443487] procd: tty
[ 11.445938] procd: Message:
[ 11.448744] procd: ACTION=add
[ 11.451809] procd: DEVPATH=/devices/virtual/tty/console
[ 11.457131] procd: SUBSYSTEM=tty
[ 11.460456] procd: SYNTH_UUID=0
[ 11.463695] procd: MAJOR=5
[ 11.466494] procd: MINOR=1
[ 11.469299] procd: DEVNAME=console
[ 11.472799] procd: SEQNUM=618
[ 11.476067] procd: Launched hotplug exec instance, pid=774
[ 11.481776] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/11002000.serial/tty/ttyS0","SUBSYSTEM":"tty","SYNTH_UUID":"0","MAJOR":"4","MINOR":"64","DEVNAME":"ttyS0","SEQNUM":"619"}}
[ 11.498606] procd: Command: makedev
[ 11.502142] procd: /dev/ttyS0
[ 11.505257] procd: 0660
[ 11.507805] procd: dialout
[ 11.510604] procd: Message:
[ 11.513411] procd: ACTION=add
[ 11.516477] procd: DEVPATH=/devices/platform/11002000.serial/tty/ttyS0
[ 11.523116] procd: SUBSYSTEM=tty
[ 11.526438] procd: SYNTH_UUID=0
[ 11.529690] procd: MAJOR=4
[ 11.532496] procd: MINOR=64
[ 11.535388] procd: DEVNAME=ttyS0
[ 11.538709] procd: SEQNUM=619
[ 11.542846] procd: Command: makedev
[ 11.546366] procd: /dev/ttyS0
[ 11.549434] procd: 0600
[ 11.551979] procd: Message:
[ 11.554779] procd: ACTION=add
[ 11.557848] procd: DEVPATH=/devices/platform/11002000.serial/tty/ttyS0
[ 11.564474] procd: SUBSYSTEM=tty
[ 11.567802] procd: SYNTH_UUID=0
[ 11.571035] procd: MAJOR=4
[ 11.573842] procd: MINOR=64
[ 11.576735] procd: DEVNAME=ttyS0
[ 11.580062] procd: SEQNUM=619
[ 11.583209] procd: Command: exec
[ 11.586443] procd: /sbin/hotplug-call
[ 11.590205] procd: tty
[ 11.592664] procd: Message:
[ 11.595471] procd: ACTION=add
[ 11.598531] procd: DEVPATH=/devices/platform/11002000.serial/tty/ttyS0
[ 11.605158] procd: SUBSYSTEM=tty
[ 11.608484] procd: SYNTH_UUID=0
[ 11.611724] procd: MAJOR=4
[ 11.614524] procd: MINOR=64
[ 11.617418] procd: DEVNAME=ttyS0
[ 11.620744] procd: SEQNUM=619
[ 11.623837] procd: Finished hotplug exec instance, pid=774
[ 11.629534] procd: Launched hotplug exec instance, pid=777
[ 11.635170] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/tty/tty","SUBSYSTEM":"tty","SYNTH_UUID":"0","MAJOR":"5","MINOR":"0","DEVNAME":"tty","DEVMODE":"0666","SEQNUM":"620"}}
[ 11.651454] procd: Command: makedev
[ 11.654955] procd: /dev/tty
[ 11.657850] procd: 0666
[ 11.660394] procd: Message:
[ 11.663202] procd: ACTION=add
[ 11.666262] procd: DEVPATH=/devices/virtual/tty/tty
[ 11.671239] procd: SUBSYSTEM=tty
[ 11.674561] procd: SYNTH_UUID=0
[ 11.677801] procd: MAJOR=5
[ 11.680607] procd: MINOR=0
[ 11.683412] procd: DEVNAME=tty
[ 11.686560] procd: DEVMODE=0666
[ 11.689800] procd: SEQNUM=620
[ 11.692917] procd: Finished hotplug exec instance, pid=777
[ 11.698464] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/11004000.serial/tty/ttyS1","SUBSYSTEM":"tty","SYNTH_UUID":"0","MAJOR":"4","MINOR":"65","DEVNAME":"ttyS1","SEQNUM":"621"}}
[ 11.715149] procd: Command: makedev
[ 11.718644] procd: /dev/ttyS1
[ 11.721730] procd: 0660
[ 11.724275] procd: dialout
[ 11.727074] procd: Message:
[ 11.729882] procd: ACTION=add
[ 11.732949] procd: DEVPATH=/devices/platform/11004000.serial/tty/ttyS1
[ 11.739575] procd: SUBSYSTEM=tty
[ 11.742895] procd: SYNTH_UUID=0
[ 11.746136] procd: MAJOR=4
[ 11.748941] procd: MINOR=65
[ 11.751838] procd: DEVNAME=ttyS1
[ 11.755166] procd: SEQNUM=621
[ 11.758295] procd: Command: makedev
[ 11.761798] procd: /dev/ttyS1
[ 11.764864] procd: 0600
[ 11.767409] procd: Message:
[ 11.770209] procd: ACTION=add
[ 11.773277] procd: DEVPATH=/devices/platform/11004000.serial/tty/ttyS1
[ 11.779903] procd: SUBSYSTEM=tty
[ 11.783231] procd: SYNTH_UUID=0
[ 11.786463] procd: MAJOR=4
[ 11.789270] procd: MINOR=65
[ 11.792163] procd: DEVNAME=ttyS1
[ 11.795490] procd: SEQNUM=621
[ 11.798607] procd: Command: exec
[ 11.801848] procd: /sbin/hotplug-call
[ 11.805609] procd: tty
[ 11.808067] procd: Message:
[ 11.810867] procd: ACTION=add
[ 11.813954] procd: DEVPATH=/devices/platform/11004000.serial/tty/ttyS1
[ 11.820585] procd: SUBSYSTEM=tty
[ 11.823921] procd: SYNTH_UUID=0
[ 11.827162] procd: MAJOR=4
[ 11.829962] procd: MINOR=65
[ 11.832855] procd: DEVNAME=ttyS1
[ 11.836182] procd: SEQNUM=621
[ 11.839446] procd: Launched hotplug exec instance, pid=781
[ 11.845071] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"179","MINOR":"0","DEVNAME":"mmcblk0","DEVTYPE":"disk","SEQNUM":"622"}}
[ 11.865916] procd: Command: makedev
[ 11.869428] procd: /dev/mmcblk0
[ 11.872667] procd: 0600
[ 11.875212] procd: Message:
[ 11.878012] procd: ACTION=add
[ 11.881080] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0
[ 11.889875] procd: SUBSYSTEM=block
[ 11.893377] procd: SYNTH_UUID=0
[ 11.896617] procd: MAJOR=179
[ 11.899597] procd: MINOR=0
[ 11.902398] procd: DEVNAME=mmcblk0
[ 11.905899] procd: DEVTYPE=disk
[ 11.909139] procd: SEQNUM=622
[ 11.912330] procd: Command: exec
[ 11.915572] procd: /sbin/hotplug-call
[ 11.919335] procd: block
[ 11.921959] procd: Message:
[ 11.924767] procd: ACTION=add
[ 11.927835] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0
[ 11.936632] procd: SUBSYSTEM=block
[ 11.940143] procd: SYNTH_UUID=0
[ 11.943398] procd: MAJOR=179
[ 11.946371] procd: MINOR=0
[ 11.949179] procd: DEVNAME=mmcblk0
[ 11.952679] procd: DEVTYPE=disk
[ 11.955920] procd: SEQNUM=622
[ 11.958999] procd: Finished hotplug exec instance, pid=781
[ 11.964722] procd: Launched hotplug exec instance, pid=785
[ 11.970349] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0boot0","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"179","MINOR":"8","DEVNAME":"mmcblk0boot0","DEVTYPE":"disk","SEQNUM":"623"}}
sh: write error:[ 11.992739] procd: Command: makedev
[ 11.997585] procd: /dev/mmcblk0boot0
[ 12.002622] procd: 0600
[ 12.005173] procd: Message:
[ 12.007980] procd: ACTION=add
[ 12.011042] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0boot0
[ 12.020969] procd: SUBSYSTEM=block
[ 12.024470] procd: SYNTH_UUID=0
[ 12.027710] procd: MAJOR=179
[ 12.030683] procd: MINOR=8
[ 12.033489] procd: DEVNAME=mmcblk0boot0
[ 12.037424] procd: DEVTYPE=disk
[ 12.040664] procd: SEQNUM=623
[ 12.043843] procd: Command: exec
[ 12.047078] procd: /sbin/hotplug-call
[ 12.050841] procd: block
[ 12.053483] procd: Message:
[ 12.056303] procd: ACTION=add
[ 12.059371] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0boot0
[ 12.069300] procd: SUBSYSTEM=block
[ 12.072801] procd: SYNTH_UUID=0
[ 12.076042] procd: MAJOR=179
[ 12.079015] procd: MINOR=8
[ 12.081822] procd: DEVNAME=mmcblk0boot0
[ 12.085758] procd: DEVTYPE=disk
[ 12.088998] procd: SEQNUM=623
[ 12.092083] procd: Finished hotplug exec instance, pid=785
[ 12.097802] procd: Launched hotplug exec instance, pid=790
sh: write error:[ 12.103465] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0p1","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"179","MINOR":"1","DEVNAME":"mmcblk0p1","DEVTYPE":"partition","PARTN":"1","SEQNUM":"624"}}
[ 12.128187] procd: Command: makedev
[ 12.132898] procd: /dev/mmcblk0p1
[ 12.136410] procd: 0600
[ 12.138951] procd: Message:
[ 12.141760] procd: ACTION=add
[ 12.144827] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0p1
[ 12.154491] procd: SUBSYSTEM=block
[ 12.157993] procd: SYNTH_UUID=0
[ 12.161234] procd: MAJOR=179
[ 12.164213] procd: MINOR=1
[ 12.167013] procd: DEVNAME=mmcblk0p1
[ 12.170693] procd: DEVTYPE=partition
[ 12.174367] procd: PARTN=1
[ 12.177174] procd: SEQNUM=624
[ 12.180353] procd: Command: exec
[ 12.183596] procd: /sbin/hotplug-call
[ 12.187357] procd: block
[ 12.189981] procd: Message:
[ 12.192788] procd: ACTION=add
[ 12.195855] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0p1
[ 12.205519] procd: SUBSYSTEM=block
[ 12.209020] procd: SYNTH_UUID=0
[ 12.212260] procd: MAJOR=179
[ 12.215241] procd: MINOR=1
[ 12.218041] procd: DEVNAME=mmcblk0p1
[ 12.221715] procd: DEVTYPE=partition
[ 12.225389] procd: PARTN=1
[ 12.228194] procd: SEQNUM=624
[ 12.231278] procd: Finished hotplug exec instance, pid=790
[ 12.237002] procd: Launched hotplug exec instance, pid=795
[ 12.242628] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0boot1","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"179","MINOR":"16","DEVNAME":"mmcblk0boot1","DEVTYPE":"disk","SEQNUM":"625"}}
[ 12.265139] procd: Command: makedev
[ 12.268656] procd: /dev/mmcblk0boot1
[ 12.272332] procd: 0600
[ 12.274872] procd: Message:
[ 12.277679] procd: ACTION=add
[ 12.280746] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0boot1
[ 12.290671] procd: SUBSYSTEM=block
[ 12.294173] procd: SYNTH_UUID=0
[ 12.297414] procd: MAJOR=179
[ 12.300393] procd: MINOR=16
[ 12.303287] procd: DEVNAME=mmcblk0boot1
[ 12.307222] procd: DEVTYPE=disk
[ 12.310455] procd: SEQNUM=625
[ 12.313609] procd: Command: exec
[ 12.316851] procd: /sbin/hotplug-call
[ 12.320612] procd: block
[ 12.323244] procd: Message:
[ 12.326045] procd: ACTION=add
[ 12.329112] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0boot1
[ 12.339037] procd: SUBSYSTEM=block
[ 12.342538] procd: SYNTH_UUID=0
[ 12.345778] procd: MAJOR=179
[ 12.348757] procd: MINOR=16
[ 12.351651] procd: DEVNAME=mmcblk0boot1
[ 12.355586] procd: DEVTYPE=disk
[ 12.358820] procd: SEQNUM=625
[ 12.361905] procd: Finished hotplug exec instance, pid=795
[ 12.367629] procd: Launched hotplug exec instance, pid=800
sh: write error:[ 12.373266] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0p2","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"179","MINOR":"2","DEVNAME":"mmcblk0p2","DEVTYPE":"partition","PARTN":"2","SEQNUM":"626"}}
[ 12.397976] procd: Command: makedev
[ 12.402699] procd: /dev/mmcblk0p2
[ 12.406150] procd: 0600
[ 12.408695] procd: Message:
[ 12.411502] procd: ACTION=add
[ 12.414563] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0p2
[ 12.424228] procd: SUBSYSTEM=block
[ 12.427729] procd: SYNTH_UUID=0
[ 12.430962] procd: MAJOR=179
[ 12.433942] procd: MINOR=2
[ 12.436747] procd: DEVNAME=mmcblk0p2
[ 12.440421] procd: DEVTYPE=partition
[ 12.444096] procd: PARTN=2
[ 12.446896] procd: SEQNUM=626
[ 12.450050] procd: Command: exec
[ 12.453292] procd: /sbin/hotplug-call
[ 12.457053] procd: block
[ 12.459683] procd: Message:
[ 12.462483] procd: ACTION=add
[ 12.465551] procd: DEVPATH=/devices/platform/11230000.mmc/mmc_host/mmc0/mmc0:0001/block/mmcblk0/mmcblk0p2
[ 12.475219] procd: SUBSYSTEM=block
[ 12.478713] procd: SYNTH_UUID=0
[ 12.481953] procd: MAJOR=179
[ 12.484933] procd: MINOR=2
[ 12.487740] procd: DEVNAME=mmcblk0p2
[ 12.491414] procd: DEVTYPE=partition
[ 12.495081] procd: PARTN=2
[ 12.497888] procd: SEQNUM=626
[ 12.500972] procd: Finished hotplug exec instance, pid=800
[ 12.506688] procd: Launched hotplug exec instance, pid=805
[ 12.512334] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/10212800.rtc/rtc/rtc0","SUBSYSTEM":"rtc","SYNTH_UUID":"0","MAJOR":"253","MINOR":"0","DEVNAME":"rtc0","SEQNUM":"627"}}
[ 12.528805] procd: Command: makedev
[ 12.532348] procd: /dev/rtc0
[ 12.535359] procd: 0600
[ 12.537930] procd: Message:
[ 12.540740] procd: ACTION=add
[ 12.543808] procd: DEVPATH=/devices/platform/10212800.rtc/rtc/rtc0
[ 12.550087] procd: SUBSYSTEM=rtc
[ 12.553415] procd: SYNTH_UUID=0
[ 12.556655] procd: MAJOR=253
[ 12.559635] procd: MINOR=0
[ 12.562436] procd: DEVNAME=rtc0
[ 12.565675] procd: SEQNUM=627
[ 12.568880] procd: Finished hotplug exec instance, pid=805
[ 12.574435] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/10212000.watchdog/watchdog/watchdog0","SUBSYSTEM":"watchdog","SYNTH_UUID":"0","MAJOR":"251","MINOR":"0","DEVNAME":"watchdog0","SEQNUM":"628"}}
[ 12.592921] procd: Command: makedev
[ 12.596425] procd: /dev/watchdog0
[ 12.599838] procd: 0600
[ 12.602377] procd: Message:
[ 12.605184] procd: ACTION=add
[ 12.608251] procd: DEVPATH=/devices/platform/10212000.watchdog/watchdog/watchdog0
[ 12.615832] procd: SUBSYSTEM=watchdog
[ 12.619593] procd: SYNTH_UUID=0
[ 12.622826] procd: MAJOR=251
[ 12.625807] procd: MINOR=0
[ 12.628612] procd: DEVNAME=watchdog0
[ 12.632287] procd: SEQNUM=628
[ 13.135925] procd: Coldplug complete
[ 13.139520] procd: Change state 1 -> 2
[ 13.143289] procd: - watchdog -
[ 13.146441] procd: Ping
[ 13.148905] procd: Opened watchdog with timeout 30s
[ 13.153864] procd: - ubus -
[ 13.156685] procd: Create service ubus
[ 13.160462] procd: Start instance ubus::instance1
[ 13.165387] procd: Started instance ubus::instance1[810]
[ 13.169064] random: ubusd: uninitialized urandom read (4 bytes read)
[ 13.170775] procd: running /etc/init.d/ubus running
[ 13.182832] procd: glob failed on /etc/init.d/ubus
[ 13.207701] random: ubusd: uninitialized urandom read (4 bytes read)
[ 13.214158] random: ubusd: uninitialized urandom read (4 bytes read)
[ 13.220748] procd: Connected to ubus, id=62f59590
[ 13.225475] procd: Change state 2 -> 3
[ 13.229238] procd: - init -
[ 13.233103] procd: Parsing inittab - ::sysinit:/etc/init.d/rcS S boot
[ 13.239586] procd: Parsing inittab - ::shutdown:/etc/init.d/rcS K shutdown
[ 13.246496] procd: Parsing inittab - ::askconsole:/usr/libexec/login.sh
[ 13.253407] procd: Launched new askconsole action, pid=811
[ 13.258932] procd: running /etc/rc.d/S* boot
Please press Enter to activate this console.
[ 13.343319] urngd: v1.0.2 started.
[ 13.354712] kmodloader: loading kernel modules from /etc/modules.d/*
[ 13.368966] random: crng init done
[ 13.372399] random: 7 urandom warning(s) missed due to ratelimiting
[ 13.379692] tun: Universal TUN/TAP device driver, 1.6
[ 13.395154] cryptodev: driver 1.10 loaded.
[ 13.402474] i2c /dev entries driver
[ 13.412462] hidraw: raw HID events driver (C) Jiri Kosina
[ 13.420040] Mirror/redirect action on
[ 13.427927] u32 classifier
[ 13.430631] input device check on
[ 13.434315] Actions configured
[ 13.454973] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 13.460319] Bluetooth: BNEP filters: protocol multicast
[ 13.465549] Bluetooth: BNEP socket layer initialized
[ 13.472503] usbcore: registered new interface driver btusb
[ 13.479006] usbcore: registered new interface driver cdc_wdm
[ 13.485360] Loading modules backported from Linux version v5.4.27-0-g585e0cc08069
[ 13.492855] Backport generated by backports.git v5.4.27-1-0-gf6e8852f
[ 13.500608] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 13.506573] Bluetooth: HIDP socket layer initialized
[ 13.522485] Bluetooth: RFCOMM TTY layer initialized
[ 13.527399] Bluetooth: RFCOMM socket layer initialized
[ 13.532548] Bluetooth: RFCOMM ver 1.11
[ 13.537939] usbcore: registered new interface driver ums-alauda
[ 13.544402] usbcore: registered new interface driver ums-cypress
[ 13.550778] usbcore: registered new interface driver ums-datafab
[ 13.557101] usbcore: registered new interface driver ums-freecom
[ 13.563426] usbcore: registered new interface driver ums-isd200
[ 13.569660] usbcore: registered new interface driver ums-jumpshot
[ 13.576044] usbcore: registered new interface driver ums-karma
[ 13.582278] usbcore: registered new interface driver ums-sddr09
[ 13.588538] usbcore: registered new interface driver ums-sddr55
[ 13.594810] usbcore: registered new interface driver ums-usbat
[ 13.604814] wireguard: WireGuard 1.0.20200506 loaded. See www.wireguard.com for information.
[ 13.613267] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <[email protected]>. All Rights Reserved.
[ 13.633098] xt_time: kernel timezone is -0000
[ 13.638075] usbcore: registered new interface driver cdc_ether
[ 13.735053] mt7615_init()-->
[ 13.737959] __pci_register_driver--> mod_name=mt7615e
[ 13.743169] mt7615e 0000:01:00.0: assign IRQ: got 139
[ 13.748254] pci 0000:00:00.0: enabling device (0000 -> 0002)
[ 13.753919] pci 0000:00:00.0: enabling bus mastering
[ 13.758894] mt7615e 0000:01:00.0: enabling device (0000 -> 0002)
[ 13.764980] mt7615e 0000:01:00.0: enabling bus mastering
[ 13.773705] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[ 13.781341] __pci_register_driver--> mod_name=mt7615e ret=0
[ 13.801919] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[ 13.809590] <--mt7615_init() with ret=0
[ 13.827235] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.835378] mt7622-wmac 18000000.wmac: <--mt7615_mcu_wait_response() with ret=2
[ 13.842745] mt7622-wmac 18000000.wmac: Returning from mt7615_mcu_send_message pt2 ret=2
[ 13.843448] PPP generic driver version 2.4.2
[ 13.854304] mt7622-wmac 18000000.wmac: HW/SW Version: 0x8a108a10, Build Time: 20190801210006a
[ 13.854304]
[ 13.865514] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.865570] NET: Registered protocol family 24
[ 13.880277] usbcore: registered new interface driver qmi_wwan
[ 13.885385] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.894632] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.902804] mt7622-wmac 18000000.wmac: <--mt7615_mcu_wait_response() with ret=3
[ 13.903046] kmodloader: done loading kernel modules from /etc/modules.d/*
[ 13.910129] mt7622-wmac 18000000.wmac: Returning from mt7615_mcu_send_message pt2 ret=3
[ 13.932913] mt7622-wmac 18000000.wmac: N9 Firmware Version: 2.0, Build Time: 20200131180931
[ 13.941535] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
sh: write error: No such file or directory
[ 13.954185] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
sh: write error: No such file or directory
sh: write error: No such file or directory
sh: write error: No such file or directory
[ 14.004856] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 14.013960] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
sh: write error: No such file or directory
sh: write error: No such file or directory
sh: write error: No such file or directory
sh: write error: No such file or directory
[ 15.328597] mtk_soc_eth 1b100000.ethernet eth0: Link is Down
[ 15.337359] mtk_soc_eth 1b100000.ethernet: 0x100 = 0xffff9070, 0x10c = 0x100808ff
[ 15.344878] mtk_soc_eth 1b100000.ethernet: reset pse
[ 15.429869] mtk_soc_eth 1b100000.ethernet: PPE started
[ 15.435141] debugfs: Directory 'mtk_ppe' with parent '/' already present!
[ 15.441951] mtk_soc_eth 1b100000.ethernet eth0: configuring for fixed/2500base-x link mode
[ 15.450455] mtk_soc_eth 1b100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control rx/tx
[ 15.456699] br-lan: port 1(eth0) entered blocking state
[ 15.464319] br-lan: port 1(eth0) entered disabled state
[ 15.469806] device eth0 entered promiscuous mode
[ 15.477689] br-lan: port 1(eth0) entered blocking state
[ 15.482949] br-lan: port 1(eth0) entered forwarding state
[ 15.496040] mtk_soc_eth 1b100000.ethernet eth1: configuring for fixed/rgmii link mode
[ 15.504523] mtk_soc_eth 1b100000.ethernet eth1: Link is Up - 1Gbps/Full - flow control rx/tx
[ 15.514317] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
sh: write error: No such file or directory
sh: write error: No such file or directory
sh: write error: No such file or directory
sh: write error: No such file or directory
udhcpc: started, v1.31.1
udhcpc: sending discover
[ 17.944775] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 17.952941] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 17.964896] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
udhcpc: no lease, failing
[ 35.811134] mt7615e 0000:01:00.0: wait_event_timeout myret=0 state=0x1 empty=1
[ 35.818366] mt7615e 0000:01:00.0: dev.init_name=(null) dev.id=0 driver_name=mt7615e Message -4294967280 (ffffffff00000010) (seq 1) timeout
[ 35.830798] mt7615e 0000:01:00.0: Returning from mt7615_mcu_send_message pt2 ret=-110
[ 35.838630] mt7615e 0000:01:00.0: Failed to get patch semaphore
BusyBox v1.31.1 () built-in shell (ash)
_______ ________ __
| |.-----.-----.-----.| | | |.----.| |_
| - || _ | -__| || | | || _|| _|
|_______|| __|_____|__|__||________||__| |____|
|__| W I R E L E S S F R E E D O M
-----------------------------------------------------
OpenWrt SNAPSHOT, r13225-bc0288b768
-----------------------------------------------------
=== WARNING! =====================================
There is no root password defined on this device!
Use the "passwd" command to set up a new password
in order to prevent unauthorized SSH logins.
--------------------------------------------------
root@OpenWrt:/# dmesg|grep max=
[ 2.089787] pci_bus 0000:01: bus scan returning with max=01
[ 2.101971] pci_bus 0000:00: bus scan returning with max=01
root@OpenWrt:/# lspci
01:00.0 Class 0002: 14c3:7615
00:00.0 Class 0604: 14c3:3258
root@OpenWrt:/# dmesg|grep mt7615
[ 13.735053] mt7615_init()-->
[ 13.737959] __pci_register_driver--> mod_name=mt7615e
[ 13.743169] mt7615e 0000:01:00.0: assign IRQ: got 139
[ 13.758894] mt7615e 0000:01:00.0: enabling device (0000 -> 0002)
[ 13.764980] mt7615e 0000:01:00.0: enabling bus mastering
[ 13.781341] __pci_register_driver--> mod_name=mt7615e ret=0
[ 13.809590] <--mt7615_init() with ret=0
[ 13.827235] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.835378] mt7622-wmac 18000000.wmac: <--mt7615_mcu_wait_response() with ret=2
[ 13.842745] mt7622-wmac 18000000.wmac: Returning from mt7615_mcu_send_message pt2 ret=2
[ 13.865514] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.885385] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.894632] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.902804] mt7622-wmac 18000000.wmac: <--mt7615_mcu_wait_response() with ret=3
[ 13.910129] mt7622-wmac 18000000.wmac: Returning from mt7615_mcu_send_message pt2 ret=3
[ 13.941535] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 13.954185] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 14.004856] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 14.013960] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 17.944775] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 17.952941] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 17.964896] mt7622-wmac 18000000.wmac: mt7615_mcu_wait_response() -- got response in skb
[ 35.811134] mt7615e 0000:01:00.0: wait_event_timeout myret=0 state=0x1 empty=1
[ 35.818366] mt7615e 0000:01:00.0: dev.init_name=(null) dev.id=0 driver_name=mt7615e Message -4294967280 (ffffffff00000010) (seq 1) timeout
[ 35.830798] mt7615e 0000:01:00.0: Returning from mt7615_mcu_send_message pt2 ret=-110
[ 35.838630] mt7615e 0000:01:00.0: Failed to get patch semaphore
root@OpenWrt:/#
I too see that the mt7622 wifi firmware gets loaded and I can get full network functionality from it (802.11n). But mt7615 driver does not get to the point of loading firmware.
@Ryder.Lee, I do have
&wmac {
status = "okay";
};
in the mt7622-bananapi-bpi-r64.dts file.
In mt7622.dtsi file, the btif node is:
btif: serial@1100c000 {
compatible = "mediatek,mt7622-btif",
"mediatek,mtk-btif";
reg = <0 0x1100c000 0 0x1000>;
interrupts = <GIC_SPI 90 IRQ_TYPE_LEVEL_LOW>;
clocks = <&pericfg CLK_PERI_BTIF_PD>;
clock-names = "main";
reg-shift = <2>;
reg-io-width = <4>;
status = "disabled";
bluetooth {
compatible = "mediatek,mt7622-bluetooth";
power-domains = <&scpsys MT7622_POWER_DOMAIN_WB>;
clocks = <&clk25m>;
clock-names = "ref";
};
};
status = “disabled”; – does this mean BTIF is already disabled?
BTIF is another problem to enable both BT and onbaord WiFi. I guess this is a board issue since I saw mcu timeout from your log.
btw I saw this patch has applied into openwrt trunk, so no need to apply extra patch for BT