ericwoud / buildR64ubuntu

Install a minimal Arch-Linux, Ubuntu or Debian on Banana Pi R64 from scratch.
9 stars 2 forks source link

PCIe not functioning properly, kernel crash #3

Closed vycb closed 2 years ago

vycb commented 2 years ago

Hello, tried last image from 2 Release Candidate. It looks like there is no any wifi link, no wlan or wlps0.. :

[root@bpi-r64 ~]# ip link show
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group de0
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1504 qdisc pfifo_fast state UP mode DEFAUL0
    link/ether aa:bb:cc:35:de:20 brd ff:ff:ff:ff:ff:ff
3: eth1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP mode DEFAUL0
    link/ether aa:bb:cc:4d:41:b5 brd ff:ff:ff:ff:ff:ff
4: sit0@NONE: <NOARP> mtu 1480 qdisc noop state DOWN mode DEFAULT group default qlen 1000
    link/sit 0.0.0.0 brd 0.0.0.0
5: wan@eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue master brlan state 0
    link/ether aa:bb:cc:35:de:20 brd ff:ff:ff:ff:ff:ff
6: lan0@eth0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue master brlan sta0
    link/ether aa:bb:cc:35:de:20 brd ff:ff:ff:ff:ff:ff
7: lan1@eth0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue master brlan sta0
    link/ether aa:bb:cc:35:de:20 brd ff:ff:ff:ff:ff:ff
8: lan2@eth0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue master brlan sta0
    link/ether aa:bb:cc:35:de:20 brd ff:ff:ff:ff:ff:ff
9: lan3@eth0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue master brlan sta0
    link/ether aa:bb:cc:35:de:20 brd ff:ff:ff:ff:ff:ff
10: aux@eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue master brlan state0
    link/ether aa:bb:cc:35:de:20 brd ff:ff:ff:ff:ff:ff
11: brlan: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP mode DEFAULT0
    link/ether 2a:0a:a6:df:eb:9c brd ff:ff:ff:ff:ff:ff

[root@bpi-r64 ~]# uname -a                                                   
Linux bpi-r64 5.15.0-rc1-bpi-r64-0 #3 SMP PREEMPT Sat Sep 18 13:15:08 CEST 2021 aarch64 Gx

[root@bpi-r64 ~]# dmesg|grep mt76
[    1.193711] rtc_mt7622 10212800.rtc: registered as rtc0
[    1.198991] rtc_mt7622 10212800.rtc: setting system clock to 2000-01-01T00:00:00 UTC ()
[    4.645468] mt7615e 0000:01:00.0: assign IRQ: got 145
[    4.665035] mt7615e 0000:01:00.0: enabling device (0000 -> 0002)
[    4.671910] mt7615e 0000:01:00.0: enabling bus mastering
[    4.761322] Modules linked in: mt7615e(+) mt7615_common mt76_connac_lib mt76 mac80211
[    5.002880]  mt7615_pci_probe+0x6c/0xf4 [mt7615e]
[    5.050961]  mt7615_init+0x38/0x1000 [mt7615e]

mediatek firmware are present:

[root@bpi-r64 ~]# ls -al /lib/firmware/mediatek/
total 1047
drwxr-xr-x 2 root root   3488 Nov 28 12:47 .
drwxr-xr-x 3 root root   3488 Sep 18 20:18 ..
-rw-r--r-- 1 root root 122644 Nov 28 12:47 mt7615_cr4.bin
-rw-r--r-- 1 root root 457992 Nov 28 12:47 mt7615_n9.bin
-rw-r--r-- 1 root root  11102 Nov 28 12:47 mt7615_rom_patch.bin
-rw-r--r-- 1 root root 300072 Nov 28 12:47 mt7622_n9.bin
-rw-r--r-- 1 root root  82110 Nov 28 12:47 mt7622_rom_patch.bin
-rwxr-xr-x 1 root root  78158 Nov 28 12:47 mt7622pr2h.bin
[root@bpi-r64 ~]# 

I have R64 with mt7615 module.

Haw to enable wifi, what is missing?

frank-w commented 2 years ago

Last 3 lines from dmesg look like a crash/warning (backtrace)

Can you upload full dmesg?

vycb commented 2 years ago

This is the full dmesg:

Debug Output

