ericwoud / buildR64arch

Build script for image for BananaPi R64 / R3 (R4) running Arch Linux
21 stars 5 forks source link

BPI-R3 wifi devices not detected #6

Closed ostrich closed 1 year ago

ostrich commented 1 year ago

(Let me know if you need any additional information and I'll provide it asap.)

Built with the latest version. Menu selections 1. bpir3, 1. sdmmc, 2. RTnoSFP, IP address 10.9.9.0. Wrote to SD card, everything seems fine. At the end I get this:

# Load mt7915e.ko at boot
mt7915e
Created symlink /etc/systemd/system/dbus-org.freedesktop.timesync1.service -> /usr/lib/systemd/system/systemd-timesyncd.service.
Created symlink /etc/systemd/system/sysinit.target.wants/systemd-timesyncd.service -> /usr/lib/systemd/system/systemd-timesyncd.service.
Created symlink /etc/systemd/system/multi-user.target.wants/sshd.service -> /usr/lib/systemd/system/sshd.service.
Created symlink /etc/systemd/system/dbus-org.freedesktop.resolve1.service -> /usr/lib/systemd/system/systemd-resolved.service.
Created symlink /etc/systemd/system/sysinit.target.wants/systemd-resolved.service -> /usr/lib/systemd/system/systemd-resolved.service.
Created symlink /etc/systemd/system/dbus-org.freedesktop.network1.service -> /usr/lib/systemd/system/systemd-networkd.service.
Created symlink /etc/systemd/system/multi-user.target.wants/systemd-networkd.service -> /usr/lib/systemd/system/systemd-networkd.service.
Created symlink /etc/systemd/system/sockets.target.wants/systemd-networkd.socket -> /usr/lib/systemd/system/systemd-networkd.socket.
Created symlink /etc/systemd/system/sysinit.target.wants/systemd-network-generator.service -> /usr/lib/systemd/system/systemd-network-generator.service.
Created symlink /etc/systemd/system/network-online.target.wants/systemd-networkd-wait-online.service -> /usr/lib/systemd/system/systemd-networkd-wait-online.service.
Created symlink /etc/systemd/system/multi-user.target.wants/hostapd@wlan0.service -> /etc/systemd/system/hostapd@.service.
Created symlink /etc/systemd/system/sys-subsystem-net-devices-wlan0.device.wants/hostapd@wlan0.service -> /etc/systemd/system/hostapd@.service.
Unit /etc/systemd/system/hostapd@.service is added as a dependency to a non-existent unit sys-subsystem-net-devices-wlan0.device.
Created symlink /etc/systemd/system/multi-user.target.wants/hostapd@wlan1.service -> /etc/systemd/system/hostapd@.service.
Created symlink /etc/systemd/system/sys-subsystem-net-devices-wlan1.device.wants/hostapd@wlan1.service -> /etc/systemd/system/hostapd@.service.
Unit /etc/systemd/system/hostapd@.service is added as a dependency to a non-existent unit sys-subsystem-net-devices-wlan1.device.
Created symlink /etc/systemd/system/basic.target.wants/setmac.service -> /etc/systemd/system/setmac.service.
Created symlink /etc/systemd/system/multi-user.target.wants/ssh-fix-reboot.service -> /etc/systemd/system/ssh-fix-reboot.service.
AA:BB:CC:04:9B:20 16
AA:BB:CC:F0:25:F6
removed '/run/udev/rules.d/10-no-automount-bpir.rules'
Running exit function to clean up...
/tmp/bpirootfs.517 is a mountpoint
Unmounting...DO NOT REMOVE!
Done. You can remove the card now.

(Note lines Unit /etc/systemd/system/hostapd@.service is added as a dependency to a non-existent unit sys-subsystem-net-devices-wlan0.device. and Unit /etc/systemd/system/hostapd@.service is added as a dependency to a non-existent unit sys-subsystem-net-devices-wlan1.device. -- not sure if this is right.)

BPI-R3 boots fine from the SD card, but I don't have wlan0 or wlan1.

In journalctl I get this repeating over and over:

Jun 22 16:34:14 bpir3 systemd[1]: Failed to start Access point and authentication server for Wi-Fi and Ethernet (wlan0).
Jun 22 16:34:14 bpir3 systemd[1]: hostapd@wlan0.service: Consumed 2.279s CPU time.
Jun 22 16:34:14 bpir3 systemd[1]: Unnecessary job was removed for /sys/subsystem/net/devices/wlan0.
Jun 22 16:34:14 bpir3 systemd[1]: hostapd@wlan1.service: start operation timed out. Terminating.
Jun 22 16:34:14 bpir3 systemd[1]: hostapd@wlan1.service: Failed with result 'timeout'.
Jun 22 16:34:14 bpir3 systemd[1]: Failed to start Access point and authentication server for Wi-Fi and Ethernet (wlan1).
Jun 22 16:34:14 bpir3 systemd[1]: hostapd@wlan1.service: Consumed 2.266s CPU time.
Jun 22 16:34:14 bpir3 systemd[1]: Unnecessary job was removed for /sys/subsystem/net/devices/wlan1.
Jun 22 16:34:16 bpir3 systemd[1]: hostapd@wlan0.service: Scheduled restart job, restart counter is at 15.
Jun 22 16:34:16 bpir3 systemd[1]: Unnecessary job was removed for /sys/subsystem/net/devices/wlan0.
Jun 22 16:34:16 bpir3 systemd[1]: hostapd@wlan1.service: Scheduled restart job, restart counter is at 15.
Jun 22 16:34:16 bpir3 systemd[1]: Unnecessary job was removed for /sys/subsystem/net/devices/wlan1.
Jun 22 16:34:16 bpir3 systemd[1]: Stopped Access point and authentication server for Wi-Fi and Ethernet (wlan0).
Jun 22 16:34:16 bpir3 systemd[1]: hostapd@wlan0.service: Consumed 2.279s CPU time.
Jun 22 16:34:17 bpir3 systemd[1]: Starting Access point and authentication server for Wi-Fi and Ethernet (wlan0)...
Jun 22 16:34:17 bpir3 systemd[1]: Stopped Access point and authentication server for Wi-Fi and Ethernet (wlan1).
Jun 22 16:34:17 bpir3 systemd[1]: hostapd@wlan1.service: Consumed 2.266s CPU time.
Jun 22 16:34:17 bpir3 systemd[1]: Starting Access point and authentication server for Wi-Fi and Ethernet (wlan1)...
Jun 22 16:35:47 bpir3 systemd[1]: hostapd@wlan0.service: start operation timed out. Terminating.
Jun 22 16:35:47 bpir3 systemd[1]: hostapd@wlan0.service: Failed with result 'timeout'.

And here's my dmesg:

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 6.3.8-bpi (nathalieeneric@rk3588) (gcc (GCC) 12.1.0, GNU ld (GNU Binutils) 2.38) #5 SMP PREEMPT Sun Jun 18 02:16:25 UTC 2023
[    0.000000] Machine model: Bananapi BPI-R3
[    0.000000] earlycon: uart8250 at MMIO32 0x0000000011002000 (options '')
[    0.000000] printk: bootconsole [uart8250] enabled
[    0.000000] efi: UEFI not found.
[    0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'wo-emi@4fd00000': base 0x000000004fd00000, size 0 MiB
[    0.000000] OF: reserved mem: 0x0000000015194000..0x0000000015194fff (4 KiB) nomap non-reusable wo-boot@15194000
[    0.000000] OF: reserved mem: 0x00000000151e0000..0x00000000151e7fff (32 KiB) nomap non-reusable wo-ilm@151e0000
[    0.000000] OF: reserved mem: 0x00000000151e8000..0x00000000151e9fff (8 KiB) nomap non-reusable wo-dlm@151e8000
[    0.000000] OF: reserved mem: 0x00000000151f0000..0x00000000151f7fff (32 KiB) nomap non-reusable wo-ilm@151f0000
[    0.000000] OF: reserved mem: 0x00000000151f8000..0x00000000151f9fff (8 KiB) nomap non-reusable wo-dlm@151f8000
[    0.000000] OF: reserved mem: 0x0000000043000000..0x000000004302ffff (192 KiB) nomap non-reusable secmon@43000000
[    0.000000] OF: reserved mem: 0x000000004fc00000..0x000000004fcfffff (1024 KiB) nomap non-reusable wmcpu-reserved@4fc00000
[    0.000000] OF: reserved mem: 0x000000004fd00000..0x000000004fd3ffff (256 KiB) nomap non-reusable wo-emi@4fd00000
[    0.000000] OF: reserved mem: 0x000000004fd40000..0x000000004fd7ffff (256 KiB) nomap non-reusable wo-emi@4fd40000
[    0.000000] OF: reserved mem: 0x000000004fd80000..0x000000004ffbffff (2304 KiB) nomap non-reusable wo-data@4fd80000
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000bfffffff]
[    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-0x000000004fbfffff]
[    0.000000]   node   0: [mem 0x000000004fc00000-0x000000004fcfffff]
[    0.000000]   node   0: [mem 0x000000004fd00000-0x000000004fd3ffff]
[    0.000000]   node   0: [mem 0x000000004fd40000-0x000000004ffbffff]
[    0.000000]   node   0: [mem 0x000000004ffc0000-0x00000000bfffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff]
[    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 28 pages/cpu s75944 r8192 d30552 u114688
[    0.000000] pcpu-alloc: s75944 r8192 d30552 u114688 alloc=28*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 516096
[    0.000000] Kernel command line: root=PARTLABEL=bpir3-sdmmc-root earlycon=uart8250,mmio32,0x11002000 console=ttyS0,115200 debug=7 rw rootwait audit=0
[    0.000000] audit: disabled (until reboot)
[    0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 2025300K/2097152K available (9728K kernel code, 1994K rwdata, 3736K rodata, 3328K init, 411K bss, 71852K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] trace event string verifier disabled
[    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]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 640 SPIs implemented
[    0.000000] GICv3: 0 Extended SPIs implemented
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GICv3: GICv3 features: 16 PPIs
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x000000000c080000
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 13.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2ff89eacb, max_idle_ns: 440795202429 ns
[    0.000000] sched_clock: 56 bits at 13MHz, resolution 76ns, wraps every 4398046511101ns
[    0.008318] Console: colour dummy device 80x25
[    0.012807] Calibrating delay loop (skipped), value calculated using timer frequency.. 26.00 BogoMIPS (lpj=130000)
[    0.023220] pid_max: default: 32768 minimum: 301
[    0.027971] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.035418] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.043990] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.050552] cblist_init_generic: Setting adjustable number of callback queues.
[    0.057813] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.063995] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.070236] rcu: Hierarchical SRCU implementation.
[    0.075056] rcu:     Max phase no-delay instances is 1000.
[    0.080793] EFI services will not be available.
[    0.085542] smp: Bringing up secondary CPUs ...
[    0.090473] Detected VIPT I-cache on CPU1
[    0.090541] cacheinfo: Unable to detect cache hierarchy for CPU 1
[    0.090551] GICv3: CPU1: found redistributor 1 region 0:0x000000000c0a0000
[    0.090584] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.090987] Detected VIPT I-cache on CPU2
[    0.091035] cacheinfo: Unable to detect cache hierarchy for CPU 2
[    0.091041] GICv3: CPU2: found redistributor 2 region 0:0x000000000c0c0000
[    0.091057] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.091406] Detected VIPT I-cache on CPU3
[    0.091450] cacheinfo: Unable to detect cache hierarchy for CPU 3
[    0.091456] GICv3: CPU3: found redistributor 3 region 0:0x000000000c0e0000
[    0.091469] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.091512] smp: Brought up 1 node, 4 CPUs
[    0.166466] SMP: Total of 4 processors activated.
[    0.171190] CPU features: detected: 32-bit EL0 Support
[    0.176349] CPU features: detected: CRC32 instructions
[    0.181557] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
[    0.189951] CPU: All CPU(s) started at EL2
[    0.194079] alternatives: applying system-wide alternatives
[    0.200535] devtmpfs: initialized
[    0.206741] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.216660] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.223680] pinctrl core: initialized pinctrl subsystem
[    0.229202] DMI not present or invalid.
[    0.233490] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.239828] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
[    0.246940] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.254758] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.263094] thermal_sys: Registered thermal governor 'fair_share'
[    0.263099] thermal_sys: Registered thermal governor 'bang_bang'
[    0.269233] thermal_sys: Registered thermal governor 'step_wise'
[    0.275265] thermal_sys: Registered thermal governor 'user_space'
[    0.281422] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.294418] ASID allocator initialised with 65536 entries
[    0.309555] cryptd: max_cpu_qlen set to 1000
[    0.314779] iommu: Default domain type: Translated
[    0.319683] iommu: DMA domain TLB invalidation policy: strict mode
[    0.326221] SCSI subsystem initialized
[    0.330124] libata version 3.00 loaded.
[    0.334073] usbcore: registered new interface driver usbfs
[    0.339620] usbcore: registered new interface driver hub
[    0.344974] usbcore: registered new device driver usb
[    0.350370] i2c-gpio i2c-gpio-0: using lines 528 (SDA) and 529 (SCL)
[    0.356892] i2c-gpio i2c-gpio-1: using lines 530 (SDA) and 531 (SCL)
[    0.363317] mc: Linux media interface: v0.10
[    0.367620] videodev: Linux video capture interface: v2.00
[    0.373161] pps_core: LinuxPPS API ver. 1 registered
[    0.378150] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.387337] PTP clock support registered
[    0.392148] vgaarb: loaded
[    0.395155] clocksource: Switched to clocksource arch_sys_counter
[    0.406606] NET: Registered PF_INET protocol family
[    0.411759] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.420653] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
[    0.429294] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.437092] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.445117] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)
[    0.452795] TCP: Hash tables configured (established 16384 bind 16384)
[    0.459471] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    0.466242] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    0.473568] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.479294] PCI: CLS 0 bytes, default 64
[    0.483418] Unpacking initramfs...
[    0.487950] Initialise system trusted keyrings
[    0.492683] workingset: timestamp_bits=46 max_order=19 bucket_order=0
[    0.499551] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.505475] jffs2: version 2.2. (NAND) (SUMMARY)  \xc2\xa9 2001-2006 Red Hat, Inc.
[    0.535385] Key type asymmetric registered
[    0.539523] Asymmetric key parser 'x509' registered
[    0.544551] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.552074] io scheduler mq-deadline registered
[    0.556657] io scheduler kyber registered
[    0.560754] io scheduler bfq registered
[    0.578499] Serial: 8250/16550 driver, 3 ports, IRQ sharing disabled
[    0.587401] printk: console [ttyS0] disabled
[    0.611935] 11002000.serial: ttyS0 at MMIO 0x11002000 (irq = 116, base_baud = 2500000) is a ST16650V2
[    0.621346] printk: console [ttyS0] enabled
[    0.629755] printk: bootconsole [uart8250] disabled
[    0.686473] 11003000.serial: ttyS1 at MMIO 0x11003000 (irq = 117, base_baud = 1625000) is a ST16650V2
[    0.686743] Freeing initrd memory: 8204K
[    0.716844] 11004000.serial: ttyS2 at MMIO 0x11004000 (irq = 118, base_baud = 1625000) is a ST16650V2
[    0.727047] mtk_rng 1020f000.rng: registered RNG driver
[    0.727313] random: crng init done
[    0.732579] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.744636] loop: module loaded
[    0.750763] sfp sfp-1: Host maximum power 1.0W
[    0.756673] sfp sfp-2: Host maximum power 1.0W
[    0.887738] mtk_soc_eth 15100000.ethernet: generated random MAC address 72:5e:2d:8a:87:24
[    0.896976] mtk_soc_eth 15100000.ethernet eth0: mediatek frame engine at 0xffffffc009e80000, irq 123
[    0.906178] mtk_soc_eth 15100000.ethernet: generated random MAC address 42:d0:cd:33:e7:ef
[    0.915329] mtk_soc_eth 15100000.ethernet eth1: mediatek frame engine at 0xffffffc009e80000, irq 123
[    0.924870] usbcore: registered new interface driver rtl8150
[    0.930771] usbcore: registered new interface driver usb-storage
[    0.937006] i2c_dev: i2c /dev entries driver
[    0.942841] mtk-wdt 1001c000.watchdog: Watchdog enabled (timeout=31 sec, nowayout=0)
[    0.953010] ledtrig-cpu: registered to indicate activity on CPUs
[    0.959349] SMCCC: SOC_ID: ID = jep106:0426:7986 Revision = 0x00000000
[    0.965994] usbcore: registered new interface driver usbhid
[    0.971557] usbhid: USB HID core driver
[    0.975954] NET: Registered PF_INET6 protocol family
[    0.981536] Segment Routing with IPv6
[    0.985230] In-situ OAM (IOAM) with IPv6
[    0.989178] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    0.995541] NET: Registered PF_PACKET protocol family
[    1.000696] 8021q: 802.1Q VLAN Support v1.8
[    1.011565] mmc0: host does not support reading read-only switch, assuming write-enable
[    1.017659] Loading compiled-in X.509 certificates
[    1.021505] mmc0: new high speed SDXC card at address 59b4
[    1.031086] mmcblk0: mmc0:59b4 SPCC  236 GiB
[    1.031576] mtk-pcie-gen3 11280000.pcie: host bridge /soc/pcie@11280000 ranges:
[    1.042186]  mmcblk0: p1 p2 p3
[    1.042783] mtk-pcie-gen3 11280000.pcie:      MEM 0x0020000000..0x002fffffff -> 0x0020000000
[    1.265172] mtk-pcie-gen3 11280000.pcie: PCIe link down, current LTSSM state: detect.active (0x1000001)
[    1.274591] mtk-pcie-gen3: probe of 11280000.pcie failed with error -110
[    1.365138] mt7530 mdio-bus:1f: configuring for fixed/2500base-x link mode
[    1.372301] mt7530 mdio-bus:1f: Link is Up - 2.5Gbps/Full - flow control rx/tx
[    1.381500] mt7530 mdio-bus:1f wan (uninitialized): PHY [mt7530-0:00] driver [MediaTek MT7531 PHY] (irq=129)
[    1.401623] mt7530 mdio-bus:1f lan0 (uninitialized): PHY [mt7530-0:01] driver [MediaTek MT7531 PHY] (irq=130)
[    1.421448] mt7530 mdio-bus:1f lan1 (uninitialized): PHY [mt7530-0:02] driver [MediaTek MT7531 PHY] (irq=131)
[    1.441247] mt7530 mdio-bus:1f lan2 (uninitialized): PHY [mt7530-0:03] driver [MediaTek MT7531 PHY] (irq=132)
[    1.461058] mt7530 mdio-bus:1f lan3 (uninitialized): PHY [mt7530-0:04] driver [MediaTek MT7531 PHY] (irq=133)
[    1.472179] mtk_soc_eth 15100000.ethernet eth0: entered promiscuous mode
[    1.478924] DSA: tree 0 setup
[    1.482382] xhci-mtk 11200000.usb: supply vbus not found, using dummy regulator
[    1.489760] xhci-mtk 11200000.usb: supply vusb33 not found, using dummy regulator
[    1.498164] xhci-mtk 11200000.usb: xHCI Host Controller
[    1.503397] xhci-mtk 11200000.usb: new USB bus registered, assigned bus number 1
[    1.513815] xhci-mtk 11200000.usb: hcc params 0x01403f99 hci version 0x110 quirks 0x0000000000210010
[    1.522978] xhci-mtk 11200000.usb: irq 134, io mem 0x11200000
[    1.528719] xhci-mtk 11200000.usb: xHCI Host Controller
[    1.533933] xhci-mtk 11200000.usb: new USB bus registered, assigned bus number 2
[    1.541316] xhci-mtk 11200000.usb: Host supports USB 3.2 Enhanced SuperSpeed
[    1.548832] hub 1-0:1.0: USB hub found
[    1.552594] hub 1-0:1.0: 2 ports detected
[    1.556936] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    1.565416] hub 2-0:1.0: USB hub found
[    1.569184] hub 2-0:1.0: 1 port detected
[    1.574021] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    1.581092] of_cfs_init
[    1.583629] of_cfs_init: OK
[    1.588228] Freeing unused kernel memory: 3328K
[    1.665202] Run /init as init process
[    1.668857]   with arguments:
[    1.671810]     /init
[    1.674070]   with environment:
[    1.677208]     HOME=/
[    1.679555]     TERM=linux
[    2.025211] usb 1-1: new high-speed USB device number 2 using xhci-mtk
[    2.206286] hub 1-1:1.0: USB hub found
[    2.210148] hub 1-1:1.0: 4 ports detected
[    3.248455] F2FS-fs (mmcblk0p3): Found nat_bits in checkpoint
[    3.649245] F2FS-fs (mmcblk0p3): Mounted with checkpoint version = 1dd60377
[    4.353707] systemd[1]: System time before build time, advancing clock.
[    4.480056] systemd[1]: systemd 253.5-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 +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK +XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
[    4.512510] systemd[1]: Detected architecture arm64.
[    4.563941] systemd[1]: Hostname set to <bpir3>.
[    4.674000] systemd[1]: bpf-lsm: BPF LSM hook not enabled in the kernel, BPF LSM not supported
[    4.683754] memfd_create() without MFD_EXEC nor MFD_NOEXEC_SEAL, pid=1 'systemd'
[    5.247185] systemd[1]: Queued start job for default target Graphical Interface.
[    5.254602] systemd[1]: Unnecessary job was removed for /sys/subsystem/net/devices/wlan1.
[    5.262827] systemd[1]: Unnecessary job was removed for /sys/subsystem/net/devices/wlan0.
[    5.308271] systemd[1]: Created slice Slice /system/getty.
[    5.346611] systemd[1]: Created slice Slice /system/hostapd.
[    5.386465] systemd[1]: Created slice Slice /system/modprobe.
[    5.426432] systemd[1]: Created slice Slice /system/serial-getty.
[    5.466122] systemd[1]: Created slice User and Session Slice.
[    5.505551] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    5.545429] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    5.585350] systemd[1]: Arbitrary Executable File Formats File System Automount Point was skipped because of an unmet condition check (ConditionPathExists=/proc/sys/fs/binfmt_misc).
[    5.601628] systemd[1]: Reached target Local Encrypted Volumes.
[    5.635274] systemd[1]: Reached target Local Integrity Protected Volumes.
[    5.675294] systemd[1]: Reached target Path Units.
[    5.705250] systemd[1]: Reached target Remote File Systems.
[    5.745244] systemd[1]: Reached target Slice Units.
[    5.775262] systemd[1]: Reached target Swaps.
[    5.805259] systemd[1]: Reached target Local Verity Protected Volumes.
[    5.845485] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    5.889478] systemd[1]: Listening on Process Core Dump Socket.
[    5.926593] systemd[1]: Listening on Journal Socket (/dev/log).
[    5.965765] systemd[1]: Listening on Journal Socket.
[    5.996688] systemd[1]: Listening on Network Service Netlink Socket.
[    6.037280] systemd[1]: Listening on udev Control Socket.
[    6.075719] systemd[1]: Listening on udev Kernel Socket.
[    6.115668] systemd[1]: Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[    6.185466] systemd[1]: Mounting POSIX Message Queue File System...
[    6.225651] systemd[1]: Kernel Debug File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/debug).
[    6.240855] systemd[1]: Mounting Kernel Trace File System...
[    6.275686] systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
[    6.287225] systemd[1]: Mounting Temporary Directory /tmp...
[    6.329502] systemd[1]: Starting Create List of Static Device Nodes...
[    6.368569] systemd[1]: Starting Load Kernel Module configfs...
[    6.408684] systemd[1]: Starting Load Kernel Module dm_mod...
[    6.448663] systemd[1]: Starting Load Kernel Module drm...
[    6.488712] systemd[1]: Starting Load Kernel Module fuse...
[    6.528601] systemd[1]: Starting Load Kernel Module loop...
[    6.568467] systemd[1]: Starting Setup MAC addresses...
[    6.605604] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
[    6.618343] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[    6.631280] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[    6.642453] systemd[1]: Starting Journal Service...
[    6.683036] systemd[1]: Starting Load Kernel Modules...
[    6.703491] systemd-journald[1497]: Collecting audit messages is disabled.
[    6.718741] systemd[1]: Starting Generate network units from Kernel command line...
[    6.755456] systemd[1]: TPM2 PCR Machine ID Measurement was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
[    6.777659] systemd[1]: Starting Remount Root and Kernel File Systems...
[    6.793495] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    6.813106] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    6.822427] systemd[1]: Starting Coldplug All udev Devices...
[    6.861062] systemd[1]: Started Journal Service.
[    7.358687] mt7986-wmac 18000000.wifi: HW/SW Version: 0x8a108a10, Build Time: 20221012174743a

