Turris OS 5.0.4 is released in the HBS (Stable) branch

Pepe · July 23, 2020, 2:17pm

Dear Turris users,

It is a pleasure to inform you that we just released Turris OS 5.0.4 in HBS (Stable) branch.

Highlight of this fixup release:

This release allows you to use multiple SSID on Turris MOX SDIO Wi-Fi card as our kernel developers, were able to take a look at mwifiex driver and they fixed memory corruption, which gets backported to LTS versions and it also needed some improvements in hostapd.

What has been changed in this release?

security updates for Python 3, squid, libvorbisidec, ntpd, bind
updates for msmtp, mwan3, xz, zstd, sumo, gkrellmd, golang, haveged, dnscrypt-proxy2, openvswitch.

You will be updated to this version automatically. If you are using updates on approval or delayed updated, you can apply the update in Foris.

As always, we appreciate any feedback regarding this release.

Pepe · July 23, 2020, 3:42pm

List of known bugs:

Since Turris OS 3.x release:
This time, there was no any ddns-package update, so you don’t need to worry about, but if you came from earlier versions of Turris OS, be careful!

If you have installed ddns-scripts package, there is a possibility that update can take several minutes or you will need to uninstall ddns-scripts package and install it afterward. This is an upstream issue.
More details: https://gitlab.labs.nic.cz/turris/turris-os-packages/issues/265

Since Turris OS 4.x release:

In some cases, Turris MOX is not correctly rebooted.
You can not have installed ttyd and mosquitto at the same time. This is an upstream issue and it was reported to them. See https://github.com/openwrt/packages/issues/11632

Since Turris OS 5.0 release:

Some Knot DNS packages can not be installed. This is fixed in development version of Turris OS 5.1.
Package v4l-utils was not compiled. This is going to be fixed in Turris OS 5.1.

yorik · July 23, 2020, 3:57pm

And I got ~20s internet connection interruption when updater restarted network interfaces. This isn’t good thing to have during a work meeting. Are there any chance to do automatic updates only during night?
I don’t wanna to switch to approving the updates, but I need to have no interruptions during working hours.

jklaas · July 23, 2020, 7:04pm

So far openvpn seems mostly stable. At least doesn’t appear to be crash-looping.

thierry · July 24, 2020, 1:27pm

not here. Openvpn has been looping in 5.0.4 since the update.
with this kind of logging

Jul 24 12:55:58 turris firewall: Reloading firewall due to ifup of vpn_turris (tun_turris)
Jul 24 12:56:00 turris openvpn(server_turris)[24439]: event_wait : Interrupted system call (code=4)
Jul 24 12:56:00 turris openvpn(server_turris)[24439]: /sbin/route del -net 10.111.111.0 netmask 255.255.255.0
Jul 24 12:56:00 turris openvpn(server_turris)[24439]: Closing TUN/TAP interface
Jul 24 12:56:00 turris openvpn(server_turris)[24439]: /sbin/ifconfig tun_turris 0.0.0.0
Jul 24 12:56:00 turris netifd: Network device ‘tun_turris’ link is down
Jul 24 12:56:00 turris netifd: Interface ‘vpn_turris’ has link connectivity loss
Jul 24 12:56:00 turris netifd: Interface ‘vpn_turris’ is now down
Jul 24 12:56:00 turris openvpn(server_turris)[24439]: SIGTERM[hard,] received, process exiting
Jul 24 12:56:00 turris netifd: Interface ‘vpn_turris’ is disabled
Jul 24 12:56:00 turris openvpn(server_turris)[25036]: OpenVPN 2.4.7 arm-openwrt-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD]
Jul 24 12:56:00 turris openvpn(server_turris)[25036]: library versions: OpenSSL 1.1.1g 21 Apr 2020, LZO 2.10
Jul 24 12:56:00 turris openvpn(server_turris)[25036]: Diffie-Hellman initialized with 2048 bit key
Jul 24 12:56:00 turris netifd: Interface ‘vpn_turris’ is enabled
Jul 24 12:56:00 turris netifd: Network device ‘tun_turris’ link is up
Jul 24 12:56:00 turris netifd: Interface ‘vpn_turris’ has link connectivity
Jul 24 12:56:00 turris netifd: Interface ‘vpn_turris’ is setting up now
Jul 24 12:56:00 turris netifd: Interface ‘vpn_turris’ is now up
Jul 24 12:56:00 turris openvpn(server_turris)[25036]: TUN/TAP device tun_turris opened

Back in 5.0.3 now.
No more problem.

pgotze · July 24, 2020, 8:17pm

Looks like openvpn is not working. Technically i am connected, but cannot reach any device IP.

mkyral · July 25, 2020, 8:08pm