``` [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 5.15.0-rc1-bpi-r64-0 (root@BLUE) (aarch64-linux-gnu-gcc (Ubuntu 9.3.0-17ubuntu1~20.04) 9.3.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #3 SMP PREEMPT Sat Sep 18 13:15:08 CEST 2021 [ 0.000000] Machine model: Bananapi BPI-R64 [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x0000000042ffffff] [ 0.000000] node 0: [mem 0x0000000043000000-0x000000004302ffff] [ 0.000000] node 0: [mem 0x0000000043030000-0x000000007fffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: MIGRATE_INFO_TYPE not supported. [ 0.000000] psci: SMC Calling Convention v1.2 [ 0.000000] percpu: Embedded 17 pages/cpu s32216 r8192 d29224 u69632 [ 0.000000] pcpu-alloc: s32216 r8192 d29224 u69632 alloc=17*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: detected: ARM erratum 843419 [ 0.000000] CPU features: detected: ARM erratum 845719 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 258048 [ 0.000000] Kernel command line: console=ttyS0,115200 rw rootwait root=PARTLABEL=root-bpir64-sdmmc [ 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: 1014092K/1048576K available (7744K kernel code, 1224K rwdata, 2588K rodata, 2048K init, 297K bss, 34484K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 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] Root IRQ handler: gic_handle_irq [ 0.000000] random: get_random_bytes called from start_kernel+0x40c/0x59c 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.000000] sched_clock: 56 bits at 12MHz, resolution 80ns, wraps every 4398046511080ns [ 0.000255] Console: colour dummy device 80x25 [ 0.000296] Calibrating delay loop (skipped), value calculated using timer frequency.. 25.00 BogoMIPS (lpj=50000) [ 0.000309] pid_max: default: 32768 minimum: 301 [ 0.000425] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.000443] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.002009] rcu: Hierarchical SRCU implementation. [ 0.002869] smp: Bringing up secondary CPUs ... [ 0.003451] Detected VIPT I-cache on CPU1 [ 0.003466] CPU features: SANITY CHECK: Unexpected variation in (((3) << 19) | ((3) << 16) | ((14) << 12) | ((0) << 8) | ((0) << 5)). Boot CPU: 0x00000000bebc20, CPU1: 0x00000000000000 [ 0.003497] CPU features: Unsupported CPU feature variation detected. [ 0.003540] CPU1: Booted secondary processor 0x0000000001 [0x410fd034] [ 0.003659] smp: Brought up 1 node, 2 CPUs [ 0.003681] SMP: Total of 2 processors activated. [ 0.003689] CPU features: detected: 32-bit EL0 Support [ 0.003697] CPU features: detected: CRC32 instructions [ 0.004481] CPU: All CPU(s) started at EL2 [ 0.004503] alternatives: patching kernel code [ 0.005264] devtmpfs: initialized [ 0.010786] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.010819] futex hash table entries: 512 (order: 3, 32768 bytes, linear) [ 0.010916] pinctrl core: initialized pinctrl subsystem [ 0.011823] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.012342] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations [ 0.012381] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.012413] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.012489] audit: initializing netlink subsys (disabled) [ 0.012693] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 [ 0.013129] i2c-core: driver [dummy] registered [ 0.013145] thermal_sys: Registered thermal governor 'fair_share' [ 0.013150] thermal_sys: Registered thermal governor 'bang_bang' [ 0.013157] thermal_sys: Registered thermal governor 'step_wise' [ 0.013164] thermal_sys: Registered thermal governor 'user_space' [ 0.013485] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.013574] ASID allocator initialised with 65536 entries [ 0.031129] gpio-499 (asm_sel): hogged as output/high [ 0.051760] vgaarb: loaded [ 0.052072] SCSI subsystem initialized [ 0.052320] libata version 3.00 loaded. [ 0.052526] usbcore: registered new interface driver usbfs [ 0.052576] usbcore: registered new interface driver hub [ 0.052612] usbcore: registered new device driver usb [ 0.052701] pps_core: LinuxPPS API ver. 1 registered [ 0.052709] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.052727] PTP clock support registered [ 0.054271] clocksource: Switched to clocksource arch_sys_counter [ 0.061379] NET: Registered PF_INET protocol family [ 0.061528] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.062488] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) [ 0.062529] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.062638] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) [ 0.062745] TCP: Hash tables configured (established 8192 bind 8192) [ 0.062888] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.062923] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.063096] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.063665] RPC: Registered named UNIX socket transport module. [ 0.063682] RPC: Registered udp transport module. [ 0.063688] RPC: Registered tcp transport module. [ 0.063694] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.063708] PCI: CLS 0 bytes, default 64 [ 0.064226] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available [ 0.065241] Initialise system trusted keyrings [ 0.065451] workingset: timestamp_bits=46 max_order=18 bucket_order=0 [ 0.073665] NFS: Registering the id_resolver key type [ 0.073707] Key type id_resolver registered [ 0.073714] Key type id_legacy registered [ 0.073812] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.073822] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 0.106933] Key type asymmetric registered [ 0.106957] Asymmetric key parser 'x509' registered [ 0.107036] io scheduler mq-deadline registered [ 0.107045] io scheduler kyber registered [ 0.113290] mt-pmic-pwrap 10001000.pwrap: unexpected interrupt int=0x1 [ 0.131900] Serial: 8250/16550 driver, 3 ports, IRQ sharing disabled [ 0.133386] printk: console [ttyS0] disabled [ 0.153609] 11002000.serial: ttyS0 at MMIO 0x11002000 (irq = 126, base_baud = 1562500) is a ST16650V2 [ 0.861123] printk: console [ttyS0] enabled [ 0.866405] 1100c000.serial: ttyS1 at MMIO 0x1100c000 (irq = 132, base_baud = 17499995) is a MediaTek BTIF [ 0.876226] serial serial0: tty port ttyS1 registered [ 0.882115] mtk_rng 1020f000.rng: registered RNG driver [ 0.882252] random: fast init done [ 0.890797] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 0.898931] libphy: Fixed MDIO Bus: probed [ 0.903898] random: crng init done [ 0.929149] libphy: mdio: probed [ 0.933261] mtk_soc_eth 1b100000.ethernet: generated random MAC address fa:20:a7:29:a2:f6 [ 0.941866] mtk_soc_eth 1b100000.ethernet eth0: mediatek frame engine at 0xffffffc010fe0000, irq 140 [ 0.951067] mtk_soc_eth 1b100000.ethernet: generated random MAC address 02:dc:25:af:76:c9 [ 0.959583] mtk_soc_eth 1b100000.ethernet eth1: mediatek frame engine at 0xffffffc010fe0000, irq 140 [ 0.969069] usbcore: registered new interface driver catc [ 0.974510] usbcore: registered new interface driver kaweth [ 0.980088] pegasus: Pegasus/Pegasus II USB Ethernet driver [ 0.985703] usbcore: registered new interface driver pegasus [ 0.991392] usbcore: registered new interface driver rtl8150 [ 0.997099] usbcore: registered new interface driver r8152 [ 1.002616] usbcore: registered new interface driver lan78xx [ 1.008347] usbcore: registered new interface driver asix [ 1.013778] usbcore: registered new interface driver ax88179_178a [ 1.019922] usbcore: registered new interface driver cdc_ether [ 1.025787] usbcore: registered new interface driver cdc_eem [ 1.031475] usbcore: registered new interface driver dm9601 [ 1.037075] usbcore: registered new interface driver sr9700 [ 1.042693] usbcore: registered new interface driver CoreChips [ 1.048568] usbcore: registered new interface driver smsc75xx [ 1.054353] usbcore: registered new interface driver smsc95xx [ 1.060128] usbcore: registered new interface driver gl620a [ 1.065727] usbcore: registered new interface driver net1080 [ 1.071419] usbcore: registered new interface driver plusb [ 1.076933] usbcore: registered new interface driver rndis_host [ 1.082892] usbcore: registered new interface driver cdc_subset [ 1.088842] usbcore: registered new interface driver zaurus [ 1.094443] usbcore: registered new interface driver MOSCHIP usb-ethernet driver [ 1.101875] usbcore: registered new interface driver int51x1 [ 1.107567] usbcore: registered new interface driver kalmia [ 1.113169] usbcore: registered new interface driver ipheth [ 1.118784] usbcore: registered new interface driver sierra_net [ 1.124733] usbcore: registered new interface driver cx82310_eth [ 1.130787] usbcore: registered new interface driver cdc_ncm [ 1.136476] usbcore: registered new interface driver huawei_cdc_ncm [ 1.142772] usbcore: registered new interface driver lg-vl600 [ 1.148547] usbcore: registered new interface driver qmi_wwan [ 1.154322] usbcore: registered new interface driver cdc_mbim [ 1.160101] usbcore: registered new interface driver ch9200 [ 1.165931] usbcore: registered new interface driver cdc_acm [ 1.171603] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters [ 1.179635] usbcore: registered new interface driver cdc_wdm [ 1.185336] usbcore: registered new interface driver usb-storage [ 1.192128] rtc_mt7622 10212800.rtc: registered as rtc0 [ 1.197413] rtc_mt7622 10212800.rtc: setting system clock to 2000-01-01T00:00:00 UTC (946684800) [ 1.206357] i2c_dev: i2c /dev entries driver [ 1.210938] i2c-mt65xx 11008000.i2c: clock-frequency: 100000 [ 1.216611] i2c-mt65xx 11008000.i2c: i2c-scl-rising-time-ns: 1000 [ 1.222711] i2c-mt65xx 11008000.i2c: i2c-scl-falling-time-ns: 300 [ 1.228814] i2c-mt65xx 11008000.i2c: i2c-scl-internal-delay-ns: 0 [ 1.234924] i2c-mt65xx 11008000.i2c: i2c-sda-falling-time-ns: 300 [ 1.241024] i2c-mt65xx 11008000.i2c: i2c-sda-hold-time-ns: 0 [ 1.246688] i2c-mt65xx 11008000.i2c: i2c-digital-filter-width-ns: 0 [ 1.252960] i2c-mt65xx 11008000.i2c: i2c-analog-filter-cutoff-frequency: 0 [ 1.260201] i2c_dev: adapter [i2c-mt65xx] registered as minor 0 [ 1.266180] i2c i2c-0: adapter [i2c-mt65xx] registered [ 1.271334] i2c i2c-0: of_i2c: walking child nodes [ 1.276356] i2c-mt65xx 11009000.i2c: clock-frequency: 100000 [ 1.282027] i2c-mt65xx 11009000.i2c: i2c-scl-rising-time-ns: 1000 [ 1.288127] i2c-mt65xx 11009000.i2c: i2c-scl-falling-time-ns: 300 [ 1.294226] i2c-mt65xx 11009000.i2c: i2c-scl-internal-delay-ns: 0 [ 1.300339] i2c-mt65xx 11009000.i2c: i2c-sda-falling-time-ns: 300 [ 1.306438] i2c-mt65xx 11009000.i2c: i2c-sda-hold-time-ns: 0 [ 1.312103] i2c-mt65xx 11009000.i2c: i2c-digital-filter-width-ns: 0 [ 1.318375] i2c-mt65xx 11009000.i2c: i2c-analog-filter-cutoff-frequency: 0 [ 1.325591] i2c_dev: adapter [i2c-mt65xx] registered as minor 1 [ 1.331565] i2c i2c-1: adapter [i2c-mt65xx] registered [ 1.336720] i2c i2c-1: of_i2c: walking child nodes [ 1.342674] mtk-wdt 10212000.watchdog: IRQ index 0 not found [ 1.348720] mtk-wdt 10212000.watchdog: Watchdog enabled (timeout=31 sec, nowayout=0) [ 1.359910] ledtrig-cpu: registered to indicate activity on CPUs [ 1.360408] mtk-msdc 11240000.mmc: Got CD GPIO [ 1.366546] SMCCC: SOC_ID: ID = jep106:0426:7622 Revision = 0x00000000 [ 1.377457] usbcore: registered new interface driver usbhid [ 1.383063] usbhid: USB HID core driver [ 1.389091] NET: Registered PF_INET6 protocol family [ 1.396016] Segment Routing with IPv6 [ 1.399890] In-situ OAM (IOAM) with IPv6 [ 1.404038] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.410897] NET: Registered PF_PACKET protocol family [ 1.416520] 8021q: 802.1Q VLAN Support v1.8 [ 1.421149] Loading compiled-in X.509 certificates [ 1.446376] mtk-pcie 1a140000.pcie: host bridge /pcie@1a140000 ranges: [ 1.452987] mtk-pcie 1a140000.pcie: Parsing ranges property... [ 1.458866] mtk-pcie 1a140000.pcie: MEM 0x0020000000..0x002fffffff -> 0x0020000000 [ 1.474128] mmc1: host does not support reading read-only switch, assuming write-enable [ 1.484219] mmc1: new high speed SDHC card at address b368 [ 1.491200] mmcblk1: mmc1:b368 LX32G 29.5 GiB [ 1.505215] GPT:Primary header thinks Alt. header is not at the end of the disk. [ 1.512675] GPT:15269887 != 61951999 [ 1.516273] GPT:Alternate GPT header not at the end of the disk. [ 1.522297] GPT:15269887 != 61951999 [ 1.525883] GPT: Use GNU Parted to correct GPT errors. [ 1.531081] mmcblk1: p1 p2 p3 [ 1.538876] mtk-msdc 11230000.mmc: phase: [map:ffffffff] [maxlen:32] [final:10] [ 1.547378] mmc0: new HS200 MMC card at address 0001 [ 1.553758] mmcblk0: mmc0:0001 008G30 7.28 GiB [ 1.564667] mmcblk0: p1 p2 [ 1.568888] mmcblk0boot0: mmc0:0001 008G30 4.00 MiB [ 1.577487] mmcblk0boot1: mmc0:0001 008G30 4.00 MiB [ 1.585166] mmcblk0rpmb: mmc0:0001 008G30 4.00 MiB, chardev (248:0) [ 1.590487] mtk-pcie 1a140000.pcie: Port1 link down [ 1.596508] mtk-pcie 1a140000.pcie: PCI host bridge to bus 0000:00 [ 1.602702] pci_bus 0000:00: root bus resource [bus 00-ff] [ 1.608196] pci_bus 0000:00: root bus resource [mem 0x20000000-0x2fffffff] [ 1.615077] pci_bus 0000:00: scanning bus [ 1.619141] pci 0000:00:00.0: [14c3:3258] type 01 class 0x060400 [ 1.625183] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x1ffffffff 64bit pref] [ 1.634023] pci_bus 0000:00: fixups for bus [ 1.638254] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 0 [ 1.644971] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring [ 1.652993] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 1 [ 1.659863] pci_bus 0000:01: scanning bus [ 1.664065] pci 0000:01:00.0: [14c3:7615] type 00 class 0x000280 [ 1.670272] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit] [ 1.678042] pci 0000:01:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s PCIe x1 link at 0000:00:00.0 (capable of 4.000 Gb/s with 5.0 GT/s PCIe x1 link) [ 1.703499] pci_bus 0000:01: fixups for bus [ 1.707711] pci_bus 0000:01: bus scan returning with max=01 [ 1.713291] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 [ 1.719920] pci_bus 0000:00: bus scan returning with max=01 [ 1.725514] pci 0000:00:00.0: BAR 0: no space for [mem size 0x200000000 64bit pref] [ 1.733178] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x200000000 64bit pref] [ 1.741188] pci 0000:00:00.0: BAR 8: assigned [mem 0x20000000-0x200fffff] [ 1.747988] pci 0000:01:00.0: BAR 0: assigned [mem 0x20000000-0x200fffff 64bit] [ 1.755390] pci 0000:00:00.0: PCI bridge to [bus 01] [ 1.760366] pci 0000:00:00.0: bridge window [mem 0x20000000-0x200fffff] [ 1.767608] mtk_hsdma 1b007000.dma-controller: Using 3 as missing dma-requests property [ 1.775983] mtk_hsdma 1b007000.dma-controller: MediaTek HSDMA driver registered [ 1.819707] mt7530 mdio-bus:00: no interrupt support [ 1.830874] libphy: mt7530-mii: probed [ 1.835851] mt7530 mdio-bus:00 wan (uninitialized): PHY [mt7530-0:00] driver [Generic PHY] (irq=POLL) [ 1.846692] mt7530 mdio-bus:00 lan0 (uninitialized): PHY [mt7530-0:01] driver [Generic PHY] (irq=POLL) [ 1.857556] mt7530 mdio-bus:00 lan1 (uninitialized): PHY [mt7530-0:02] driver [Generic PHY] (irq=POLL) [ 1.868349] mt7530 mdio-bus:00 lan2 (uninitialized): PHY [mt7530-0:03] driver [Generic PHY] (irq=POLL) [ 1.879203] mt7530 mdio-bus:00 lan3 (uninitialized): PHY [mt7530-0:04] driver [Generic PHY] (irq=POLL) [ 1.890094] mt7530 mdio-bus:00: configuring for fixed/2500base-x link mode [ 1.898238] mt7530 mdio-bus:00: Link is Up - 2.5Gbps/Full - flow control rx/tx [ 1.902457] DSA: tree 0 setup [ 1.909674] xhci-mtk 1a0c0000.usb: xHCI Host Controller [ 1.914951] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 1 [ 1.924039] xhci-mtk 1a0c0000.usb: hcc params 0x01403198 hci version 0x96 quirks 0x0000000000210010 [ 1.933153] xhci-mtk 1a0c0000.usb: irq 136, io mem 0x1a0c0000 [ 1.939845] hub 1-0:1.0: USB hub found [ 1.943648] hub 1-0:1.0: 2 ports detected [ 1.948088] xhci-mtk 1a0c0000.usb: xHCI Host Controller [ 1.953335] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 2 [ 1.960749] xhci-mtk 1a0c0000.usb: Host supports USB 3.0 SuperSpeed [ 1.967092] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 1.975977] hub 2-0:1.0: USB hub found [ 1.979776] hub 2-0:1.0: 1 port detected [ 1.984917] input: gpio-keys as /devices/platform/gpio-keys/input/input0 [ 2.020886] F2FS-fs (mmcblk1p1): Found nat_bits in checkpoint [ 2.143340] F2FS-fs (mmcblk1p1): Mounted with checkpoint version = 1dbb6660 [ 2.150406] VFS: Mounted root (f2fs filesystem) on device 179:1. [ 2.158374] devtmpfs: mounted [ 2.162400] Freeing unused kernel memory: 2048K [ 2.167004] Run /sbin/init as init process [ 2.171107] with arguments: [ 2.174069] /sbin/init [ 2.176778] with environment: [ 2.179922] HOME=/ [ 2.182285] TERM=linux [ 2.760820] systemd[1]: System time before build time, advancing clock. [ 2.831159] systemd[1]: systemd 249.3-1-arch running in system mode (+PAM +AUDIT -SELINUX -APPARMOR -IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) [ 2.862339] systemd[1]: Detected architecture arm64. [ 2.887332] systemd[1]: Hostname set to . [ 3.183698] systemd-fstab-generator[90]: Checking was requested for "dummy", but it is not a device. [ 3.552782] systemd[1]: Queued start job for default target Graphical Interface. [ 3.564754] systemd[1]: Created slice Slice /system/getty. [ 3.587959] systemd[1]: Created slice Slice /system/modprobe. [ 3.612170] systemd[1]: Created slice Slice /system/serial-getty. [ 3.635721] systemd[1]: Created slice User and Session Slice. [ 3.658827] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 3.682773] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 3.706574] systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. [ 3.718411] systemd[1]: Reached target Local Encrypted Volumes. [ 3.738600] systemd[1]: Reached target Path Units. [ 3.758483] systemd[1]: Reached target Remote File Systems. [ 3.778450] systemd[1]: Reached target Slice Units. [ 3.798486] systemd[1]: Reached target Swaps. [ 3.818508] systemd[1]: Reached target Local Verity Integrity Protected Volumes. [ 3.842945] systemd[1]: Listening on Device-mapper event daemon FIFOs. [ 3.873349] systemd[1]: Listening on Process Core Dump Socket. [ 3.897287] systemd[1]: Listening on Journal Audit Socket. [ 3.919038] systemd[1]: Listening on Journal Socket (/dev/log). [ 3.943105] systemd[1]: Listening on Journal Socket. [ 3.965285] systemd[1]: Listening on Network Service Netlink Socket. [ 3.989182] systemd[1]: Listening on udev Control Socket. [ 4.010957] systemd[1]: Listening on udev Kernel Socket. [ 4.031030] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 4.043456] systemd[1]: Mounting POSIX Message Queue File System... [ 4.070502] systemd[1]: Mounting Kernel Debug File System... [ 4.090853] systemd[1]: Condition check resulted in Kernel Trace File System being skipped. [ 4.105432] systemd[1]: Mounting Temporary Directory /tmp... [ 4.131763] systemd[1]: Starting Create List of Static Device Nodes... [ 4.159467] systemd[1]: Starting Load Kernel Module configfs... [ 4.182967] systemd[1]: Starting Load Kernel Module drm... [ 4.207130] systemd[1]: Starting Load Kernel Module fuse... [ 4.230648] systemd[1]: Starting Setup MAC addresses... [ 4.252486] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 4.262857] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. [ 4.275839] systemd[1]: (This warning is only shown for the first unit using IP firewalling.) [ 4.288230] systemd[1]: Starting Journal Service... [ 4.314798] systemd[1]: Starting Load Kernel Modules... [ 4.338972] systemd[1]: Starting Remount Root and Kernel File Systems... [ 4.371784] systemd[1]: Condition check resulted in Repartition Root Disk being skipped. [ 4.395536] systemd[1]: Starting Coldplug All udev Devices... [ 4.438165] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 4.459089] systemd[1]: Mounted POSIX Message Queue File System. [ 4.491125] systemd[1]: Started Journal Service. [ 4.511100] audit: type=1130 audit(1628448944.748:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.556818] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 4.586705] audit: type=1130 audit(1628448944.824:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.619324] audit: type=1130 audit(1628448944.852:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.639806] audit: type=1131 audit(1628448944.852:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.670633] audit: type=1130 audit(1628448944.908:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.689199] audit: type=1131 audit(1628448944.908:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.714206] mt7615e 0000:01:00.0: assign IRQ: got 145 [ 4.719673] pci 0000:00:00.0: enabling device (0000 -> 0002) [ 4.725444] pci 0000:00:00.0: enabling bus mastering [ 4.730497] mt7615e 0000:01:00.0: enabling device (0000 -> 0002) [ 4.733077] audit: type=1130 audit(1628448944.968:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.736655] mt7615e 0000:01:00.0: enabling bus mastering [ 4.757614] audit: type=1131 audit(1628448944.968:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.760723] Unable to handle kernel NULL pointer dereference at virtual address 0000000000000000 [ 4.787884] Mem abort info: [ 4.787893] ESR = 0x96000005 [ 4.787898] EC = 0x25: DABT (current EL), IL = 32 bits [ 4.799083] SET = 0, FnV = 0 [ 4.802138] EA = 0, S1PTW = 0 [ 4.805295] FSC = 0x05: level 1 translation fault [ 4.811583] Data abort info: [ 4.815832] ISV = 0, ISS = 0x00000005 [ 4.821058] CM = 0, WnR = 0 [ 4.825419] user pgtable: 4k pages, 39-bit VAs, pgdp=0000000045487000 [ 4.825667] audit: type=1130 audit(1628448945.064:10): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=setmac comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.831913] [0000000000000000] pgd=0000000000000000, p4d=0000000000000000, pud=0000000000000000 [ 4.859611] Internal error: Oops: 96000005 [#1] PREEMPT SMP [ 4.859624] Modules linked in: mt7615e(+) mt7615_common mt76_connac_lib mt76 mac80211 libarc4 sha256_generic libsha256 cfg80211 rfkill ip_tables x_tables [ 4.859673] CPU: 0 PID: 111 Comm: systemd-modules Tainted: G S 5.15.0-rc1-bpi-r64-0 #3 [ 4.859682] Hardware name: Bananapi BPI-R64 (DT) [ 4.859688] pstate: 20000005 (nzCv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--) [ 4.859696] pc : mtk_compose_msi_msg+0x78/0x94 [ 4.859715] lr : irq_chip_compose_msi_msg+0x30/0x64 [ 4.859727] sp : ffffffc0111336b0 [ 4.859730] x29: ffffffc0111336b0 x28: 0000000000000001 x27: ffffffc010d359f8 [ 4.859744] x26: ffffffc010947d73 x25: ffffff8005614f80 x24: 0000000000000000 [ 4.859756] x23: ffffff8000cfdad8 x22: 0000000000000000 x21: ffffff8000e6d800 [ 4.859769] x20: ffffff80047b4a30 x19: ffffff80047b4a30 x18: 0000000000000000 [ 4.859782] x17: 0000000000000030 x16: 2e30303a31303a30 x15: 0000000000012cc0 [ 4.859794] x14: 0000000000000000 x13: 0000000000000002 x12: 0000000000039444 [ 4.859807] x11: 0000000000000801 x10: ffffffc010cb9008 x9 : 0000000000000092 [ 4.859820] x8 : ffffffc010cb9000 x7 : 0000000000000000 x6 : 00000040106a80b8 [ 4.859832] x5 : 00000000424a80b8 x4 : 0000000000000000 x3 : 0000000000000000 [ 4.859844] x2 : ffffffc01097c7a5 x1 : 0000000000000000 x0 : ffffffc010d39000 [ 4.859858] Call trace: [ 4.859862] mtk_compose_msi_msg+0x78/0x94 [ 4.859872] irq_chip_compose_msi_msg+0x30/0x64 [ 4.859881] msi_domain_activate+0x38/0x88 [ 4.859890] __irq_domain_activate_irq+0x68/0x94 [ 4.859897] irq_domain_activate_irq+0x4c/0x54 [ 4.859904] __msi_domain_alloc_irqs+0x22c/0x2c8 [ 4.859912] msi_domain_alloc_irqs+0x1c/0x28 [ 4.859920] pci_msi_setup_msi_irqs.isra.0+0x2c/0x44 [ 4.859930] __pci_enable_msi_range+0x230/0x334 [ 4.859939] pci_alloc_irq_vectors_affinity+0xc4/0x11c [ 4.859947] mt7615_pci_probe+0x6c/0xf4 [mt7615e] [ 4.859970] pci_device_probe+0xbc/0x14c [ 4.859980] really_probe.part.0+0x198/0x388 [ 4.859989] really_probe+0x78/0x7c [ 4.859996] __driver_probe_device+0x14c/0x164 [ 4.860003] driver_probe_device+0x4c/0xe4 [ 4.860010] __driver_attach+0x154/0x164 [ 4.860018] bus_for_each_dev+0x70/0xc0 [ 4.860024] driver_attach+0x24/0x30 [ 4.860031] bus_add_driver+0x1a4/0x1ec [ 4.860038] driver_register+0xb4/0xec [ 4.860045] __pci_register_driver+0x4c/0x58 [ 4.860055] mt7615_init+0x38/0x1000 [mt7615e] [ 4.860071] do_one_initcall+0x78/0x1a0 [ 4.860080] do_init_module+0x5c/0x1e8 [ 4.860090] load_module+0x11ac/0x1278 [ 4.860098] __do_sys_finit_module+0xdc/0x100 [ 4.860108] __arm64_sys_finit_module+0x1c/0x28 [ 4.860116] invoke_syscall+0x6c/0xf8 [ 4.860126] el0_svc_common.constprop.0+0xb0/0xdc [ 4.860135] do_el0_svc+0x44/0x74 [ 4.860144] el0_svc+0x20/0x4c [ 4.860152] el0t_64_sync_handler+0xcc/0x154 [ 4.860160] el0t_64_sync+0x1a0/0x1a4 [ 4.860172] Code: b9400803 d0002c02 f00049e0 911e9442 (f9400021) [ 4.860178] ---[ end trace 710367f9cc9d647a ]--- [ 5.158576] audit: type=1130 audit(1628448945.396:11): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.210582] audit: type=1130 audit(1628448945.448:12): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed' [ 5.275612] systemd-journald[110]: Received client request to flush runtime journal. [ 5.302571] systemd-journald[110]: File /var/log/journal/5ac75b9c820c43ff8ad84bba5d33192e/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 5.381292] audit: type=1130 audit(1628448945.616:13): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.421188] audit: type=1130 audit(1628448945.652:14): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.570629] audit: type=1130 audit(1628448945.808:15): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.606627] audit: type=1130 audit(1628448945.844:16): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.642543] audit: type=1130 audit(1628448945.880:17): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.870511] audit: type=1130 audit(1628448946.104:18): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.332894] Bluetooth: Core ver 2.22 [ 6.359402] NET: Registered PF_BLUETOOTH protocol family [ 6.374407] Bluetooth: HCI device and connection manager initialized [ 6.449276] Bluetooth: HCI socket layer initialized [ 6.487988] Bluetooth: L2CAP socket layer initialized [ 6.519469] Bluetooth: SCO socket layer initialized [ 6.958674] audit: type=1130 audit(1628448947.196:19): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 11.948186] audit: type=1131 audit(1628448952.184:20): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 16.606289] Bluetooth: hci0: Execution of wmt command timed out [ 16.612243] Bluetooth: hci0: Failed to query firmware status (-110) [ 94.230645] audit: type=1130 audit(1628449034.468:21): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 94.301119] audit: type=1127 audit(1628449034.536:22): pid=162 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' [ 94.338776] audit: type=1130 audit(1628449034.576:23): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 94.570640] audit: type=1130 audit(1638103150.347:24): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 94.796810] audit: type=1130 audit(1638103150.571:25): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dbus comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 95.199099] audit: type=1130 audit(1638103150.975:26): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-logind comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 95.756424] audit: type=1325 audit(1638103150.939:27): table=nat:2 family=1 entries=8 op=nft_register_chain pid=166 comm="nft" [ 95.768377] audit: type=1325 audit(1638103150.939:27): table=filter:2 family=1 entries=20 op=nft_register_chain pid=166 comm="nft" [ 95.781294] audit: type=1300 audit(1638103150.939:27): arch=c00000b7 syscall=211 success=yes exit=4036 a0=3 a1=7fe7ec58c0 a2=0 a3=7fb9d60010 items=0 ppid=1 pid=166 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="nft" exe="/usr/bin/nft" key=(null) [ 95.809418] audit: type=1327 audit(1638103150.939:27): proctitle=2F7573722F62696E2F6E6674002D66002F6574632F6E667461626C65732E636F6E66 [ 96.122122] brlan: port 1(aux) entered blocking state [ 96.127420] brlan: port 1(aux) entered disabled state [ 96.141006] brlan: port 2(lan3) entered blocking state [ 96.146476] brlan: port 2(lan3) entered disabled state [ 96.155333] device lan3 entered promiscuous mode [ 96.160215] device aux entered promiscuous mode [ 96.169555] brlan: port 3(lan2) entered blocking state [ 96.174802] brlan: port 3(lan2) entered disabled state [ 96.182420] device lan2 entered promiscuous mode [ 96.191923] brlan: port 4(lan1) entered blocking state [ 96.197244] brlan: port 4(lan1) entered disabled state [ 96.204817] device lan1 entered promiscuous mode [ 96.214253] brlan: port 5(lan0) entered blocking state [ 96.219587] brlan: port 5(lan0) entered disabled state [ 96.227188] device lan0 entered promiscuous mode [ 96.236693] brlan: port 6(wan) entered blocking state [ 96.241870] brlan: port 6(wan) entered disabled state [ 96.249376] device wan entered promiscuous mode [ 96.272861] mtk_soc_eth 1b100000.ethernet eth1: configuring for fixed/rgmii link mode [ 96.281113] mtk_soc_eth 1b100000.ethernet eth1: Link is Up - 1Gbps/Full - flow control rx/tx [ 96.292664] mtk_soc_eth 1b100000.ethernet eth0: configuring for fixed/2500base-x link mode [ 96.301102] device eth0 entered promiscuous mode [ 96.305912] mtk_soc_eth 1b100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control rx/tx [ 96.383702] mt7530 mdio-bus:00 aux: configuring for fixed/rgmii link mode [ 96.391447] mt7530 mdio-bus:00 aux: Link is Up - 1Gbps/Full - flow control rx/tx [ 96.401336] 8021q: adding VLAN 0 to HW filter on device aux [ 96.409478] IPv6: ADDRCONF(NETDEV_CHANGE): aux: link becomes ready [ 96.416480] brlan: port 1(aux) entered blocking state [ 96.421630] brlan: port 1(aux) entered forwarding state [ 96.427787] IPv6: ADDRCONF(NETDEV_CHANGE): brlan: link becomes ready [ 96.435134] mt7530 mdio-bus:00 lan3: configuring for phy/gmii link mode [ 96.443304] 8021q: adding VLAN 0 to HW filter on device lan3 [ 96.455219] mt7530 mdio-bus:00 lan2: configuring for phy/gmii link mode [ 96.466239] 8021q: adding VLAN 0 to HW filter on device lan2 [ 96.476899] mt7530 mdio-bus:00 lan1: configuring for phy/gmii link mode [ 96.485363] 8021q: adding VLAN 0 to HW filter on device lan1 [ 96.495652] mt7530 mdio-bus:00 lan0: configuring for phy/gmii link mode [ 96.505480] 8021q: adding VLAN 0 to HW filter on device lan0 [ 96.515605] mt7530 mdio-bus:00 wan: configuring for phy/gmii link mode [ 96.523786] 8021q: adding VLAN 0 to HW filter on device wan [ 96.527392] mt7530 mdio-bus:00 wan: Link is Up - 100Mbps/Full - flow control rx/tx [ 96.537088] IPv6: ADDRCONF(NETDEV_CHANGE): wan: link becomes ready [ 96.543685] brlan: port 6(wan) entered blocking state [ 96.548863] brlan: port 6(wan) entered forwarding state [ 101.199800] kauditd_printk_skb: 26 callbacks suppressed [ 101.199823] audit: type=1130 audit(1638103156.975:41): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 107.498928] audit: type=1100 audit(1638103163.275:42): pid=236 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/login" hostname=bpi-r64 addr=? terminal=/dev/ttyS0 res=success' [ 107.524410] audit: type=1101 audit(1638103163.275:43): pid=236 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="root" exe="/usr/bin/login" hostname=bpi-r64 addr=? terminal=/dev/ttyS0 res=success' [ 107.548639] audit: type=1103 audit(1638103163.275:44): pid=236 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/login" hostname=bpi-r64 addr=? terminal=/dev/ttyS0 res=success' [ 107.575509] audit: type=1006 audit(1638103163.275:45): pid=236 uid=0 old-auid=4294967295 auid=0 tty=ttyS0 old-ses=4294967295 ses=1 res=1 [ 107.592645] audit: type=1300 audit(1638103163.275:45): arch=c00000b7 syscall=64 success=yes exit=1 a0=4 a1=7ff38ef330 a2=1 a3=7f7f810bd0 items=0 ppid=1 pid=236 auid=0 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=ttyS0 ses=1 comm="login" exe="/usr/bin/login" key=(null) [ 107.621800] audit: type=1327 audit(1638103163.275:45): proctitle=2F62696E2F6C6F67696E002D70002D2D [ 107.638983] audit: type=1130 audit(1638103163.411:46): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 107.670871] audit: type=1101 audit(1638103163.443:47): pid=342 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 107.696113] audit: type=1103 audit(1638103163.447:48): pid=342 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed' [ 107.718982] audit: type=1006 audit(1638103163.447:49): pid=342 uid=0 old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=2 res=1 [ 131.227460] kauditd_printk_skb: 6 callbacks suppressed [ 131.227481] audit: type=1131 audit(1638103667.200:54): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 186.956394] audit: type=1130 audit(1638103722.929:55): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=hostapd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed' [ 186.988116] audit: type=1130 audit(1638103722.961:56): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=ssh-fix-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 189.204526] audit: type=1130 audit(1638103725.173:57): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=hostapd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 189.227480] audit: type=1131 audit(1638103725.173:58): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=hostapd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 269.975171] usb 2-1: new SuperSpeed USB device number 2 using xhci-mtk [ 270.006845] usb-storage 2-1:1.0: USB Mass Storage device detected [ 270.015954] scsi host0: usb-storage 2-1:1.0 [ 271.039987] scsi 0:0:0:0: Direct-Access Generic STORAGE DEVICE 1532 PQ: 0 ANSI: 6 [ 271.158042] sd 0:0:0:0: [sda] 62720 512-byte logical blocks: (32.1 MB/30.6 MiB) [ 271.166381] sd 0:0:0:0: [sda] Write Protect is off [ 271.171235] sd 0:0:0:0: [sda] Mode Sense: 21 00 00 00 [ 271.176855] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 271.209876] sda: sda1 [ 271.218672] sd 0:0:0:0: [sda] Attached SCSI removable disk [ 279.457922] audit: type=1130 audit(1638103815.428:59): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=hostapd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed' [ 281.704678] audit: type=1130 audit(1638103817.676:60): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=hostapd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 281.725229] audit: type=1131 audit(1638103817.676:61): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=hostapd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' ```