[    7.492878] mt7986-wmac 18000000.wifi: WM Firmware Version: ____000000, Build Time: 20221012174805
[    7.521596] systemd-journald[1497]: Received client request to flush runtime journal.
[    7.560109] mt7986-wmac 18000000.wifi: WA Firmware Version: DEV_000000, Build Time: 20221012174937
[    7.560401] systemd-journald[1497]: File /var/log/journal/303f85e3a6f342dfa35c5b31a5a5f92c/system.journal corrupted or uncleanly shut down, renaming and replacing.
[   28.045172] mt7986-wmac 18000000.wifi: Message 00000002 (seq 9) timeout
[   28.051783] mt7986-wmac 18000000.wifi: Failed to start WA firmware
[   28.058097] mt7986-wmac: probe of 18000000.wifi failed with error -110
[   28.962694] mtk_soc_eth 15100000.ethernet eth0: configuring for fixed/2500base-x link mode
[   28.972117] mtk_soc_eth 15100000.ethernet eth0: Link is Up - 2.5Gbps/Full - flow control rx/tx
[   28.978670] brlan: port 1(lan0) entered blocking state
[   28.985886] brlan: port 1(lan0) entered disabled state
[   28.991074] mt7530 mdio-bus:1f lan0: entered allmulticast mode
[   28.997546] mtk_soc_eth 15100000.ethernet eth0: entered allmulticast mode
[   29.010230] brlan: port 2(lan1) entered blocking state
[   29.015411] brlan: port 2(lan1) entered disabled state
[   29.020584] mt7530 mdio-bus:1f lan1: entered allmulticast mode
[   29.028612] mt7530 mdio-bus:1f lan1: entered promiscuous mode
[   29.034432] mt7530 mdio-bus:1f lan0: entered promiscuous mode
[   29.043073] brlan: port 3(lan2) entered blocking state
[   29.048276] brlan: port 3(lan2) entered disabled state
[   29.053456] mt7530 mdio-bus:1f lan2: entered allmulticast mode
[   29.061500] mt7530 mdio-bus:1f lan2: entered promiscuous mode
[   29.069769] brlan: port 4(lan3) entered blocking state
[   29.074936] brlan: port 4(lan3) entered disabled state
[   29.080135] mt7530 mdio-bus:1f lan3: entered allmulticast mode
[   29.088195] mt7530 mdio-bus:1f lan3: entered promiscuous mode
[   29.096365] brlan: port 5(lan4) entered blocking state
[   29.101566] brlan: port 5(lan4) entered disabled state
[   29.106836] mt7530 mdio-bus:1f lan4: entered allmulticast mode
[   29.114891] mt7530 mdio-bus:1f lan4: entered promiscuous mode
[   29.245979] mt7530 mdio-bus:1f wan: configuring for phy/gmii link mode
[   29.255891] mt7530 mdio-bus:1f wan: Link is Up - 1Gbps/Full - flow control rx/tx
[   29.263390] IPv6: ADDRCONF(NETDEV_CHANGE): wan: link becomes ready
[   29.327558] mt7530 mdio-bus:1f lan0: configuring for phy/gmii link mode
[   29.334755] 8021q: adding VLAN 0 to HW filter on device lan0
[   29.343879] mt7530 mdio-bus:1f lan1: configuring for phy/gmii link mode
[   29.351206] 8021q: adding VLAN 0 to HW filter on device lan1
[   29.360448] mt7530 mdio-bus:1f lan2: configuring for phy/gmii link mode
[   29.368050] 8021q: adding VLAN 0 to HW filter on device lan2
[   29.376883] mt7530 mdio-bus:1f lan3: configuring for phy/gmii link mode
[   29.384101] 8021q: adding VLAN 0 to HW filter on device lan3
[   29.392268] mt7530 mdio-bus:1f lan4: configuring for inband/2500base-x link mode
[   29.400807] 8021q: adding VLAN 0 to HW filter on device lan4
[   62.954937] systemd-journald[1497]: /var/log/journal/303f85e3a6f342dfa35c5b31a5a5f92c/user-1000.journal: Monotonic clock jumped backwards relative to last journal entry, rotating.
ostrich commented 1 year ago

Ok, apparently this is user error. I hadn't plugged in my wifi antennas. I assumed they were passive, but I guess not. Closing.

ericwoud commented 1 year ago

Hmz, I also do not have antennas attached at the moment, I need to check the journal log...

About the warnings, I thought I was finally rid of them, but apparently not on all systems... I will look into that also, re-opening your issue.

ericwoud commented 1 year ago

The warnings are gone, although I wish there was a more clean method.

Still need to check the log...

ericwoud commented 1 year ago

At the moment, I also have no antennas on the R3. I do not see anything about hostapd failing over and over in the journal.

ostrich commented 1 year ago

Interesting. Not sure what happened to my configuration then. I guess the reboot that got it working by chance happened at the same time I attached the antennas.