I have set delayed update (48h) and delayed restart (3days at 3:30). I just returned from holiday and before I was able to approve the update, it was auto approved, installed and… restarted. At 21:27. Not in night and 3 days earlier. Why?

sammy_cda · July 25, 2020, 10:58pm

I updated this morning from 5.0.2 and everything seems to work fine. My system is pretty vanilla with only UPnP installed though.

I haven’t used the WIFI modules for awhile as they were unstable but am pleasantly surprised to find out they work great now!

Thanks for the great update.

k_205 · August 10, 2020, 11:44am

It seems after some time within 5.0.x SATA HDD RAID NAS issue were reintroduced. I am using 5.0 stable from automatic migration from 4. stable, however I’m guessing from 5.0.2, I’m not 100% sure which exactly. SATA is working properly on one port only. Other HDD fails, symptoms as in old post: https://forum.test.turris.cz/t/sata-hdd-issues/1173/160

ssdnvv · August 13, 2020, 4:21pm

I just today formatted my MOX with the latest TOS 5.0.4 as I was happy to finally be able to make use of the SDIO-WiFi but got disentchanted right after setting up the first SSID: it won’t come up (right like on TOS 4.x versions).
Here’s the

kernel log

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.14.187 (beast@slave10) (gcc version 7.5.0 (OpenWrt GCC 7.5.0 2ca5a38)) #0 SMP Tue Jul 21 23:10:07 2020
[    0.000000] Boot CPU: AArch64 Processor [410fd034]
[    0.000000] Machine model: CZ.NIC Turris Mox Board
[    0.000000] earlycon: ar3700_uart0 at MMIO 0x00000000d0012000 (options '')
[    0.000000] bootconsole [ar3700_uart0] enabled
[    0.000000] On node 0 totalpages: 131072
[    0.000000]   DMA zone: 2048 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 131072 pages, LIFO batch:31
[    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.1
[    0.000000] random: get_random_bytes called from start_kernel+0x9c/0x494 with crng_init=0
[    0.000000] percpu: Embedded 16 pages/cpu s26648 r8192 d30696 u65536
[    0.000000] pcpu-alloc: s26648 r8192 d30696 u65536 alloc=16*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] Speculative Store Bypass Disable mitigation not required
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 129024
[    0.000000] Kernel command line: earlyprintk console=ttyMV0,115200 earlycon=ar3700_uart,0xd0012000 mangled_fs=btrfs root=PARTUUID=2b574498-01 rootflags=commit=5,subvol=@ rootwait  rw cfg80211.freg= 
[    0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Memory: 496508K/524288K available (7294K kernel code, 468K rwdata, 1900K rodata, 320K init, 304K bss, 27780K reserved, 0K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     modules : 0xffffff8000000000 - 0xffffff8008000000   (   128 MB)
[    0.000000]     vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000   (   250 GB)
[    0.000000]       .text : 0xffffff8008080000 - 0xffffff80087a0000   (  7296 KB)
[    0.000000]     .rodata : 0xffffff80087a0000 - 0xffffff8008980000   (  1920 KB)
[    0.000000]       .init : 0xffffff8008980000 - 0xffffff80089d0000   (   320 KB)
[    0.000000]       .data : 0xffffff80089d0000 - 0xffffff8008a45008   (   469 KB)
[    0.000000]        .bss : 0xffffff8008a45008 - 0xffffff8008a911b0   (   305 KB)
[    0.000000]     fixed   : 0xffffffbefe7fb000 - 0xffffffbefec00000   (  4116 KB)
[    0.000000]     PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000   (    16 MB)
[    0.000000]     vmemmap : 0xffffffbf00000000 - 0xffffffc000000000   (     4 GB maximum)
[    0.000000]               0xffffffbf00000000 - 0xffffffbf00800000   (     8 MB actual)
[    0.000000]     memory  : 0xffffffc000000000 - 0xffffffc020000000   (   512 MB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	CONFIG_RCU_FANOUT set to non-default value of 32
[    0.000000] 	RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: no VLPI support, no direct LPI support
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000d1d40000
[    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.000004] sched_clock: 56 bits at 12MHz, resolution 80ns, wraps every 4398046511080ns
[    0.008422] Console: colour dummy device 80x25
[    0.012935] Calibrating delay loop (skipped), value calculated using timer frequency.. 25.00 BogoMIPS (lpj=125000)
[    0.023581] pid_max: default: 32768 minimum: 301
[    0.028429] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.035169] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.043415] ASID allocator initialised with 32768 entries
[    0.048995] Hierarchical SRCU implementation.
[    0.053858] smp: Bringing up secondary CPUs ...
[    0.058817] Detected VIPT I-cache on CPU1
[    0.058847] GICv3: CPU1: found redistributor 1 region 0:0x00000000d1d60000
[    0.058878] CPU1: Booted secondary processor [410fd034]
[    0.058965] smp: Brought up 1 node, 2 CPUs
[    0.079672] SMP: Total of 2 processors activated.
[    0.084519] CPU features: detected: GIC system register CPU interface
[    0.091140] CPU features: detected: 32-bit EL0 Support
[    0.096438] CPU: All CPU(s) started at EL2
[    0.100650] alternatives: patching kernel code
[    0.105906] devtmpfs: initialized
[    0.111372] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.121473] futex hash table entries: 512 (order: 4, 65536 bytes)
[    0.127825] xor: measuring software checksum speed
[    0.232727]    8regs     :  1918.800 MB/sec
[    0.332763]    8regs_prefetch:  1710.400 MB/sec
[    0.432800]    32regs    :  2358.800 MB/sec
[    0.532835]    32regs_prefetch:  1984.400 MB/sec
[    0.537502] xor: using function: 32regs (2358.800 MB/sec)
[    0.543069] pinctrl core: initialized pinctrl subsystem
[    0.549183] NET: Registered protocol family 16
[    0.554816] cpuidle: using governor ladder
[    0.559093] vdso: 2 pages (1 code @ ffffff80087a5000, 1 data @ ffffff80089d4000)
[    0.566663] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.573906] DMA: preallocated 256 KiB pool for atomic allocations
[    0.581416] d0012000.serial: ttyMV0 at MMIO 0xd0012000 (irq = 9, base_baud = 0) is a mvebu-uart
[    0.590362] console [ttyMV0] enabled
[    0.597663] bootconsole [ar3700_uart0] disabled
[    0.787207] raid6: int64x1  gen()   320 MB/s
[    0.957296] raid6: int64x1  xor()   317 MB/s
[    1.127340] raid6: int64x2  gen()   518 MB/s
[    1.297393] raid6: int64x2  xor()   449 MB/s
[    1.467490] raid6: int64x4  gen()   782 MB/s
[    1.637584] raid6: int64x4  xor()   550 MB/s
[    1.807607] raid6: int64x8  gen()   939 MB/s
[    1.977680] raid6: int64x8  xor()   561 MB/s
[    2.147839] raid6: neonx1   gen()   594 MB/s
[    2.317866] raid6: neonx1   xor()   624 MB/s
[    2.487963] raid6: neonx2   gen()   986 MB/s
[    2.657994] raid6: neonx2   xor()   943 MB/s
[    2.828100] raid6: neonx4   gen()  1313 MB/s
[    2.998148] raid6: neonx4   xor()  1166 MB/s
[    3.168228] raid6: neonx8   gen()  1297 MB/s
[    3.338304] raid6: neonx8   xor()  1131 MB/s
[    3.342446] raid6: using algorithm neonx4 gen() 1313 MB/s
[    3.347930] raid6: .... xor() 1166 MB/s, rmw enabled
[    3.353419] raid6: using neon recovery algorithm
[    3.358937] SCSI subsystem initialized
[    3.363127] libata version 3.00 loaded.
[    3.363349] usbcore: registered new interface driver usbfs
[    3.369055] usbcore: registered new interface driver hub
[    3.374189] usbcore: registered new device driver usb
[    3.380157] pxa2xx-i2c d0011000.i2c: could not find pctldev for node /soc/internal-regs@d0000000/pinctrl@13800/i2c1-pins, deferring probe
[    3.394286] clocksource: Switched to clocksource arch_sys_counter
[    3.407402] NET: Registered protocol family 2
[    3.411998] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[    3.419176] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
[    3.425937] TCP: Hash tables configured (established 4096 bind 4096)
[    3.432156] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    3.438201] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    3.444791] NET: Registered protocol family 1
[    3.449168] PCI: CLS 0 bytes, default 128
[    3.449813] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[    3.461943] Crashlog allocated RAM at address 0x3f00000
[    3.468082] workingset: timestamp_bits=46 max_order=17 bucket_order=0
[    3.478479] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    3.484573] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    3.497518] io scheduler noop registered
[    3.501312] io scheduler deadline registered (default)
[    3.609881] OF: PCI: host bridge /soc/pcie@d0070000 ranges:
[    3.615499] OF: PCI:   MEM 0xe8000000..0xe8ffffff -> 0xe8000000
[    3.621769] OF: PCI:    IO 0xe9000000..0xe900ffff -> 0xe9000000
[    4.124283] advk-pcie d0070000.pcie: link up
[    4.128853] advk-pcie d0070000.pcie: PCI host bridge to bus 0000:00
[    4.135178] pci_bus 0000:00: root bus resource [bus 00-ff]
[    4.140914] pci_bus 0000:00: root bus resource [mem 0xe8000000-0xe8ffffff]
[    4.147668] pci_bus 0000:00: root bus resource [io  0x0000-0xffff] (bus address [0xe9000000-0xe900ffff])
[    4.157792] pci 0000:00:00.0: [168c:003c] type 00 class 0x028000
[    4.157882] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x001fffff 64bit]
[    4.157975] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    4.158159] pci 0000:00:00.0: supports D1 D2
[    4.158304] pci 0000:00:00.0: BAR 0: assigned [mem 0xe8000000-0xe81fffff 64bit]
[    4.165906] pci 0000:00:00.0: BAR 6: assigned [mem 0xe8200000-0xe820ffff pref]
[    4.174535] mv_xor d0060900.xor: Marvell shared XOR driver
[    4.234868] mv_xor d0060900.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[    4.304872] mv_xor d0060900.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[    4.312539] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    4.321166] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    4.331654] loop: module loaded
[    4.341496] random: fast init done
[    4.349825] m25p80 spi0.0: w25q64dw (8192 Kbytes)
[    4.354596] 5 fixed-partitions partitions found on MTD device spi0.0
[    4.361250] Creating 5 MTD partitions on "spi0.0":
[    4.366223] 0x000000000000-0x000000020000 : "secure-firmware"
[    4.372658] 0x000000020000-0x000000180000 : "u-boot"
[    4.378476] 0x000000180000-0x000000190000 : "u-boot-env"
[    4.384202] 0x000000190000-0x0000007f0000 : "Rescue system"
[    4.390550] 0x0000007f0000-0x000000800000 : "dtb"
[    4.396060] moxtet spi0.1: Found SD Turris Mox CPU module
[    4.401651] moxtet spi0.1: PCIe module found
[    4.406769] libphy: Fixed MDIO Bus: probed
[    4.411378] libphy: orion_mdio_bus: probed
[    4.417765] mvneta d0030000.ethernet eth0: Using device tree mac address d8:58:d7:00:c8:78
[    4.426408] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    4.433078] ehci-pci: EHCI PCI platform driver
[    4.437388] ehci-platform: EHCI generic platform driver
[    4.442902] ehci-orion: EHCI orion driver
[    4.447378] orion-ehci d005e000.usb: EHCI Host Controller
[    4.452618] orion-ehci d005e000.usb: new USB bus registered, assigned bus number 1
[    4.460723] orion-ehci d005e000.usb: irq 12, io mem 0xd005e000
[    4.494291] orion-ehci d005e000.usb: USB 2.0 started, EHCI 1.00
[    4.500875] hub 1-0:1.0: USB hub found
[    4.504540] hub 1-0:1.0: 1 port detected
[    4.509731] usbcore: registered new interface driver usb-storage
[    4.516063] i2c /dev entries driver
[    4.520298] armada_37xx_wdt d0008300.watchdog: Initial timeout 120 sec
[    4.527477] sdhci: Secure Digital Host Controller Interface driver
[    4.533606] sdhci: Copyright(c) Pierre Ossman
[    4.538078] sdhci-pltfm: SDHCI platform and OF driver helper
[    4.544301] xenon-sdhci d00d0000.sdhci: allocated mmc-pwrseq
[    4.550002] xenon-sdhci d00d8000.sdhci: Got CD GPIO
[    4.556774] NET: Registered protocol family 10
[    4.561975] Segment Routing with IPv6
[    4.565673] NET: Registered protocol family 17
[    4.569939] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    4.583695] 8021q: 802.1Q VLAN Support v1.8
[    4.589928] Btrfs loaded, crc32c=crc32c-arm64-ce
[    4.598670] rtc-ds1307 0-006f: registered as rtc0
[    4.603232] i2c i2c-0:  PXA I2C adapter
[    4.607533] xhci-hcd d0058000.usb: xHCI Host Controller
[    4.612779] xhci-hcd d0058000.usb: new USB bus registered, assigned bus number 2
[    4.620293] xhci-hcd d0058000.usb: hcc params 0x0a000998 hci version 0x100 quirks 0x0000000000010010
[    4.629816] xhci-hcd d0058000.usb: irq 11, io mem 0xd0058000
[    4.636189] hub 2-0:1.0: USB hub found
[    4.640100] hub 2-0:1.0: 1 port detected
[    4.644316] xhci-hcd d0058000.usb: xHCI Host Controller
[    4.649462] xhci-hcd d0058000.usb: new USB bus registered, assigned bus number 3
[    4.657313] xhci-hcd d0058000.usb: Host supports USB 3.0  SuperSpeed
[    4.664010] usb usb3: We don't know the algorithms for LPM for this host, disabling LPM.
[    4.672842] hub 3-0:1.0: USB hub found
[    4.676686] hub 3-0:1.0: 1 port detected
[    4.681025] xenon-sdhci d00d0000.sdhci: allocated mmc-pwrseq
[    4.744292] mmc0: SDHCI controller on d00d0000.sdhci [d00d0000.sdhci] using ADMA
[    4.751941] xenon-sdhci d00d8000.sdhci: Got CD GPIO
[    4.797529] sdio_read_cccr card report SDIO_UHS_DDR50
[    4.802580] sdio_read_cccr card report SDIO_UHS_SDR50
[    4.807898] sdio_read_cccr card report SDIO_UHS_SDR104
[    4.814478] mmc1: SDHCI controller on d00d8000.sdhci [d00d8000.sdhci] using ADMA
[    4.824569] armada-37xx-rwtm-mailbox d00b0000.mailbox: Trust Status: 00000000
[    4.934584] Turris Mox serial number 0000000D3000755E
[    4.939634]            board version 22
[    4.943314]            burned RAM size 512 MiB
[    5.264601] random: crng init done
[    5.268052] rtc-ds1307 0-006f: setting system clock to 2020-08-13 15:09:05 UTC (1597331345)
[    5.396272] Waiting for root device PARTUUID=2b574498-01...
[    5.407939] xenon-sdhci d00d0000.sdhci: Timing issue might occur in DDR mode
[    5.429289] mmc0: new ultra high speed DDR50 SDIO card at address 0001
[    5.445334] mmc1: new ultra high speed DDR50 SDHC card at address 0007
[    5.452534] mmcblk1: mmc1:0007 SD8GB 7.42 GiB 
[    5.459038]  mmcblk1: p1
[    5.498343] F2FS-fs (mmcblk1p1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    5.506062] F2FS-fs (mmcblk1p1): Can't find valid F2FS filesystem in 1th superblock
[    5.515342] F2FS-fs (mmcblk1p1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    5.522466] F2FS-fs (mmcblk1p1): Can't find valid F2FS filesystem in 2th superblock
[    5.530449] F2FS-fs (mmcblk1p1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    5.538152] F2FS-fs (mmcblk1p1): Can't find valid F2FS filesystem in 1th superblock
[    5.545785] F2FS-fs (mmcblk1p1): Magic Mismatch, valid(0xf2f52010) - read(0x0)
[    5.553156] F2FS-fs (mmcblk1p1): Can't find valid F2FS filesystem in 2th superblock
[    5.562501] BTRFS: device label turris devid 1 transid 60 /dev/root
[    5.570871] BTRFS info (device mmcblk1p1): disk space caching is enabled
[    5.577861] BTRFS info (device mmcblk1p1): has skinny extents
[    5.608148] BTRFS info (device mmcblk1p1): enabling ssd optimizations
[    5.624413] VFS: Mounted root (btrfs filesystem) on device 0:12.
[    5.632387] devtmpfs: mounted
[    5.635364] Freeing unused kernel memory: 320K
[    5.751987] init: Console is alive
[    5.755737] init: - watchdog -
[    6.303280] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    6.323674] usbcore: registered new interface driver uas
[    6.329094] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    6.346845] init: - preinit -
[    6.784879] mvneta d0030000.ethernet eth0: PHY [d0032004.mdio-mii:01] driver [Marvell 88E1510]
[    6.794160] mvneta d0030000.ethernet eth0: configuring for phy/rgmii-id link mode
[    6.804740] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    9.928627] mount_root: mounting /dev/root
[   11.095564] mvneta d0030000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[   11.103963] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   12.792600] BTRFS info (device mmcblk1p1): disk space caching is enabled
[   13.411045] mvneta d0030000.ethernet eth0: Link is Down
[   13.427597] procd: - early -
[   13.430644] procd: - watchdog -
[   14.101584] procd: - watchdog -
[   14.105520] procd: - ubus -
[   14.166248] procd: - init -
[   14.290679] kmodloader: loading kernel modules from /etc/modules.d/*
[   14.312949] zram: Added device: zram0
[   14.324530] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[   14.339032] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   14.349491] Netfilter messages via NETLINK v0.30.
[   14.356354] ip_set: protocol 6
[   14.383511] Loading modules backported from Linux version v4.19.120-0-gfdc072324f3c
[   14.391532] Backport generated by backports.git v4.19.120-1-0-g60c3a249
[   14.429267] hidraw: raw HID events driver (C) Jiri Kosina
[   14.438246] Mirror/redirect action on
[   14.450332] u32 classifier
[   14.452876]     input device check on
[   14.456774]     Actions configured
[   14.466870] sch_cake: Unknown symbol nf_conntrack_find_get (err 0)
[   14.473314] sch_cake: Unknown symbol nf_ct_get_tuplepr (err 0)
[   14.532895] ath10k_pci 0000:00:00.0: enabling device (0000 -> 0002)
[   14.539829] ath10k_pci 0000:00:00.0: pci irq msi oper_irq_mode 2 irq_mode 0 reset_mode 0
[   14.777827] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
[   14.789043] ath10k_pci 0000:00:00.0: Falling back to user helper
[   14.944654] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
[   14.953839] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/cal-pci-0000:00:00.0.bin failed with error -2
[   14.964659] ath10k_pci 0000:00:00.0: Falling back to user helper
[   14.979814] firmware ath10k!cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
[   14.989674] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-6.bin failed with error -2
[   15.000837] ath10k_pci 0000:00:00.0: Falling back to user helper
[   15.016119] firmware ath10k!QCA988X!hw2.0!firmware-6.bin: firmware_loading_store: map pages failed
[   15.037234] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
[   15.046840] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1
[   15.060968] ath10k_pci 0000:00:00.0: firmware ver 10.2.4-1.0-00047 api 5 features no-p2p,raw-mode,mfp,allows-mesh-bcast crc32 35bd9258
[   15.106821] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
[   15.117566] ath10k_pci 0000:00:00.0: Falling back to user helper
[   15.132878] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
[   15.143493] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
[   16.301713] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal otp max-sta 128 raw 0 hwcrypto 1
[   16.426644] ath: EEPROM regdomain: 0x0
[   16.426656] ath: EEPROM indicates default country code should be used
[   16.426661] ath: doing EEPROM country->regdmn map search
[   16.426672] ath: country maps to regdmn code: 0x3a
[   16.426678] ath: Country alpha2 being used: US
[   16.426684] ath: Regpair used: 0x3a
[   16.452564] Bluetooth: Core ver 2.22
[   16.456380] NET: Registered protocol family 31
[   16.460990] Bluetooth: HCI device and connection manager initialized
[   16.467211] Bluetooth: HCI socket layer initialized
[   16.472450] Bluetooth: L2CAP socket layer initialized
[   16.477843] Bluetooth: SCO socket layer initialized
[   16.484525] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   16.489865] Bluetooth: BNEP filters: protocol multicast
[   16.495140] Bluetooth: BNEP socket layer initialized
[   16.502606] Bridge firewalling registered
[   16.512593] Bluetooth: vendor=0x2df, device=0x9142, class=255, fn=2
[   18.118205] Bluetooth: FW download over, size 623240 bytes
[   18.354601] sdio platform data not available
[   18.365404] usbcore: registered new interface driver btusb
[   18.386999] Ebtables v2.0 registered
[   18.391901] Bluetooth: HCI UART driver ver 2.3
[   18.396412] Bluetooth: HCI UART protocol H4 registered
[   18.401733] Bluetooth: HCI UART protocol BCSP registered
[   18.407062] Bluetooth: HCI UART protocol ATH3K registered
[   18.414179] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[   18.420401] Bluetooth: HIDP socket layer initialized
[   18.427784] ip_tables: (C) 2000-2006 Netfilter Core Team
[   18.456122] nf_conntrack version 0.5.0 (4096 buckets, 16384 max)
[   18.467531] ctnetlink v0.93: registering with nfnetlink.
[   18.506187] Bluetooth: RFCOMM TTY layer initialized
[   18.511127] Bluetooth: RFCOMM socket layer initialized
[   18.516540] Bluetooth: RFCOMM ver 1.11
[   18.524640] usbcore: registered new interface driver ums-alauda
[   18.532088] usbcore: registered new interface driver ums-cypress
[   18.539128] usbcore: registered new interface driver ums-datafab
[   18.546179] usbcore: registered new interface driver ums-freecom
[   18.553154] usbcore: registered new interface driver ums-isd200
[   18.560344] usbcore: registered new interface driver ums-jumpshot
[   18.567502] usbcore: registered new interface driver ums-karma
[   18.574358] usbcore: registered new interface driver ums-sddr09
[   18.581199] usbcore: registered new interface driver ums-sddr55
[   18.587465] mwifiex_sdio mmc0:0001:1: WLAN is not the winner! Skip FW dnld
[   18.596195] usbcore: registered new interface driver ums-usbat
[   18.647877] xt_time: kernel timezone is -0000
[   18.672307] PPP generic driver version 2.4.2
[   18.678276] NET: Registered protocol family 24
[   18.699173] kmodloader: done loading kernel modules from /etc/modules.d/*
[   18.954654] mwifiex_sdio mmc0:0001:1: WLAN FW is active
[   18.990351] mwifiex_sdio mmc0:0001:1: Unknown api_id: 3
[   18.995741] mwifiex_sdio mmc0:0001:1: Unknown api_id: 4
[   19.001000] mwifiex_sdio mmc0:0001:1: Unknown GET_HW_SPEC TLV type: 0x217
[   19.029909] mwifiex_sdio mmc0:0001:1: info: MWIFIEX VERSION: mwifiex 1.0 (16.68.1.p197) 
[   19.038249] mwifiex_sdio mmc0:0001:1: driver_version = mwifiex 1.0 (16.68.1.p197) 
[   19.261560] zram0: detected capacity change from 0 to 253755392
[   19.278743] Adding 247804k swap on /dev/zram0.  Priority:-2 extents:1 across:247804k SS
[   21.514733] mvneta d0030000.ethernet eth0: PHY [d0032004.mdio-mii:01] driver [Marvell 88E1510]
[   21.524571] mvneta d0030000.ethernet eth0: configuring for phy/rgmii-id link mode
[   21.532619] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   21.541494] mvneta d0030000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[   21.543279] br-gast: port 1(eth0.5) entered blocking state
[   21.555165] br-gast: port 1(eth0.5) entered disabled state
[   21.561441] device eth0.5 entered promiscuous mode
[   21.566297] device eth0 entered promiscuous mode
[   21.571662] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   21.586490] br-gast: port 1(eth0.5) entered blocking state
[   21.592212] br-gast: port 1(eth0.5) entered forwarding state
[   21.598063] IPv6: ADDRCONF(NETDEV_UP): br-gast: link is not ready
[   21.768199] br-iot: port 1(eth0.2) entered blocking state
[   21.773818] br-iot: port 1(eth0.2) entered disabled state
[   21.783976] device eth0.2 entered promiscuous mode
[   21.797084] br-iot: port 1(eth0.2) entered blocking state
[   21.802606] br-iot: port 1(eth0.2) entered forwarding state
[   22.062338] br-lan: port 1(eth0.1) entered blocking state
[   22.067967] br-lan: port 1(eth0.1) entered disabled state
[   22.076993] device eth0.1 entered promiscuous mode
[   22.087523] br-lan: port 1(eth0.1) entered blocking state
[   22.092982] br-lan: port 1(eth0.1) entered forwarding state
[   22.614500] IPv6: ADDRCONF(NETDEV_CHANGE): br-gast: link becomes ready
[   22.698456] mvneta d0030000.ethernet eth0: Link is Down
[   22.873390] ath: EEPROM regdomain: 0x8114
[   22.877503] ath: EEPROM indicates we should expect a country code
[   22.883685] ath: doing EEPROM country->regdmn map search
[   22.889408] ath: country maps to regdmn code: 0x37
[   22.894115] ath: Country alpha2 being used: DE
[   22.898917] ath: Regpair used: 0x37
[   22.902493] ath: regdomain 0x8114 dynamically updated by user
[   23.656846] br-lan: port 1(eth0.1) entered disabled state
[   23.674436] br-iot: port 1(eth0.2) entered disabled state
[   23.686974] br-gast: port 1(eth0.5) entered disabled state
[   23.977257] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0x20 error, result=0x1
[   24.775492] mvneta d0030000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[   26.522545] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
[   26.545445] IPv6: ADDRCONF(NETDEV_UP): wifi5lan: link is not ready
[   26.558527] br-lan: port 1(eth0.1) entered blocking state
[   26.563962] br-lan: port 1(eth0.1) entered forwarding state
[   26.570050] br-iot: port 1(eth0.2) entered blocking state
[   26.575270] br-iot: port 1(eth0.2) entered forwarding state
[   26.581383] br-gast: port 1(eth0.5) entered blocking state
[   26.587071] br-gast: port 1(eth0.5) entered forwarding state
[   26.596587] IPv6: ADDRCONF(NETDEV_UP): wifi2lan: link is not ready
[   26.606071] br-lan: port 2(wifi5lan) entered blocking state
[   26.611865] br-lan: port 2(wifi5lan) entered disabled state
[   26.617929] device wifi5lan entered promiscuous mode
[   26.645533] br-lan: port 3(wifi2lan) entered blocking state
[   26.650972] br-lan: port 3(wifi2lan) entered disabled state
[   26.657199] device wifi2lan entered promiscuous mode
[   26.662427] br-lan: port 3(wifi2lan) entered blocking state
[   26.668014] br-lan: port 3(wifi2lan) entered forwarding state
[   28.564485] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0xb1 error, result=0x1
[   28.571558] mwifiex_sdio mmc0:0001:1: Failed to start the BSS
[   28.577442] mwifiex_sdio mmc0:0001:1: Failed to start AP
[   28.583438] br-lan: port 3(wifi2lan) entered disabled state
[   28.724602] device wifi2lan left promiscuous mode
[   28.729630] br-lan: port 3(wifi2lan) entered disabled state
[   90.271732] br-lan: port 2(wifi5lan) entered blocking state
[   90.277785] br-lan: port 2(wifi5lan) entered forwarding state

and the

wifi settings

config wifi-device ‘radio0’
option type ‘mac80211’
option macaddr ‘04:f0:21:24:1c:75’
option disabled ‘0’
option hwmode ‘11a’
option txpower ‘20’
option channel ‘100’
option legacy_rates ‘0’
option country ‘DE’
option htmode ‘VHT80’

config wifi-iface ‘default_radio0’
option device ‘radio0’
option network ‘lan’
option mode ‘ap’
option encryption ‘psk2+ccmp’
option wpa_group_rekey ‘86400’
option key ‘12345678’
option disabled ‘0’
option ft_over_ds ‘1’
option ifname ‘wifi5lan’
option wpa_disable_eapol_key_retries ‘1’
option mobility_domain ‘xyxa’
option ft_psk_generate_local ‘1’
option ieee80211r ‘1’
option ssid ‘SSID1’

config wifi-device ‘radio1’
option type ‘mac80211’
option macaddr ‘d0:c5:d3:33:dd:f1’
option disabled ‘0’
option hwmode ‘11g’
option txpower ‘20’
option channel ‘1’
option noscan ‘1’
option legacy_rates ‘0’
option htmode ‘HT40’
option country ‘DE’

config wifi-iface ‘default_radio1’
option device ‘radio1’
option network ‘lan’
option mode ‘ap’
option disabled ‘0’
option encryption ‘psk2+ccmp’
option wpa_group_rekey ‘86400’
option key ‘12345678’
option ft_over_ds ‘1’
option ifname ‘wifi2lan’
option ssid ‘OpenWrt’
option wpa_disable_eapol_key_retries ‘1’
option mobility_domain ‘xyxa’
option ft_psk_generate_local ‘1’
option ieee80211r ‘1’

radio0 is a WLE900VX which works fine (just as on TOS 4.x).
By the way - luci still shows the SDIO-WiFi to be active:

@Pepe in my eyes this bug doesn’t seem to be fixed! I am not even able to use one single SSID on this card… Or is there any firmware upgrade procedure necessary for which pkgupdate is needed (as I reached 5.0.4 not by upgrading but reformatting my SD-card)?

And yet another bug (even though this might be an upstream and certainly harmless one):
Have a look into the luci-wireless screenshot above: The SSID1 is shown as not active/disabled even though one device is successfully connected.

And there is new other bug which I never saw bevor - when trying to have look into syslog via luci I get

This one occured after

rebooting MOX → didn’t work (reboot bug)
unplugging and after 30 seconds replugging the power plug

and seems to not disappear from itself. So question would be which file I need to create in order to get syslog back again?

Pepe · August 17, 2020, 8:29am

Not sure how did you configure your Wi-Fi. In LuCi or Foris maybe even both? Who knows.
Culprit is definitely this one:

[   28.564485] mwifiex_sdio mmc0:0001:1: CMD_RESP: cmd 0xb1 error, result=0x1
[   28.571558] mwifiex_sdio mmc0:0001:1: Failed to start the BSS
[   28.577442] mwifiex_sdio mmc0:0001:1: Failed to start AP

Some of your options in your WI-Fi config does not definitely come from Foris, which I am not saying that you shouldn’t use LuCI if you know what you are doing. However, logfile should not happen. Is syslog-ng running?

ssdnvv · August 17, 2020, 8:32pm

It was created with Foris (activated) but completely changed in Luci - I deleted the SSIDs and recreated them in Luci (as there are lots of options Foris doesn’t offer). Those options work great with a Omnia and TOS 4.x and later (but I guess that doesn’t count as it is a completely different driver). It was a little bit strange as I tried to undo all changes and ended with the very same config with which I started by Foris but the kernel logs are repeatedly the ones you quoted never mind how often I power cycled my MOX.
I can do step by step troubleshooting - but maybe as a start it would be easier if you tell me which options you tested to be working. You said multi-SSID should be working which cannot be created via Foris so you used also luci to create your configuration, right? Or did you just use/test the guest-WiFi-option in Foris?

I will check and report as soon as I return from my holidays.

ssdnvv · August 17, 2020, 8:40pm

Actually there might just be options which are standard in luci but are not supported by the driver, right? So we need a list which options work and which don’t (so maybe those bugs could be closed in the end…)