frank-w commented 2 years ago

so we have a NULL-pointer dereference in mtk_compose_msi_msg

can you try using 5.15.0 final instead of rc1?

this function was introduced by this commit (long time ago):

42fe2f91b4eb 2018-05-04 PCI: mediatek: Implement chained IRQ handling setup

https://elixir.bootlin.com/linux/v5.16-rc2/source/drivers/pci/controller/pcie-mediatek.c#L401

so we need to find out which line here causing the crash

you could put a line like this before/after each existing

printk(KERN_ALERT "DEBUG: Passed %s %d \n",__FUNCTION__,__LINE__);
ericwoud commented 2 years ago

I see mt7622-wmac_rf.bin is missing from /lib/firmware/mediatek

Maybe this is it?

frank-w commented 2 years ago

*_rf.bin is data to override eeprom, not needed

it looks like pcie related, but imho mt7622_wmac is not attached to pcie, but mt7615 driver (which is core for mt7622) seems to check it and crash there because of null pointer

please try to upgrade to 5.15.x without rc and if this does not help try to debug which value is NULL/line where crash happens

something like this:

diff --git a/drivers/pci/controller/pcie-mediatek.c b/drivers/pci/controller/pcie-mediatek.c
index 2f3f974977a3..bc31d99c16e4 100644
--- a/drivers/pci/controller/pcie-mediatek.c
+++ b/drivers/pci/controller/pcie-mediatek.c
@@ -400,18 +400,20 @@ static struct pci_ops mtk_pcie_ops_v2 = {

 static void mtk_compose_msi_msg(struct irq_data *data, struct msi_msg *msg)
 {
+printk(KERN_ALERT "DEBUG: Passed %s %d data: 0x%p \n",__FUNCTION__,__LINE__,data);
        struct mtk_pcie_port *port = irq_data_get_irq_chip_data(data);
        phys_addr_t addr;
-
+printk(KERN_ALERT "DEBUG: Passed %s %d 0x%p\n",__FUNCTION__,__LINE__,port);
        /* MT2712/MT7622 only support 32-bit MSI addresses */
        addr = virt_to_phys(port->base + PCIE_MSI_VECTOR);
        msg->address_hi = 0;
        msg->address_lo = lower_32_bits(addr);
-
+printk(KERN_ALERT "DEBUG: Passed %s %d 0x%p\n",__FUNCTION__,__LINE__,data);
        msg->data = data->hwirq;
-
+printk(KERN_ALERT "DEBUG: Passed %s %d port-pcie:0x%p msg:0x%p\n",__FUNCTION__,__LINE__,port->pcie,msg);
        dev_dbg(port->pcie->dev, "msi#%d address_hi %#x address_lo %#x\n",
                (int)data->hwirq, msg->address_hi, msg->address_lo);
+printk(KERN_ALERT "DEBUG: Passed %s %d \n",__FUNCTION__,__LINE__);
 }

 static int mtk_msi_set_affinity(struct irq_data *irq_data,
ericwoud commented 2 years ago

Weird, I'm running on the 5.15-rc1 for a long time now...

Although I'm not using that image, but build one again from the script...

Is there hardware plugged in any pcie slot?

vycb commented 2 years ago

Is there hardware plugged in any pcie slot?

Yes, It's R64 with mt7615 module

ericwoud commented 2 years ago

Did you try without the module ? Note that you do still need to add the rf.bin manually

vycb commented 2 years ago

Did you try without the module ? Note that you do still need to add the rf.bin manually

I'm going to use R64 with mt7615, as suggested BPI-SINOVOIP. Original mediatec firmware from an image also didn't get wify:

mt7615e_rf.bin mt7622-wmac_rf.bin mt7622_n9.bin mt7622_rom_patch.bin

so I did try from linux git

ericwoud commented 2 years ago

mt7615_cr4.bin mt7615_n9.bin mt7615_rom_patch.bin

In the last list you then would also need these

frank-w commented 2 years ago

have tested r64 with mt7615 in cn25 and my own debian bulllseye image with my kernel:

root@bpi-r64:~# uname -a
Linux bpi-r64 5.15.0-bpi-r64-main #1 SMP PREEMPT Sat Nov 27 14:56:12 CET 2021 aarch64 GNU/Linux
root@bpi-r64:~# ip a | grep wl
12: wlp1s0: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
15: wlan1: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
root@bpi-r64:~# dmesg | grep -i 'pcie\|mt7615\|mt7622'
[    0.000000] Kernel command line: board=bpi-r64 console=ttyS0,115200n1 earlyprintk root=/dev/mmcblk1p5 rootfstype=ext4 rootwait serv2
[    1.299058] rtc_mt7622 10212800.rtc: registered as rtc0
[    1.304320] rtc_mt7622 10212800.rtc: setting system clock to 2000-01-01T00:00:00 UTC (946684800)
[    1.557232] mtk-pcie 1a143000.pcie: host bridge /pcie@1a143000 ranges:
[    1.564006] mtk-pcie 1a143000.pcie: Parsing ranges property...
[    1.575502] mtk-pcie 1a143000.pcie:      MEM 0x0020000000..0x0027ffffff -> 0x0020000000
[    1.608778] mtk-pcie 1a143000.pcie: PCI host bridge to bus 0000:00
[    1.724891] pci 0000:01:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s PCIe x1 link at 0000:00:00.0 (capable of 4.0)
[    1.816429] mtk-pcie 1a145000.pcie: host bridge /pcie@1a145000 ranges:
[    1.823054] mtk-pcie 1a145000.pcie: Parsing ranges property...
[    1.828901] mtk-pcie 1a145000.pcie:      MEM 0x0028000000..0x002fffffff -> 0x0028000000
[    1.937237] mtk-pcie 1a145000.pcie: Port1 link down
[    1.942316] mtk-pcie 1a145000.pcie: PCI host bridge to bus 0001:00
[    6.357204] mt7615e 0000:01:00.0: assign IRQ: got 146
[    6.473149] mt7615e 0000:01:00.0: enabling device (0000 -> 0002)
[    6.509742] mt7615e 0000:01:00.0: enabling bus mastering
[    6.530775] mtk-pcie 1a143000.pcie: msi#0 address_hi 0x0 address_lo 0x40ebd0c0
[    6.579444] mt7615e 0000:01:00.0: Load eeprom: /lib/firmware/mediatek/mt7615e_rf.bin
[    6.600582] mt7615e 0000:01:00.0: Open eeprom file failed: /lib/firmware/mediatek/mt7615e_rf.bin
[    6.771994] mt7622-wmac 18000000.wmac: Load eeprom: /lib/firmware/mediatek/mt7622-wmac_rf.bin
[    6.793776] mt7622-wmac 18000000.wmac: Load eeprom OK, count 1024 byte
[    6.939567] mt7622-wmac 18000000.wmac: N9 Firmware Version: _reserved_, Build Time: 20190801210351
[    7.037018] mt7615e 0000:01:00.0 wlp1s0: renamed from wlan0
root@bpi-r64:~# 

maybe eric misses the pcie-patches für r64? afair they are mainline with 5.16

https://github.com/frank-w/BPI-R2-4.14/commits/5.15-main

ericwoud commented 2 years ago

Indeed there are no PCIe patches, I do not have the means to test them. One could add the patches to the linux-5.xx.x directory. Then build an image using the script.

Or change the Linux version to 5.16-rc3 in the script and rename the linux-5.15.-rc1 directory accordingly. Then build an image with the script.

ericwoud commented 2 years ago

The script is now changed to 5.16-rc3, only need to build it and try:

./build.sh -SD
./build.sh
vycb commented 2 years ago

./build.sh -SD ./build.sh

tried to build ubuntu-5.16-rc3-bpir64-sdmmc.img as USE_LOOPDEV="true", but the image in result not bootable. Looks like it's too small in size:

6.5M Nov 30 20:12  ubuntu-5.16-rc3-bpir64-sdmmc.img.xz

 compare to original:

187M Nov 27 09:32  arch-5.15.rc3-bpir64-sdmmc.img.xz
ericwoud commented 2 years ago

Delete the whole buildR64ubuntu folder, clone again.

./build.sh -a

First. This will install all necessary packages, you might not have some installed yet.

vycb commented 2 years ago

Problem size was fixed but SDcard still not bootable, however image looks normal. To build the image I use VM - QEMU KVM Ubuntu Focal. To review the image: https://drive.google.com/drive/folders/1Y5PpFmqk4wBDKWOci2WwcFM5OZ6_KQ0b?usp=sharing

build -a Output

``` build -a Target device=sdmmc Not running on Bananapi BPI-R64 Reading package lists... Building dependency tree... Reading state information... bc is already the newest version (1.07.1-2build1). bison is already the newest version (2:3.5.1+dfsg-1). flex is already the newest version (2.6.4-6.2). gzip is already the newest version (1.10-0ubuntu4). libncurses-dev is already the newest version (6.2-0ubuntu2). zerofree is already the newest version (1.1.1-1). arch-install-scripts is already the newest version (23-1). f2fs-tools is already the newest version (1.11.0-1.1ubuntu1). symlinks is already the newest version (1.4-4). build-essential is already the newest version (12.8ubuntu1.1). ca-certificates is already the newest version (20210119~20.04.2). debootstrap is already the newest version (1.0.118ubuntu1.5). git is already the newest version (1:2.25.1-1ubuntu3.2). libssl-dev is already the newest version (1.1.1f-1ubuntu2.9). parted is already the newest version (3.3-4ubuntu0.20.04.1). u-boot-tools is already the newest version (2021.01+dfsg-3ubuntu0~20.04.3). udisks2 is already the newest version (2.8.4-1ubuntu2). wget is already the newest version (1.20.3-1ubuntu2). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Reading package lists... Building dependency tree... Reading state information... gcc-aarch64-linux-gnu is already the newest version (4:9.3.0-1ubuntu2). libc6:i386 is already the newest version (2.31-0ubuntu9.2). qemu-user-static is already the newest version (1:4.2-3ubuntu6.18). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. basename: missing operand Try 'basename --help' for more information. OPTIONS: rootfs= boot= kernel= tar= apt=true SETUP=RT Rootfsdir=/mnt/bpirootfs Src=/mnt/bpirootfs/usr/src Crossc=CROSS_COMPILE=aarch64-linux-gnu- Device=/dev/loop0 sectorsize=512 Mountdev=/dev/loop0p1 Makej=-j4 Running exit function to clean up... /mnt/bpirootfs is a mountpoint Unmounting...DO NOT REMOVE! Done. You can remove the card now. ```

ericwoud commented 2 years ago

Hmz, I have just build an image running focal and it boots fine.

What do the build.log and build-error.log look like, when you try building again (with files of last attempt in tact):

./build.sh

The logs should give my some clue as what is going wrong.

What does the debug output look like when booting?

Please enclose output with three time the character: `

Did you write the .img.xz with:

xz -dcv XXX.img.xz | sudo dd of=/dev/sdX
vycb commented 2 years ago

xz -dcv XXX.img.xz | sudo dd of=/dev/sdX

I decompress to XXX.img and use bpi-copy

What does the debug output look like when booting?

card not boot at all - starts booting emmc like no SDcard at all.

build-error.log on Ubuntu VM:

basename: missing operand
rmdir: failed to remove '/mnt/bpirootfs/usr/src': No such file or directory
./build.sh: line 452: ./atf-mtksoc/*.bash: No such file or directory

I created Arch linux VM-qemu and now I'm in process of building image

vycb commented 2 years ago

tried Third Release Candidate, boot stops on error:

[    2.242764] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(179,1)
[    2.251196] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G S                5.16.0-rc3-bpi-r64-0 #1
[    2.259716] Hardware name: Bananapi BPI-R64 (DT)
[    2.264326] Call trace:
[    2.266764]  dump_backtrace+0x0/0x1c4
[    2.270432]  show_stack+0x18/0x64
[    2.273744]  dump_stack_lvl+0x68/0x84
[    2.277404]  dump_stack+0x18/0x34
[    2.280714]  panic+0x130/0x2e8
[    2.283768]  mount_block_root+0x174/0x1f4
[    2.287773]  mount_root+0x8c/0x16c
[    2.291169]  prepare_namespace+0x150/0x160
[    2.295260]  kernel_init_freeable+0x230/0x280
[    2.299611]  kernel_init+0x24/0x124
[    2.303095]  ret_from_fork+0x10/0x20
[    2.306669] SMP: stopping secondary CPUs
[    2.310587] Kernel Offset: disabled
[    2.314067] CPU features: 0x0,00006002,00000842
[    2.318591] Memory Limit: none
[    2.321642] ---[ end Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(179,1) ]---
vycb commented 2 years ago

arch 5.16-rc3 build,sh was successful. SDcard boots quickly.

On R64 with minicom I can't check network status, after command ip a console stops respond to minicom, restart is required.

ericwoud commented 2 years ago

I refreshed the image, so should be no more bad block.

I'll check if it hangs here tomorrow...

vycb commented 2 years ago

Image Third Release Candidate boot without the error.

However R64 console hangs on command ip a, PS1 doesn't return, only debug output printed:

[  552.034915] audit: type=1131 audit(1637538080.436:72): pid=1 uid=0 auid=4294967295 ses'
.....
ericwoud commented 2 years ago

Still need to disable the audit messages in the kernel bootargs.... That will disable the audit messages.

They are not a message of any error. Is it really stuck after getting an audit message? Can you press enter and get back to the prompt?

I've tried the image (downloaded again) and it works fine. 'ip a' and 'iw dev' and 'systemctl status hostapd' show everything ok.

You could try connecting via SSH over lan or wifi. Maybe the serial link is faulty...

vycb commented 2 years ago

3d release build and my build 5.16.rc3 are same after 'ip a' and 'iw dev' no return and Ctr-c not help, only restart. Also no network. no ping any.

[root@bpir64 ~]# ping 1.1.1.1
ping: connect: Network is unreachable

systemctl restart systemd-networkd

systemd-networkd.service: Found left-over prcess 233 (systemd-network) in control group while starting unit. Ignoring.
This usually indicates unclean termination of a previous run, or service implementation deficiencies.
systemd-networkd.service: start operation timed out. Terminating.

I built linux-5.15 there is no such error, but 'iw dev' still shows empty

ericwoud commented 2 years ago

I guess still a PCIe related error. I cannot reproduce. What does dmesg output look like just after booting? Please post the whole output

vycb commented 2 years ago

dmesg output similar to above:

linux-5.15 dmesg output

``` [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 5.15.0-bpi-r64-0 (root@arch) (aarch64-linux-gnu-gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37) #1 SMP PREEMPT Thu Dec 2 12:22:18 UTC 2021 [ 0.000000] Machine model: Bananapi BPI-R64 [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x0000000042ffffff] [ 0.000000] node 0: [mem 0x0000000043000000-0x000000004302ffff] [ 0.000000] node 0: [mem 0x0000000043030000-0x000000007fffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: MIGRATE_INFO_TYPE not supported. [ 0.000000] psci: SMC Calling Convention v1.2 [ 0.000000] percpu: Embedded 17 pages/cpu s32216 r8192 d29224 u69632 [ 0.000000] pcpu-alloc: s32216 r8192 d29224 u69632 alloc=17*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: detected: ARM erratum 843419 [ 0.000000] CPU features: detected: ARM erratum 845719 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 258048 [ 0.000000] Kernel command line: console=ttyS0,115200 rw rootwait root=PARTLABEL=root-bpir64-sdmmc [ 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: 1015300K/1048576K available (6976K kernel code, 1160K rwdata, 2364K rodata, 1856K init, 290K bss, 33276K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 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] Root IRQ handler: gic_handle_irq [ 0.000000] random: get_random_bytes called from start_kernel+0x350/0x4e0 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.000001] sched_clock: 56 bits at 12MHz, resolution 80ns, wraps every 4398046511080ns [ 0.000288] Console: colour dummy device 80x25 [ 0.000330] Calibrating delay loop (skipped), value calculated using timer frequency.. 25.00 BogoMIPS (lpj=50000) [ 0.000345] pid_max: default: 32768 minimum: 301 [ 0.000469] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.000487] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.002115] rcu: Hierarchical SRCU implementation. [ 0.002969] smp: Bringing up secondary CPUs ... [ 0.003576] Detected VIPT I-cache on CPU1 [ 0.003591] CPU features: SANITY CHECK: Unexpected variation in (((3) << 19) | ((3) << 16) | ((14) << 12) | ((0) << 8) | ((0) << 5)). Boot CPU: 0x00000000bebc20, CPU1: 0x00000000000000 [ 0.003623] CPU features: Unsupported CPU feature variation detected. [ 0.003666] CPU1: Booted secondary processor 0x0000000001 [0x410fd034] [ 0.003791] smp: Brought up 1 node, 2 CPUs [ 0.003814] SMP: Total of 2 processors activated. [ 0.003821] CPU features: detected: 32-bit EL0 Support [ 0.003829] CPU features: detected: CRC32 instructions [ 0.004549] CPU: All CPU(s) started at EL2 [ 0.004571] alternatives: patching kernel code [ 0.005366] devtmpfs: initialized [ 0.011171] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.011204] futex hash table entries: 512 (order: 3, 32768 bytes, linear) [ 0.011304] pinctrl core: initialized pinctrl subsystem [ 0.012259] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.012759] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations [ 0.012798] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.012830] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.012901] audit: initializing netlink subsys (disabled) [ 0.013106] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 [ 0.013559] i2c-core: driver [dummy] registered [ 0.013573] thermal_sys: Registered thermal governor 'fair_share' [ 0.013578] thermal_sys: Registered thermal governor 'bang_bang' [ 0.013585] thermal_sys: Registered thermal governor 'step_wise' [ 0.013592] thermal_sys: Registered thermal governor 'user_space' [ 0.013908] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.014003] ASID allocator initialised with 65536 entries [ 0.031943] gpio-499 (asm_sel): hogged as output/high [ 0.052188] vgaarb: loaded [ 0.052600] SCSI subsystem initialized [ 0.052812] libata version 3.00 loaded. [ 0.053014] usbcore: registered new interface driver usbfs [ 0.053056] usbcore: registered new interface driver hub [ 0.053093] usbcore: registered new device driver usb [ 0.053171] pps_core: LinuxPPS API ver. 1 registered [ 0.053178] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.053197] PTP clock support registered [ 0.054770] clocksource: Switched to clocksource arch_sys_counter [ 0.062075] NET: Registered PF_INET protocol family [ 0.062230] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.063205] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) [ 0.063246] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.063373] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) [ 0.063477] TCP: Hash tables configured (established 8192 bind 8192) [ 0.063619] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.063648] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.063820] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.063851] PCI: CLS 0 bytes, default 64 [ 0.064446] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available [ 0.065502] Initialise system trusted keyrings [ 0.065723] workingset: timestamp_bits=46 max_order=18 bucket_order=0 [ 0.105912] Key type asymmetric registered [ 0.105939] Asymmetric key parser 'x509' registered [ 0.106009] io scheduler mq-deadline registered [ 0.106018] io scheduler kyber registered [ 0.112882] mt-pmic-pwrap 10001000.pwrap: unexpected interrupt int=0x1 [ 0.132032] Serial: 8250/16550 driver, 3 ports, IRQ sharing disabled [ 0.133606] printk: console [ttyS0] disabled [ 0.153826] 11002000.serial: ttyS0 at MMIO 0x11002000 (irq = 126, base_baud = 1562500) is a ST16650V2 [ 0.808687] printk: console [ttyS0] enabled [ 0.813996] 1100c000.serial: ttyS1 at MMIO 0x1100c000 (irq = 132, base_baud = 17499995) is a MediaTek BTIF [ 0.823826] serial serial0: tty port ttyS1 registered [ 0.829783] mtk_rng 1020f000.rng: registered RNG driver [ 0.829921] random: fast init done [ 0.838524] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 0.838678] random: crng init done [ 0.850220] libphy: Fixed MDIO Bus: probed [ 0.877967] libphy: mdio: probed [ 0.882057] mtk_soc_eth 1b100000.ethernet: generated random MAC address ca:84:c6:6d:c6:eb [ 0.890696] mtk_soc_eth 1b100000.ethernet eth0: mediatek frame engine at 0xffffffc010ea0000, irq 140 [ 0.899905] mtk_soc_eth 1b100000.ethernet: generated random MAC address 62:ec:bd:3f:3a:40 [ 0.908430] mtk_soc_eth 1b100000.ethernet eth1: mediatek frame engine at 0xffffffc010ea0000, irq 140 [ 0.918299] usbcore: registered new interface driver usb-storage [ 0.925168] rtc_mt7622 10212800.rtc: registered as rtc0 [ 0.930454] rtc_mt7622 10212800.rtc: setting system clock to 2000-01-01T00:00:00 UTC (946684800) [ 0.939404] i2c_dev: i2c /dev entries driver [ 0.944020] i2c-mt65xx 11008000.i2c: clock-frequency: 100000 [ 0.949696] i2c-mt65xx 11008000.i2c: i2c-scl-rising-time-ns: 1000 [ 0.955796] i2c-mt65xx 11008000.i2c: i2c-scl-falling-time-ns: 300 [ 0.961895] i2c-mt65xx 11008000.i2c: i2c-scl-internal-delay-ns: 0 [ 0.967995] i2c-mt65xx 11008000.i2c: i2c-sda-falling-time-ns: 300 [ 0.974095] i2c-mt65xx 11008000.i2c: i2c-sda-hold-time-ns: 0 [ 0.979772] i2c-mt65xx 11008000.i2c: i2c-digital-filter-width-ns: 0 [ 0.986045] i2c-mt65xx 11008000.i2c: i2c-analog-filter-cutoff-frequency: 0 [ 0.993306] i2c_dev: adapter [i2c-mt65xx] registered as minor 0 [ 0.999291] i2c i2c-0: adapter [i2c-mt65xx] registered [ 1.004445] i2c i2c-0: of_i2c: walking child nodes [ 1.009483] i2c-mt65xx 11009000.i2c: clock-frequency: 100000 [ 1.015157] i2c-mt65xx 11009000.i2c: i2c-scl-rising-time-ns: 1000 [ 1.021259] i2c-mt65xx 11009000.i2c: i2c-scl-falling-time-ns: 300 [ 1.027363] i2c-mt65xx 11009000.i2c: i2c-scl-internal-delay-ns: 0 [ 1.033463] i2c-mt65xx 11009000.i2c: i2c-sda-falling-time-ns: 300 [ 1.039574] i2c-mt65xx 11009000.i2c: i2c-sda-hold-time-ns: 0 [ 1.045239] i2c-mt65xx 11009000.i2c: i2c-digital-filter-width-ns: 0 [ 1.051512] i2c-mt65xx 11009000.i2c: i2c-analog-filter-cutoff-frequency: 0 [ 1.058749] i2c_dev: adapter [i2c-mt65xx] registered as minor 1 [ 1.064742] i2c i2c-1: adapter [i2c-mt65xx] registered [ 1.069896] i2c i2c-1: of_i2c: walking child nodes [ 1.075953] mtk-wdt 10212000.watchdog: IRQ index 0 not found [ 1.082033] mtk-wdt 10212000.watchdog: Watchdog enabled (timeout=31 sec, nowayout=0) [ 1.093960] mtk-msdc 11240000.mmc: Got CD GPIO [ 1.098964] ledtrig-cpu: registered to indicate activity on CPUs [ 1.105118] SMCCC: SOC_ID: ID = jep106:0426:7622 Revision = 0x00000000 [ 1.112161] usbcore: registered new interface driver usbhid [ 1.117747] usbhid: USB HID core driver [ 1.124001] NET: Registered PF_INET6 protocol family [ 1.130419] Segment Routing with IPv6 [ 1.134248] In-situ OAM (IOAM) with IPv6 [ 1.138329] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.144901] NET: Registered PF_PACKET protocol family [ 1.150273] 8021q: 802.1Q VLAN Support v1.8 [ 1.154984] Loading compiled-in X.509 certificates [ 1.181231] mtk-pcie 1a140000.pcie: host bridge /pcie@1a140000 ranges: [ 1.187913] mtk-pcie 1a140000.pcie: Parsing ranges property... [ 1.193774] mtk-pcie 1a140000.pcie: MEM 0x0020000000..0x002fffffff -> 0x0020000000 [ 1.208758] mmc1: host does not support reading read-only switch, assuming write-enable [ 1.218868] mmc1: new high speed SDHC card at address b368 [ 1.225866] mmcblk1: mmc1:b368 LX32G 29.5 GiB [ 1.238374] GPT:Primary header thinks Alt. header is not at the end of the disk. [ 1.245820] GPT:15269887 != 61951999 [ 1.249428] GPT:Alternate GPT header not at the end of the disk. [ 1.255435] GPT:15269887 != 61951999 [ 1.259012] GPT: Use GNU Parted to correct GPT errors. [ 1.264209] mmcblk1: p1 p2 p3 [ 1.273850] mtk-msdc 11230000.mmc: phase: [map:ffffffff] [maxlen:32] [final:10] [ 1.282372] mmc0: new HS200 MMC card at address 0001 [ 1.288756] mmcblk0: mmc0:0001 008G30 7.28 GiB [ 1.297055] mmcblk0: p1 p2 [ 1.301290] mmcblk0boot0: mmc0:0001 008G30 4.00 MiB [ 1.309919] mmcblk0boot1: mmc0:0001 008G30 4.00 MiB [ 1.317664] mmcblk0rpmb: mmc0:0001 008G30 4.00 MiB, chardev (248:0) [ 1.325649] mtk-pcie 1a140000.pcie: Port1 link down [ 1.330751] mtk-pcie 1a140000.pcie: PCI host bridge to bus 0000:00 [ 1.336958] pci_bus 0000:00: root bus resource [bus 00-ff] [ 1.342453] pci_bus 0000:00: root bus resource [mem 0x20000000-0x2fffffff] [ 1.349335] pci_bus 0000:00: scanning bus [ 1.353399] pci 0000:00:00.0: [14c3:3258] type 01 class 0x060400 [ 1.359443] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x1ffffffff 64bit pref] [ 1.368329] pci_bus 0000:00: fixups for bus [ 1.372543] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 0 [ 1.379252] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring [ 1.387274] pci 0000:00:00.0: scanning [bus 00-00] behind bridge, pass 1 [ 1.394174] pci_bus 0000:01: scanning bus [ 1.398378] pci 0000:01:00.0: [14c3:7615] type 00 class 0x000280 [ 1.404580] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit] [ 1.412352] pci 0000:01:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s PCIe x1 link at 0000:00:00.0 (capable of 4.000 Gb/s with 5.0 GT/s PCIe x1 link) [ 1.440058] pci_bus 0000:01: fixups for bus [ 1.444268] pci_bus 0000:01: bus scan returning with max=01 [ 1.449854] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 [ 1.456484] pci_bus 0000:00: bus scan returning with max=01 [ 1.462079] pci 0000:00:00.0: BAR 0: no space for [mem size 0x200000000 64bit pref] [ 1.469743] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x200000000 64bit pref] [ 1.477754] pci 0000:00:00.0: BAR 8: assigned [mem 0x20000000-0x200fffff] [ 1.484555] pci 0000:01:00.0: BAR 0: assigned [mem 0x20000000-0x200fffff 64bit] [ 1.491957] pci 0000:00:00.0: PCI bridge to [bus 01] [ 1.496933] pci 0000:00:00.0: bridge window [mem 0x20000000-0x200fffff] [ 1.504191] mtk_hsdma 1b007000.dma-controller: Using 3 as missing dma-requests property [ 1.512566] mtk_hsdma 1b007000.dma-controller: MediaTek HSDMA driver registered [ 1.556370] mt7530 mdio-bus:00: no interrupt support [ 1.567533] libphy: mt7530-mii: probed [ 1.572548] mt7530 mdio-bus:00 wan (uninitialized): PHY [mt7530-0:00] driver [Generic PHY] (irq=POLL) [ 1.583420] mt7530 mdio-bus:00 lan0 (uninitialized): PHY [mt7530-0:01] driver [Generic PHY] (irq=POLL) [ 1.594302] mt7530 mdio-bus:00 lan1 (uninitialized): PHY [mt7530-0:02] driver [Generic PHY] (irq=POLL) [ 1.605130] mt7530 mdio-bus:00 lan2 (uninitialized): PHY [mt7530-0:03] driver [Generic PHY] (irq=POLL) [ 1.615986] mt7530 mdio-bus:00 lan3 (uninitialized): PHY [mt7530-0:04] driver [Generic PHY] (irq=POLL) [ 1.627352] mt7530 mdio-bus:00: configuring for fixed/2500base-x link mode [ 1.634652] mt7530 mdio-bus:00: Link is Up - 2.5Gbps/Full - flow control rx/tx [ 1.642076] DSA: tree 0 setup [ 1.646394] xhci-mtk 1a0c0000.usb: xHCI Host Controller [ 1.651674] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 1 [ 1.660774] xhci-mtk 1a0c0000.usb: hcc params 0x01403198 hci version 0x96 quirks 0x0000000000210010 [ 1.669891] xhci-mtk 1a0c0000.usb: irq 136, io mem 0x1a0c0000 [ 1.675837] xhci-mtk 1a0c0000.usb: xHCI Host Controller [ 1.681077] xhci-mtk 1a0c0000.usb: new USB bus registered, assigned bus number 2 [ 1.688489] xhci-mtk 1a0c0000.usb: Host supports USB 3.0 SuperSpeed [ 1.695501] hub 1-0:1.0: USB hub found [ 1.699310] hub 1-0:1.0: 2 ports detected [ 1.703838] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 1.712559] hub 2-0:1.0: USB hub found [ 1.716364] hub 2-0:1.0: 1 port detected [ 1.721540] input: gpio-keys as /devices/platform/gpio-keys/input/input0 [ 1.759203] F2FS-fs (mmcblk1p1): Found nat_bits in checkpoint [ 1.868403] F2FS-fs (mmcblk1p1): Mounted with checkpoint version = 72c75e70 [ 1.875509] VFS: Mounted root (f2fs filesystem) on device 179:1. [ 1.883551] devtmpfs: mounted [ 1.887512] Freeing unused kernel memory: 1856K [ 1.892158] Run /sbin/init as init process [ 1.896274] with arguments: [ 1.899247] /sbin/init [ 1.901948] with environment: [ 1.905097] HOME=/ [ 1.907459] TERM=linux [ 2.483255] systemd[1]: System time before build time, advancing clock. [ 2.549706] systemd[1]: systemd 249.7-2-arch running in system mode (+PAM +AUDIT -SELINUX -APPARMOR -IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) [ 2.580814] systemd[1]: Detected architecture arm64. [ 2.607824] systemd[1]: Hostname set to . [ 2.882029] systemd-fstab-generator[85]: Checking was requested for "dummy", but it is not a device. [ 3.276734] systemd[1]: Queued start job for default target Graphical Interface. [ 3.288735] systemd[1]: Created slice Slice /system/getty. [ 3.312509] systemd[1]: Created slice Slice /system/modprobe. [ 3.336695] systemd[1]: Created slice Slice /system/serial-getty. [ 3.360216] systemd[1]: Created slice User and Session Slice. [ 3.383339] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 3.407291] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 3.431083] systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. [ 3.442939] systemd[1]: Reached target Local Encrypted Volumes. [ 3.463109] systemd[1]: Reached target Path Units. [ 3.482983] systemd[1]: Reached target Remote File Systems. [ 3.502970] systemd[1]: Reached target Slice Units. [ 3.522999] systemd[1]: Reached target Swaps. [ 3.543013] systemd[1]: Reached target Local Verity Protected Volumes. [ 3.567418] systemd[1]: Listening on Device-mapper event daemon FIFOs. [ 3.597964] systemd[1]: Listening on Process Core Dump Socket. [ 3.621809] systemd[1]: Listening on Journal Audit Socket. [ 3.643561] systemd[1]: Listening on Journal Socket (/dev/log). [ 3.667665] systemd[1]: Listening on Journal Socket. [ 3.689827] systemd[1]: Listening on Network Service Netlink Socket. [ 3.714118] systemd[1]: Listening on udev Control Socket. [ 3.735486] systemd[1]: Listening on udev Kernel Socket. [ 3.755568] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 3.768002] systemd[1]: Mounting POSIX Message Queue File System... [ 3.795036] systemd[1]: Mounting Kernel Debug File System... [ 3.815360] systemd[1]: Condition check resulted in Kernel Trace File System being skipped. [ 3.829979] systemd[1]: Mounting Temporary Directory /tmp... [ 3.856508] systemd[1]: Starting Create List of Static Device Nodes... [ 3.883900] systemd[1]: Starting Load Kernel Module configfs... [ 3.907537] systemd[1]: Starting Load Kernel Module drm... [ 3.931493] systemd[1]: Starting Load Kernel Module fuse... [ 3.955234] systemd[1]: Starting Setup MAC addresses... [ 3.977181] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 3.987259] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. [ 4.000216] systemd[1]: (This warning is only shown for the first unit using IP firewalling.) [ 4.012560] systemd[1]: Starting Journal Service... [ 4.040647] systemd[1]: Starting Load Kernel Modules... [ 4.061740] systemd[1]: Starting Remount Root and Kernel File Systems... [ 4.083489] systemd[1]: Condition check resulted in Repartition Root Disk being skipped. [ 4.107094] systemd[1]: Starting Coldplug All udev Devices... [ 4.140049] systemd[1]: Mounted POSIX Message Queue File System. [ 4.159149] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 4.167817] systemd[1]: Mounted Kernel Debug File System. [ 4.191666] systemd[1]: Mounted Temporary Directory /tmp. [ 4.216829] systemd[1]: Finished Create List of Static Device Nodes. [ 4.245474] audit: type=1130 audit(1638319239.760:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.264801] systemd[1]: modprobe@configfs.service: Deactivated successfully. [ 4.274443] systemd[1]: Finished Load Kernel Module configfs. [ 4.290551] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 4.307106] audit: type=1130 audit(1638319239.824:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.307969] systemd[1]: Started Journal Service. [ 4.326090] audit: type=1131 audit(1638319239.824:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.363338] audit: type=1130 audit(1638319239.880:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.399445] audit: type=1130 audit(1638319239.916:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.418463] audit: type=1131 audit(1638319239.916:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.451103] audit: type=1130 audit(1638319239.968:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.471318] audit: type=1131 audit(1638319239.968:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.495304] mt7615e 0000:01:00.0: assign IRQ: got 145 [ 4.500522] pci 0000:00:00.0: enabling device (0000 -> 0002) [ 4.508684] pci 0000:00:00.0: enabling bus mastering [ 4.508730] audit: type=1130 audit(1638319240.020:10): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=setmac comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4.515017] mt7615e 0000:01:00.0: enabling device (0000 -> 0002) [ 4.541928] mt7615e 0000:01:00.0: enabling bus mastering [ 4.548790] Unable to handle kernel paging request at virtual address ffffffc010998830 [ 4.557834] Mem abort info: [ 4.560657] ESR = 0x96000047 [ 4.563741] EC = 0x25: DABT (current EL), IL = 32 bits [ 4.569070] SET = 0, FnV = 0 [ 4.572145] EA = 0, S1PTW = 0 [ 4.575338] FSC = 0x07: level 3 translation fault [ 4.580270] Data abort info: [ 4.583181] ISV = 0, ISS = 0x00000047 [ 4.587048] CM = 0, WnR = 1 [ 4.590023] swapper pgtable: 4k pages, 39-bit VAs, pgdp=0000000042734000 [ 4.596753] [ffffffc010998830] pgd=100000007ffff003, p4d=100000007ffff003, pud=100000007ffff003, pmd=100000007fffc003, pte=0000000000000000 [ 4.609393] Internal error: Oops: 96000047 [#1] PREEMPT SMP [ 4.609410] Modules linked in: mt7615e(+) mt7615_common mt76_connac_lib mt76 mac80211 libarc4 sha256_generic libsha256 cfg80211 rfkill ip_tables x_tables [ 4.628747] CPU: 1 PID: 106 Comm: systemd-modules Tainted: G S 5.15.0-bpi-r64-0 #1 [ 4.637618] Hardware name: Bananapi BPI-R64 (DT) [ 4.642231] pstate: 80000005 (Nzcv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--) [ 4.649191] pc : queued_spin_lock_slowpath+0x1c4/0x2dc [ 4.654337] lr : queued_spin_lock_slowpath+0x9c/0x2dc [ 4.660773] sp : ffffffc010fd3600 [ 4.664082] x29: ffffffc010fd3600 x28: 0000000000000001 x27: ffffffc010bdc750 [ 4.672602] x26: 0000000000080000 x25: ffffffc010bdcaa0 x24: ffffffc010fd3890 [ 4.681124] x23: 0000000000000000 x22: ffffffc010b18878 x21: ffffff803fdbc800 [ 4.689647] x20: ffffffc010998800 x19: ffffff800033f908 x18: 0000000000000000 [ 4.697387] x17: 0000000000000030 x16: 2e30303a31303a30 x15: 000000000000000a [ 4.704528] x14: 0000000000000092 x13: ffffffffffffffff x12: ffffffffffffffff [ 4.711670] x11: ffffff8000e2cf68 x10: 0000000000000000 x9 : ffffff800033f900 [ 4.718809] x8 : 0000000000000000 x7 : 0000000000000000 x6 : ffffff803fdbc800 [ 4.725949] x5 : 0000000000000001 x4 : 0000000000080000 x3 : ffffff800033f90a [ 4.733089] x2 : 0000000000000000 x1 : 0000000000000000 x0 : ffffffc010998830 [ 4.740230] Call trace: [ 4.742672] queued_spin_lock_slowpath+0x1c4/0x2dc [ 4.747463] do_raw_spin_lock+0x2c/0x38 [ 4.751298] _raw_spin_lock+0x24/0x34 [ 4.754965] __mutex_lock.constprop.0+0xb8/0x250 [ 4.759583] __mutex_lock_slowpath+0x14/0x20 [ 4.763853] mutex_lock+0x28/0x34 [ 4.767167] mtk_pcie_irq_domain_alloc+0x3c/0xcc [ 4.771789] irq_domain_alloc_irqs_hierarchy+0x50/0x54 [ 4.776929] irq_domain_alloc_irqs_parent+0x1c/0x30 [ 4.781806] msi_domain_alloc+0xa8/0x164 [ 4.785729] irq_domain_alloc_irqs_hierarchy+0x50/0x54 [ 4.790867] __irq_domain_alloc_irqs+0x104/0x2fc [ 4.795483] __msi_domain_alloc_irqs+0x110/0x2c4 [ 4.800102] msi_domain_alloc_irqs+0x1c/0x28 [ 4.804371] pci_msi_setup_msi_irqs.isra.0+0x28/0x40 [ 4.809338] __pci_enable_msi_range+0x21c/0x31c [ 4.813871] pci_alloc_irq_vectors_affinity+0xc4/0x11c [ 4.819009] mt7615_pci_probe+0x6c/0xf4 [mt7615e] [ 4.823735] pci_device_probe+0xb4/0x144 [ 4.827672] really_probe+0x1e0/0x3d0 [ 4.831358] __driver_probe_device+0x148/0x160 [ 4.835804] driver_probe_device+0x4c/0xe4 [ 4.839912] __driver_attach+0x154/0x164 [ 4.842348] systemd-journald[105]: Received client request to flush runtime journal. [ 4.843860] bus_for_each_dev+0x70/0xbc [ 4.843877] driver_attach+0x24/0x30 [ 4.843885] bus_add_driver+0x1a4/0x1ec [ 4.843894] driver_register+0xb4/0xec [ 4.843900] __pci_register_driver+0x4c/0x58 [ 4.843909] mt7615_init+0x38/0x1000 [mt7615e] [ 4.856855] systemd-journald[105]: File /var/log/journal/551d98cc7a404cd4890125d582da0273/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 4.859035] do_one_initcall+0x6c/0x188 [ 4.859046] do_init_module+0x5c/0x1e8 [ 4.859055] load_module+0x9f8/0xa64 [ 4.859062] __do_sys_finit_module+0xdc/0x100 [ 4.859070] __arm64_sys_finit_module+0x1c/0x28 [ 4.859078] invoke_syscall+0x6c/0xf8 [ 4.913477] el0_svc_common.constprop.0+0xa0/0xcc [ 4.918186] do_el0_svc+0x48/0x78 [ 4.921498] el0_svc+0x20/0x4c [ 4.924554] el0t_64_sync_handler+0xbc/0x158 [ 4.928825] el0t_64_sync+0x1a0/0x1a4 [ 4.932492] Code: d37c0400 51000421 8b000280 f861dac1 (f8216806) [ 4.938584] ---[ end trace 524fd66b9757edca ]--- [ 4.943227] note: systemd-modules[106] exited with preempt_count 2 [ 5.027112] kauditd_printk_skb: 1 callbacks suppressed [ 5.027130] audit: type=1130 audit(1638319240.544:12): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed' [ 5.067560] audit: type=1130 audit(1638319240.584:13): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.103669] audit: type=1130 audit(1638319240.620:14): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.143523] audit: type=1130 audit(1638319240.660:15): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.215093] audit: type=1130 audit(1638319240.732:16): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.287083] audit: type=1130 audit(1638319240.804:17): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.615253] audit: type=1130 audit(1638319241.132:18): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.772367] audit: type=1130 audit(1638319241.288:19): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.917683] audit: type=1127 audit(1638319241.432:20): pid=140 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' [ 5.981130] audit: type=1130 audit(1638319241.492:21): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.140480] Bluetooth: Core ver 2.22 [ 6.176213] NET: Registered PF_BLUETOOTH protocol family [ 6.233566] Bluetooth: HCI device and connection manager initialized [ 6.302983] Bluetooth: HCI socket layer initialized [ 6.334871] Bluetooth: L2CAP socket layer initialized [ 6.358930] Bluetooth: SCO socket layer initialized [ 8.369471] brlan: port 1(aux) entered blocking state [ 8.374703] brlan: port 1(aux) entered disabled state [ 8.386264] brlan: port 2(lan3) entered blocking state [ 8.391541] brlan: port 2(lan3) entered disabled state [ 8.399215] device lan3 entered promiscuous mode [ 8.404067] device aux entered promiscuous mode [ 8.412651] brlan: port 3(lan2) entered blocking state [ 8.418333] brlan: port 3(lan2) entered disabled state [ 8.426716] device lan2 entered promiscuous mode [ 8.434686] brlan: port 4(lan1) entered blocking state [ 8.439948] brlan: port 4(lan1) entered disabled state [ 8.447604] device lan1 entered promiscuous mode [ 8.455998] brlan: port 5(lan0) entered blocking state [ 8.461240] brlan: port 5(lan0) entered disabled state [ 8.468854] device lan0 entered promiscuous mode [ 8.477059] brlan: port 6(wan) entered blocking state [ 8.482201] brlan: port 6(wan) entered disabled state [ 8.489690] device wan entered promiscuous mode [ 8.510972] mtk_soc_eth 1b100000.ethernet eth1: configuring for fixed/rgmii link mode [ 8.518963] mtk_soc_eth 1b100000.ethernet eth1: Link is Up - 1Gbps/Full - flow control rx/tx [ 8.528476] mtk_soc_eth 1b100000.ethernet eth0: configuring for fixed/2500base-x link mode [ 8.536944] device eth0 entered promiscuous mode [ 8.541746] mtk_soc_eth 1b100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control rx/tx [ 8.607175] mt7530 mdio-bus:00 aux: configuring for fixed/rgmii link mode [ 8.614719] mt7530 mdio-bus:00 aux: Link is Up - 1Gbps/Full - flow control rx/tx [ 8.622539] 8021q: adding VLAN 0 to HW filter on device aux [ 8.630624] IPv6: ADDRCONF(NETDEV_CHANGE): aux: link becomes ready [ 8.637868] brlan: port 1(aux) entered blocking state [ 8.643016] brlan: port 1(aux) entered forwarding state [ 8.650231] mt7530 mdio-bus:00 lan3: configuring for phy/gmii link mode [ 8.660201] 8021q: adding VLAN 0 to HW filter on device lan3 [ 8.668672] IPv6: ADDRCONF(NETDEV_CHANGE): brlan: link becomes ready [ 8.677739] mt7530 mdio-bus:00 lan2: configuring for phy/gmii link mode [ 8.687748] 8021q: adding VLAN 0 to HW filter on device lan2 [ 8.701325] mt7530 mdio-bus:00 lan1: configuring for phy/gmii link mode [ 8.710390] 8021q: adding VLAN 0 to HW filter on device lan1 [ 8.721192] mt7530 mdio-bus:00 lan0: configuring for phy/gmii link mode [ 8.731006] 8021q: adding VLAN 0 to HW filter on device lan0 [ 8.741746] mt7530 mdio-bus:00 wan: configuring for phy/gmii link mode [ 8.754871] mt7530 mdio-bus:00 wan: Link is Up - 100Mbps/Full - flow control rx/tx [ 8.757391] 8021q: adding VLAN 0 to HW filter on device wan [ 8.770354] IPv6: ADDRCONF(NETDEV_CHANGE): wan: link becomes ready [ 8.776939] brlan: port 6(wan) entered blocking state [ 8.782081] brlan: port 6(wan) entered forwarding state [ 11.962483] kauditd_printk_skb: 34 callbacks suppressed [ 11.962502] audit: type=1131 audit(1638464720.073:40): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 12.066835] audit: type=1130 audit(1638464720.177:41): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 16.606789] Bluetooth: hci0: Execution of wmt command timed out [ 16.612751] Bluetooth: hci0: Failed to query firmware status (-110) [ 20.547502] audit: type=1100 audit(1638464728.661:42): pid=233 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/login" hostname=bpir64 addr=? terminal=/dev/ttyS0 res=success' [ 20.590003] audit: type=1101 audit(1638464728.701:43): pid=233 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="root" exe="/usr/bin/login" hostname=bpir64 addr=? terminal=/dev/ttyS0 res=success' [ 20.616608] audit: type=1103 audit(1638464728.729:44): pid=233 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/login" hostname=bpir64 addr=? terminal=/dev/ttyS0 res=success' [ 20.641425] audit: type=1006 audit(1638464728.729:45): pid=233 uid=0 old-auid=4294967295 auid=0 tty=ttyS0 old-ses=4294967295 ses=1 res=1 [ 20.653858] audit: type=1300 audit(1638464728.729:45): arch=c00000b7 syscall=64 success=yes exit=1 a0=4 a1=7fc98395a0 a2=1 a3=7fb0938bd0 items=0 ppid=1 pid=233 auid=0 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=ttyS0 ses=1 comm="login" exe="/usr/bin/login" key=(null) [ 20.679020] audit: type=1327 audit(1638464728.729:45): proctitle=2F62696E2F6C6F67696E002D70002D2D [ 20.769771] audit: type=1130 audit(1638464728.881:46): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 20.813374] audit: type=1101 audit(1638464728.925:47): pid=347 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 20.836328] audit: type=1103 audit(1638464728.925:48): pid=347 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed' [ 20.855542] audit: type=1006 audit(1638464728.925:49): pid=347 uid=0 old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=2 res=1 [ 42.091163] kauditd_printk_skb: 6 callbacks suppressed [ 42.091180] audit: type=1131 audit(1638470156.991:54): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' ```

Patch from 'frank-w' I added to linux-5.15 build

frank-w commented 2 years ago

You have added the debug-patch or dts patches? I see no debug before the crash

vycb commented 2 years ago

I added a patch from the post

frank-w commented 2 years ago

Ah i see the crash is different. It looks like deadlock or attempt to re-relock (release lock missing) after/in mtk_pcie_irq_domain_alloc

vycb commented 2 years ago

This issue, actually, isn't directly related to an origin of the project. It's more related to the kernel version, I think.

ericwoud commented 2 years ago

I believe so also... Sadly I do not own an PCIe card, so cannot help any further.

vycb commented 2 years ago

This issue is closed. Maybe you know, what is the kernel version with the PCI fix?

ericwoud commented 2 years ago

Maybe Frank knows

frank-w commented 2 years ago

5.16 should include the dts-patch i've linked,in 5.15 i need to add it manually

vycb commented 2 years ago

Thanks Frank

ericwoud commented 2 years ago

The last attempt 5.16-rc3 crashed

frank-w commented 2 years ago

Idk if i booted 5.16 with pcie,but 5.15.0 with the patch worked for me without crash,see my stripped trace above

ericwoud commented 2 years ago

You can build franks kernel with the script. Set the .git as kernel source and the branch as kernel version. Create a folder with the linux-branchname and carefully select patches to put in the folder. Save e defconfig there

frank-w commented 2 years ago

you need to change build.conf in my repo for r64...by default kernel for r2 is compiled

https://github.com/frank-w/BPI-R2-4.14/blob/5.4-main/.github/workflows/build.yml#L75

cryptodev is not needed, but importconfig and build.sh to build

5.16-rc is booting without crash too, mt7615 in cn25 (slot not shared with sata, have sata activated, so cn8 disabled) is recognized without errors

root@bpi-r64:~# uname -a
Linux bpi-r64 5.16.0-rc1-bpi-r64 #3 SMP PREEMPT Sun Dec 5 13:04:25 CET 2021 aarch64 GNU/Linux
root@bpi-r64:~# ip a | grep wl
12: wlp1s0: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
13: wlan1: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
root@bpi-r64:~# dmesg | grep -i 'pcie\|mt7615\|mt7622'
[    0.000000] Kernel command line: board=bpi-r64 console=ttyS0,115200n1 earlyprintk root=/dev/mmcblk1p5 rootfstype=ext4 rootwait servi2
[    1.306987] rtc_mt7622 10212800.rtc: registered as rtc0
[    1.312264] rtc_mt7622 10212800.rtc: setting system clock to 2000-01-01T00:00:00 UTC (946684800)
[    1.569990] mtk-pcie 1a143000.pcie: host bridge /pcie@1a143000 ranges:
[    1.584748] mtk-pcie 1a143000.pcie: Parsing ranges property...
[    1.596137] mtk-pcie 1a143000.pcie:      MEM 0x0020000000..0x0027ffffff -> 0x0020000000
[    1.633817] mtk-pcie 1a143000.pcie: PCI host bridge to bus 0000:00
[    1.739499] pci 0000:01:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s PCIe x1 link at 0000:00:00.0 (capable of 4.00)
[    1.832519] mtk-pcie 1a145000.pcie: host bridge /pcie@1a145000 ranges:
[    1.839103] mtk-pcie 1a145000.pcie: Parsing ranges property...
[    1.844947] mtk-pcie 1a145000.pcie:      MEM 0x0028000000..0x002fffffff -> 0x0028000000
[    1.953276] mtk-pcie 1a145000.pcie: Port1 link down
[    1.958356] mtk-pcie 1a145000.pcie: PCI host bridge to bus 0001:00
[    6.226554] mt7615e 0000:01:00.0: assign IRQ: got 146
[    6.289318] mt7615e 0000:01:00.0: enabling device (0000 -> 0002)
[    6.315454] mt7615e 0000:01:00.0: enabling bus mastering
[    6.327374] mtk-pcie 1a143000.pcie: msi#0 address_hi 0x0 address_lo 0x40f350c0
[    6.394074] mt7615e 0000:01:00.0: Load eeprom: /lib/firmware/mediatek/mt7615e_rf.bin
[    6.415909] mt7615e 0000:01:00.0: Open eeprom file failed: /lib/firmware/mediatek/mt7615e_rf.bin
[    6.472839] mt7622-wmac 18000000.wmac: Load eeprom: /lib/firmware/mediatek/mt7622-wmac_rf.bin
[    6.489330] mt7622-wmac 18000000.wmac: Load eeprom OK, count 1024 byte
[    6.608796] mt7622-wmac 18000000.wmac: N9 Firmware Version: _reserved_, Build Time: 20190801210351
[    6.982617] mt7615e 0000:01:00.0 wlp1s0: renamed from wlan0