OpenWrt/LEDE Project

  • Status Unconfirmed
  • Percent Complete
    0%
  • Task Type Bug Report
  • Category Base system
  • Assigned To No-one
  • Operating System All
  • Severity Medium
  • Priority Very Low
  • Reported Version Trunk
  • Due in Version Undecided
  • Due Date Undecided
  • Votes 2
  • Private
Attached to Project: OpenWrt/LEDE Project
Opened by huaracheguarache - 23.02.2018

FS#1385 - Unable to set up repeater on DFS-channel

I have a TP-Link RE450 v1 wireless repeater running OpenWrt SNAPSHOT r6257-94a3af88f3 that I want to set up to extend a network running on a DFS-channel. I am able to connect to the main network in client mode - it is hosted on a NETGEAR R7800 running OpenWrt SNAPSHOT r6150-dc7a1e8555 (hnyman’s latest build). However, the repeating network refuses to start up. This is the guide I followed to set up the relayd-based relay (Luci GUI method): https://openwrt.org/docs/user-guide/wifi/relay_configuration

When following the same method to set up a non-DFS channel everything works as it should.

Here is the system log:

Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.000000] Linux version 4.9.82 (buildbot@builds) (gcc version 5.5.0 (OpenWrt GCC 5.5.0 r6038-13e8d54) ) #0 Sun Feb 18 08:21:12 2018
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] MyLoader: sysp=212b15a7, boardp=2d6f6985, parts=6de765b6
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] bootconsole [early0] enabled
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] CPU0 revision is: 00019750 (MIPS 74Kc)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] SoC: Qualcomm Atheros QCA9558 ver 1 rev 0
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Determined physical RAM map:
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] memory: 08000000 @ 00000000 (usable)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Initrd not found or empty - disabling initrd
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Zone ranges:
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Normal [mem 0×0000000000000000-0x0000000007ffffff]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Movable zone start for each node
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Early memory node ranges
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] node 0: [mem 0×0000000000000000-0x0000000007ffffff]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Initmem setup node 0 [mem 0×0000000000000000-0x0000000007ffffff]
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] On node 0 totalpages: 32768
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] free_area_init_node: node 0, pgdat 804ab9e4, node_mem_map 81000020
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] Normal zone: 256 pages used for memmap
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] Normal zone: 0 pages reserved
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] Normal zone: 32768 pages, LIFO batch:7
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.000000] pcpu-alloc: [0] 0
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.000000] Kernel command line: board=RE450 mtdparts=spi0.0:128k(u-boot)ro,6016k(firmware),64k(partition-table)ro,64k(product-info)ro,1856k(config)ro,64k(art)ro console=ttyS0,115200 rootfstype=squashfs noinitrd
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Writing ErrCtl register=00000000
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Readback ErrCtl register=00000000
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Memory: 124612K/131072K available (3394K kernel code, 174K rwdata, 836K rodata, 260K init, 219K bss, 6460K reserved, 0K cma-reserved)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] NR_IRQS:51
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] Clocks: CPU:720.000MHz, DDR:600.000MHz, AHB:200.000MHz, Ref:40.000MHz
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5309056796 ns
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.000007] sched_clock: 32 bits at 360MHz, resolution 2ns, wraps every 5965232126ns
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.008299] Calibrating delay loop... 358.80 BogoMIPS (lpj=1794048)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.071187] pid_max: default: 32768 minimum: 301
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.076184] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.083229] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.092660] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.103156] futex hash table entries: 256 (order: -1, 3072 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.110567] NET: Registered protocol family 16
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.116508] MIPS: machine is TP-LINK RE450
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 0.122731] registering PCI controller with io_map_base unset
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 0.128976] ar724x-pci ar724x-pci.1: PCIe link is down
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 0.134441] registering PCI controller with io_map_base unset
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.391525] PCI host bridge to bus 0000:00
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.395892] pci_bus 0000:00: root bus resource [mem 0×10000000-0x11ffffff]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.403234] pci_bus 0000:00: root bus resource [io 0×0000]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.409157] pci_bus 0000:00: root bus resource [??? 0×00000000 flags 0×0]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.416382] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.424828] pci 0000:00:00.0: [168c:003c] type 00 class 0×028000 Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.424865] pci 0000:00:00.0: reg 0×10: [mem 0×00000000-0x001fffff 64bit]
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.424915] pci 0000:00:00.0: reg 0×30: [mem 0×00000000-0x0000ffff pref]
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.424980] pci 0000:00:00.0: supports D1 D2
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.425150] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.425171] pci 0000:00:00.0: BAR 0: assigned [mem 0×10000000-0x101fffff 64bit]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.432989] pci 0000:00:00.0: BAR 6: assigned [mem 0×10200000-0x1020ffff pref]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.440777] PCI host bridge to bus 0000:01
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.445157] pci_bus 0000:01: root bus resource [mem 0×12000000-0x13ffffff]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.452466] pci_bus 0000:01: root bus resource [io 0×0001]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.458398] pci_bus 0000:01: root bus resource [??? 0×00000000 flags 0×0]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.465613] pci_bus 0000:01: No busn resource found for root bus, will use [bus 01-ff]
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.474055] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.474068] pci 0000:00:00.0: using irq 40 for pin 1
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.481748] clocksource: Switched to clocksource MIPS
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.487998] NET: Registered protocol family 2
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.493366] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.500789] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.507593] TCP: Hash tables configured (established 1024 bind 1024)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.514433] UDP hash table entries: 256 (order: 0, 4096 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.520649] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.527538] NET: Registered protocol family 1
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 0.532235] PCI: CLS 0 bytes, default 32
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 0.534326] Crashlog allocated RAM at address 0x3f00000
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.540612] workingset: timestamp_bits=30 max_order=15 bucket_order=0
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.553247] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.559445] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) © 2001-2006 Red Hat, Inc.
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.576804] io scheduler noop registered
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.580974] io scheduler deadline registered (default)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.586810] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.595712] console [ttyS0] disabled
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.619586] serial8250.0: ttyS0 at MMIO 0×18020000 (irq = 11, base_baud = 2500000) is a 16550A
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.628770] console [ttyS0] enabled
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.636186] bootconsole [early0] disabled
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 0.648931] m25p80 spi0.0: found s25fl064k, expected m25p80
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.667134] m25p80 spi0.0: s25fl064k (8192 Kbytes)
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.672033] 6 cmdlinepart partitions found on MTD device spi0.0
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.678029] Creating 6 MTD partitions on “spi0.0”:
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.682906] 0×000000000000-0×000000020000 : “u-boot” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.689064] 0×000000020000-0×000000600000 : “firmware” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.707081] 2 tplink-fw partitions found on MTD device firmware
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.713127] 0×000000020000-0x000000185ceb : “kernel” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.719204] 0x0000001a0000-0×000000600000 : “rootfs” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.725496] mtd: device 3 (rootfs) set to be root filesystem
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.731263] 1 squashfs-split partitions found on MTD device rootfs
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.737575] 0×000000440000-0×000000600000 : “rootfs_data” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.744349] 0×000000600000-0×000000610000 : “partition-table” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.751431] 0×000000610000-0×000000620000 : “product-info” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.758325] 0×000000620000-0x0000007f0000 : “config” Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 0.764662] 0x0000007f0000-0×000000800000 : “art” Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.773062] libphy: GPIO Bitbanged MDIO: probed
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 0.778061] libphy: Fixed MDIO Bus: probed
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.512179] ag71xx ag71xx.0: connected to PHY at gpio-0:04 [uid=004dd072, driver=Atheros 8035 ethernet]
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.522288] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.530065] NET: Registered protocol family 10
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.537560] NET: Registered protocol family 17
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.542160] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.555384] 8021q: 802.1Q VLAN Support v1.8
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.561016] hctosys: unable to open rtc device (rtc0)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.571029] VFS: Mounted root (squashfs filesystem) readonly on device 31:3.
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 1.579154] Freeing unused kernel memory: 260K
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 1.583687] This architecture does not have kernel memory protection.
Fri Feb 23 18:36:12 2018 user.info kernel: [ 2.132376] init: Console is alive
Fri Feb 23 18:36:12 2018 user.info kernel: [ 2.135998] init: - watchdog -
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 2.494811] random: fast init done
Fri Feb 23 18:36:12 2018 user.info kernel: [ 2.924159] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Fri Feb 23 18:36:12 2018 user.info kernel: [ 2.965714] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Fri Feb 23 18:36:12 2018 user.info kernel: [ 2.974640] init: - preinit -
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 4.004457] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 7.093477] eth0: link up (1000Mbps/Full duplex)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 7.098187] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Fri Feb 23 18:36:12 2018 kern.notice kernel: [ 7.293806] jffs2: notice: (403) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
Fri Feb 23 18:36:12 2018 user.info kernel: [ 7.311068] mount_root: switching to jffs2 overlay
Fri Feb 23 18:36:12 2018 user.warn kernel: [ 7.337522] urandom-seed: Seeding with /etc/urandom.seed
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 7.435532] eth0: link down
Fri Feb 23 18:36:12 2018 user.info kernel: [ 7.447458] procd: - early -
Fri Feb 23 18:36:12 2018 user.info kernel: [ 7.450449] procd: - watchdog -
Fri Feb 23 18:36:12 2018 user.info kernel: [ 8.057837] procd: - watchdog -
Fri Feb 23 18:36:12 2018 user.info kernel: [ 8.061236] procd: - ubus -
Fri Feb 23 18:36:12 2018 user.info kernel: [ 8.168116] procd: - init -
Fri Feb 23 18:36:12 2018 user.info kernel: [ 8.539737] kmodloader: loading kernel modules from /etc/modules.d/*
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.571268] ip6_tables: (C) 2000-2006 Netfilter Core Team
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.585643] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.593800] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.602959] ip_tables: (C) 2000-2006 Netfilter Core Team
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.614861] nf_conntrack version 0.5.0 (2048 buckets, 8192 max)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.662930] xt_time: kernel timezone is -0000
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.831336] PPP generic driver version 2.4.2
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.837672] NET: Registered protocol family 24
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 8.861154] PCI: Enabling device 0000:00:00.0 (0000 → 0002)
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 8.867107] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 9.145193] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 9.156072] ath10k_pci 0000:00:00.0: Falling back to user helper
Fri Feb 23 18:36:12 2018 kern.err kernel: [ 9.344946] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 9.355293] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-6.bin failed with error -2
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 9.366187] ath10k_pci 0000:00:00.0: Falling back to user helper
Fri Feb 23 18:36:12 2018 kern.err kernel: [ 9.494658] firmware ath10k!QCA988X!hw2.0!firmware-6.bin: firmware_loading_store: map pages failed
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 9.508099] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 9.517496] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 9.530543] ath10k_pci 0000:00:00.0: firmware ver 10.2.4-1.0-00033 api 5 features no-p2p,raw-mode,mfp,allows-mesh-bcast crc32 c41417d0
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 9.624942] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
Fri Feb 23 18:36:12 2018 kern.warn kernel: [ 9.635573] ath10k_pci 0000:00:00.0: Falling back to user helper
Fri Feb 23 18:36:12 2018 kern.err kernel: [ 9.705908] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 9.735953] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
Fri Feb 23 18:36:12 2018 kern.info kernel: [ 10.826902] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 10.963073] ath: EEPROM regdomain: 0×0 Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 10.963080] ath: EEPROM indicates default country code should be used
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 10.963083] ath: doing EEPROM country→regdmn map search
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 10.963093] ath: country maps to regdmn code: 0x3a
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 10.963098] ath: Country alpha2 being used: US
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 10.963101] ath: Regpair used: 0x3a
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 11.080469] ath: EEPROM regdomain: 0×0 Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 11.080476] ath: EEPROM indicates default country code should be used
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 11.080478] ath: doing EEPROM country→regdmn map search
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 11.080490] ath: country maps to regdmn code: 0x3a
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 11.080495] ath: Country alpha2 being used: US
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 11.080497] ath: Regpair used: 0x3a
Fri Feb 23 18:36:12 2018 kern.debug kernel: [ 11.096449] ieee80211 phy1: Selected rate control algorithm ‘minstrel_ht’ Fri Feb 23 18:36:12 2018 kern.info kernel: [ 11.099579] ieee80211 phy1: Atheros AR9550 Rev:0 mem=0xb8100000, irq=47
Fri Feb 23 18:36:12 2018 user.info kernel: [ 11.109568] kmodloader: done loading kernel modules from /etc/modules.d/*
Fri Feb 23 18:36:14 2018 user.notice : Added device handler type: tunnel
Fri Feb 23 18:36:14 2018 user.notice : Added device handler type: Network device
Fri Feb 23 18:36:14 2018 user.notice : Added device handler type: bridge
Fri Feb 23 18:36:14 2018 user.notice : Added device handler type: veth
Fri Feb 23 18:36:14 2018 user.notice : Added device handler type: macvlan
Fri Feb 23 18:36:14 2018 user.notice : Added device handler type: 8021ad
Fri Feb 23 18:36:14 2018 user.notice : Added device handler type: 8021q
Fri Feb 23 18:36:14 2018 daemon.notice procd: /etc/init.d/network: ‘radio1’ is disabled
Fri Feb 23 18:36:14 2018 daemon.notice procd: /etc/init.d/network: ‘radio1’ is disabled
Fri Feb 23 18:36:15 2018 authpriv.info dropbear[953]: Not backgrounding
Fri Feb 23 18:36:17 2018 daemon.notice procd: /etc/rc.d/S96led: setting up led LAN Data
Fri Feb 23 18:36:17 2018 daemon.notice procd: /etc/rc.d/S96led: setting up led LAN Link
Fri Feb 23 18:36:17 2018 daemon.notice procd: /etc/rc.d/S96led: setting up led WLAN 2.4 GHz Fri Feb 23 18:36:17 2018 daemon.notice procd: /etc/rc.d/S96led: setting up led WLAN 5 GHz Fri Feb 23 18:36:18 2018 kern.info kernel: [ 18.492806] br-lan: port 1(eth0) entered blocking state
Fri Feb 23 18:36:18 2018 kern.info kernel: [ 18.498113] br-lan: port 1(eth0) entered disabled state
Fri Feb 23 18:36:18 2018 kern.info kernel: [ 18.503728] device eth0 entered promiscuous mode
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Interface ‘lan’ is enabled
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Interface ‘lan’ is setting up now
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Interface ‘lan’ is now up
Fri Feb 23 18:36:18 2018 kern.info kernel: [ 18.522482] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Interface ‘loopback’ is enabled
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Interface ‘loopback’ is setting up now
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Interface ‘loopback’ is now up
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Network device ‘lo’ link is up
Fri Feb 23 18:36:18 2018 daemon.notice netifd: Interface ‘loopback’ has link connectivity
Fri Feb 23 18:36:18 2018 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Fri Feb 23 18:36:19 2018 daemon.info procd: - init complete -
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776056] ath: EEPROM regdomain: 0×8242 Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776063] ath: EEPROM indicates we should expect a country code
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776069] ath: doing EEPROM country→regdmn map search
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776075] ath: country maps to regdmn code: 0×37 Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776079] ath: Country alpha2 being used: NO
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776082] ath: Regpair used: 0×37 Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776087] ath: regdomain 0×8242 dynamically updated by user
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776168] ath: EEPROM regdomain: 0×8242 Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776171] ath: EEPROM indicates we should expect a country code
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776176] ath: doing EEPROM country→regdmn map search
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776181] ath: country maps to regdmn code: 0×37 Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776184] ath: Country alpha2 being used: NO
Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776186] ath: Regpair used: 0×37 Fri Feb 23 18:36:19 2018 kern.debug kernel: [ 19.776190] ath: regdomain 0×8242 dynamically updated by user
Fri Feb 23 18:36:21 2018 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: started, version 2.79rc1 cachesize 150
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: DNS service limited to local subnets
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain test
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain onion
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain localhost
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.441522] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain local
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain invalid
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain example.net
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain example.org
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using local addresses only for domain example.com
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: using 3 more local addresses
Fri Feb 23 18:36:23 2018 daemon.warn dnsmasq[1349]: no servers found in /tmp/resolv.conf.auto, will retry
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: read /etc/hosts - 4 addresses
Fri Feb 23 18:36:23 2018 daemon.info dnsmasq[1349]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.460824] br-lan: port 2(wlan0-1) entered blocking state
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.466441] br-lan: port 2(wlan0-1) entered disabled state
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.472324] device wlan0-1 entered promiscuous mode
Fri Feb 23 18:36:23 2018 daemon.notice hostapd: wlan0-1: interface state UNINITIALIZED→COUNTRY_UPDATE
Fri Feb 23 18:36:23 2018 daemon.notice hostapd: wlan0-1: interface state COUNTRY_UPDATE→HT_SCAN
Fri Feb 23 18:36:23 2018 daemon.notice hostapd: wlan0-1: interface state HT_SCAN→DFS
Fri Feb 23 18:36:23 2018 daemon.notice hostapd: wlan0-1: DFS-CAC-START freq=5560 chan=112 sec_chan=-1, width=1, seg0=106, seg1=0, cac_time=60s
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.813439] eth0: link up (1000Mbps/Full duplex)
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.818173] br-lan: port 1(eth0) entered blocking state
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.823505] br-lan: port 1(eth0) entered forwarding state
Fri Feb 23 18:36:23 2018 daemon.notice netifd: Network device ‘eth0’ link is up
Fri Feb 23 18:36:23 2018 kern.info kernel: [ 23.830482] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Fri Feb 23 18:36:23 2018 daemon.notice netifd: bridge ‘br-lan’ link is up
Fri Feb 23 18:36:23 2018 daemon.notice netifd: Interface ‘lan’ has link connectivity
Fri Feb 23 18:36:25 2018 daemon.info dnsmasq[1349]: read /etc/hosts - 4 addresses
Fri Feb 23 18:36:25 2018 daemon.info dnsmasq[1349]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Fri Feb 23 18:36:33 2018 daemon.err uhttpd[984]: luci: accepted login on /admin for root from 192.168.5.10
Fri Feb 23 18:37:24 2018 daemon.notice hostapd: wlan0-1: DFS-CAC-COMPLETED success=1 freq=5560 ht_enabled=0 chan_offset=0 chan_width=3 cf1=5530 cf2=0
Fri Feb 23 18:37:24 2018 daemon.err hostapd: Using interface wlan0-1 with hwaddr 86:16:f9:d6:21:3a and ssid “Test” Fri Feb 23 18:37:25 2018 daemon.notice hostapd: wlan0-1: interface state DFS→ENABLED
Fri Feb 23 18:37:25 2018 daemon.notice hostapd: wlan0-1: AP-ENABLED
Fri Feb 23 18:37:25 2018 kern.info kernel: [ 85.892280] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Fri Feb 23 18:37:25 2018 daemon.notice netifd: radio0 (1079): Successfully initialized wpa_supplicant
Fri Feb 23 18:37:26 2018 daemon.notice netifd: Interface ‘wwan’ is enabled
Fri Feb 23 18:37:26 2018 daemon.notice hostapd: wlan0-1: DFS-PRE-CAC-EXPIRED freq=5500 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5500 cf2=0
Fri Feb 23 18:37:26 2018 daemon.notice hostapd: wlan0-1: DFS-PRE-CAC-EXPIRED freq=5520 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5520 cf2=0
Fri Feb 23 18:37:26 2018 daemon.notice hostapd: wlan0-1: DFS-PRE-CAC-EXPIRED freq=5540 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5540 cf2=0
Fri Feb 23 18:37:26 2018 daemon.notice hostapd: wlan0-1: DFS-PRE-CAC-EXPIRED freq=5560 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5560 cf2=0
Fri Feb 23 18:37:27 2018 kern.info kernel: [ 88.008800] wlan0: authenticate with a0:40:a0:7c:cb:b9
Fri Feb 23 18:37:27 2018 kern.info kernel: [ 88.018939] wlan0: send auth to a0:40:a0:7c:cb:b9 (try 1/3)
Fri Feb 23 18:37:27 2018 kern.info kernel: [ 88.026654] wlan0: authenticated
Fri Feb 23 18:37:27 2018 kern.info kernel: [ 88.031919] wlan0: associate with a0:40:a0:7c:cb:b9 (try 1/3)
Fri Feb 23 18:37:27 2018 kern.info kernel: [ 88.039518] wlan0: RX AssocResp from a0:40:a0:7c:cb:b9 (capab=0×111 status=0 aid=5)
Fri Feb 23 18:37:27 2018 kern.info kernel: [ 88.048975] wlan0: associated
Fri Feb 23 18:37:27 2018 daemon.notice netifd: Network device ‘wlan0’ link is up
Fri Feb 23 18:37:27 2018 daemon.notice netifd: Interface ‘wwan’ has link connectivity
Fri Feb 23 18:37:27 2018 daemon.notice netifd: Interface ‘wwan’ is setting up now
Fri Feb 23 18:37:27 2018 kern.info kernel: [ 88.058784] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Fri Feb 23 18:37:27 2018 kern.debug kernel: [ 88.071867] wlan0: Limiting TX power to 23 (26 - 3) dBm as advertised by a0:40:a0:7c:cb:b9
Fri Feb 23 18:37:27 2018 daemon.notice netifd: wwan (1595): udhcpc: started, v1.27.2
Fri Feb 23 18:37:28 2018 daemon.notice netifd: wwan (1595): udhcpc: sending discover
Fri Feb 23 18:37:28 2018 daemon.notice netifd: wwan (1595): udhcpc: sending select for 192.168.1.144
Fri Feb 23 18:37:28 2018 daemon.notice netifd: wwan (1595): udhcpc: lease of 192.168.1.144 obtained, lease time 43200
Fri Feb 23 18:37:28 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Feb 23 18:37:28 2018 daemon.notice netifd: Interface ‘wwan’ is now up
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: reading /tmp/resolv.conf.auto
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain test
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain onion
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain localhost
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain local
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain invalid
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain example.net
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain example.org
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using local addresses only for domain example.com
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using nameserver 192.168.1.1#53
Fri Feb 23 18:37:28 2018 daemon.info dnsmasq[1349]: using 3 more local addresses
Fri Feb 23 18:37:28 2018 user.notice firewall: Reloading firewall due to ifup of wwan (wlan0)
Fri Feb 23 18:39:43 2018 daemon.warn odhcpd[888]: DHCPV6 CONFIRM IA_NA from 000466ecdf7df77d2477547e0529ea254566 on br-lan: not on-link
Fri Feb 23 18:39:44 2018 daemon.info dnsmasq[1349]: read /etc/hosts - 4 addresses
Fri Feb 23 18:39:44 2018 daemon.info dnsmasq[1349]: read /tmp/hosts/odhcpd - 0 addresses
Fri Feb 23 18:39:44 2018 daemon.info dnsmasq[1349]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Fri Feb 23 18:39:44 2018 daemon.warn odhcpd[888]: DHCPV6 SOLICIT IA_NA from 000466ecdf7df77d2477547e0529ea254566 on br-lan: ok fd6a:5bd0:8330::35e/128
Fri Feb 23 18:39:45 2018 daemon.warn odhcpd[888]: DHCPV6 REQUEST IA_NA from 000466ecdf7df77d2477547e0529ea254566 on br-lan: ok fd6a:5bd0:8330::35e/128
Fri Feb 23 18:39:45 2018 daemon.info dnsmasq[1349]: read /etc/hosts - 4 addresses
Fri Feb 23 18:39:45 2018 daemon.info dnsmasq[1349]: read /tmp/hosts/odhcpd - 0 addresses
Fri Feb 23 18:39:45 2018 daemon.info dnsmasq[1349]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Fri Feb 23 18:40:06 2018 kern.notice kernel: [ 113.239791] random: crng init done

And here is the kernel log:

[ 0.000000] Linux version 4.9.82 (buildbot@builds) (gcc version 5.5.0 (OpenWrt GCC 5.5.0 r6038-13e8d54) ) #0 Sun Feb 18 08:21:12 2018
[ 0.000000] MyLoader: sysp=212b15a7, boardp=2d6f6985, parts=6de765b6
[ 0.000000] bootconsole [early0] enabled
[ 0.000000] CPU0 revision is: 00019750 (MIPS 74Kc)
[ 0.000000] SoC: Qualcomm Atheros QCA9558 ver 1 rev 0
[ 0.000000] Determined physical RAM map:
[ 0.000000] memory: 08000000 @ 00000000 (usable)
[ 0.000000] Initrd not found or empty - disabling initrd
[ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0×0000000000000000-0x0000000007ffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0×0000000000000000-0x0000000007ffffff]
[ 0.000000] Initmem setup node 0 [mem 0×0000000000000000-0x0000000007ffffff]
[ 0.000000] On node 0 totalpages: 32768
[ 0.000000] free_area_init_node: node 0, pgdat 804ab9e4, node_mem_map 81000020
[ 0.000000] Normal zone: 256 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 32768 pages, LIFO batch:7
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512
[ 0.000000] Kernel command line: board=RE450 mtdparts=spi0.0:128k(u-boot)ro,6016k(firmware),64k(partition-table)ro,64k(product-info)ro,1856k(config)ro,64k(art)ro console=ttyS0,115200 rootfstype=squashfs noinitrd
[ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Writing ErrCtl register=00000000
[ 0.000000] Readback ErrCtl register=00000000
[ 0.000000] Memory: 124612K/131072K available (3394K kernel code, 174K rwdata, 836K rodata, 260K init, 219K bss, 6460K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:51
[ 0.000000] Clocks: CPU:720.000MHz, DDR:600.000MHz, AHB:200.000MHz, Ref:40.000MHz
[ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5309056796 ns
[ 0.000007] sched_clock: 32 bits at 360MHz, resolution 2ns, wraps every 5965232126ns
[ 0.008299] Calibrating delay loop... 358.80 BogoMIPS (lpj=1794048)
[ 0.071187] pid_max: default: 32768 minimum: 301
[ 0.076184] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.083229] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.092660] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.103156] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.110567] NET: Registered protocol family 16
[ 0.116508] MIPS: machine is TP-LINK RE450
[ 0.122731] registering PCI controller with io_map_base unset
[ 0.128976] ar724x-pci ar724x-pci.1: PCIe link is down
[ 0.134441] registering PCI controller with io_map_base unset
[ 0.391525] PCI host bridge to bus 0000:00
[ 0.395892] pci_bus 0000:00: root bus resource [mem 0×10000000-0x11ffffff]
[ 0.403234] pci_bus 0000:00: root bus resource [io 0×0000]
[ 0.409157] pci_bus 0000:00: root bus resource [??? 0×00000000 flags 0×0]
[ 0.416382] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[ 0.424828] pci 0000:00:00.0: [168c:003c] type 00 class 0×028000 [ 0.424865] pci 0000:00:00.0: reg 0×10: [mem 0×00000000-0x001fffff 64bit]
[ 0.424915] pci 0000:00:00.0: reg 0×30: [mem 0×00000000-0x0000ffff pref]
[ 0.424980] pci 0000:00:00.0: supports D1 D2
[ 0.425150] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[ 0.425171] pci 0000:00:00.0: BAR 0: assigned [mem 0×10000000-0x101fffff 64bit]
[ 0.432989] pci 0000:00:00.0: BAR 6: assigned [mem 0×10200000-0x1020ffff pref]
[ 0.440777] PCI host bridge to bus 0000:01
[ 0.445157] pci_bus 0000:01: root bus resource [mem 0×12000000-0x13ffffff]
[ 0.452466] pci_bus 0000:01: root bus resource [io 0×0001]
[ 0.458398] pci_bus 0000:01: root bus resource [??? 0×00000000 flags 0×0]
[ 0.465613] pci_bus 0000:01: No busn resource found for root bus, will use [bus 01-ff]
[ 0.474055] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 0.474068] pci 0000:00:00.0: using irq 40 for pin 1
[ 0.481748] clocksource: Switched to clocksource MIPS
[ 0.487998] NET: Registered protocol family 2
[ 0.493366] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.500789] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.507593] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.514433] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.520649] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.527538] NET: Registered protocol family 1
[ 0.532235] PCI: CLS 0 bytes, default 32
[ 0.534326] Crashlog allocated RAM at address 0x3f00000
[ 0.540612] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[ 0.553247] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.559445] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) © 2001-2006 Red Hat, Inc.
[ 0.576804] io scheduler noop registered
[ 0.580974] io scheduler deadline registered (default)
[ 0.586810] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[ 0.595712] console [ttyS0] disabled
[ 0.619586] serial8250.0: ttyS0 at MMIO 0×18020000 (irq = 11, base_baud = 2500000) is a 16550A
[ 0.628770] console [ttyS0] enabled
[ 0.636186] bootconsole [early0] disabled
[ 0.648931] m25p80 spi0.0: found s25fl064k, expected m25p80
[ 0.667134] m25p80 spi0.0: s25fl064k (8192 Kbytes)
[ 0.672033] 6 cmdlinepart partitions found on MTD device spi0.0
[ 0.678029] Creating 6 MTD partitions on “spi0.0”:
[ 0.682906] 0×000000000000-0×000000020000 : “u-boot” [ 0.689064] 0×000000020000-0×000000600000 : “firmware” [ 0.707081] 2 tplink-fw partitions found on MTD device firmware
[ 0.713127] 0×000000020000-0x000000185ceb : “kernel” [ 0.719204] 0x0000001a0000-0×000000600000 : “rootfs” [ 0.725496] mtd: device 3 (rootfs) set to be root filesystem
[ 0.731263] 1 squashfs-split partitions found on MTD device rootfs
[ 0.737575] 0×000000440000-0×000000600000 : “rootfs_data” [ 0.744349] 0×000000600000-0×000000610000 : “partition-table” [ 0.751431] 0×000000610000-0×000000620000 : “product-info” [ 0.758325] 0×000000620000-0x0000007f0000 : “config” [ 0.764662] 0x0000007f0000-0×000000800000 : “art” [ 0.773062] libphy: GPIO Bitbanged MDIO: probed
[ 0.778061] libphy: Fixed MDIO Bus: probed
[ 1.512179] ag71xx ag71xx.0: connected to PHY at gpio-0:04 [uid=004dd072, driver=Atheros 8035 ethernet]
[ 1.522288] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[ 1.530065] NET: Registered protocol family 10
[ 1.537560] NET: Registered protocol family 17
[ 1.542160] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[ 1.555384] 8021q: 802.1Q VLAN Support v1.8
[ 1.561016] hctosys: unable to open rtc device (rtc0)
[ 1.571029] VFS: Mounted root (squashfs filesystem) readonly on device 31:3.
[ 1.579154] Freeing unused kernel memory: 260K
[ 1.583687] This architecture does not have kernel memory protection.
[ 2.132376] init: Console is alive
[ 2.135998] init: - watchdog -
[ 2.494811] random: fast init done
[ 2.924159] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[ 2.965714] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[ 2.974640] init: - preinit -
[ 4.004457] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 7.093477] eth0: link up (1000Mbps/Full duplex)
[ 7.098187] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 7.293806] jffs2: notice: (403) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[ 7.311068] mount_root: switching to jffs2 overlay
[ 7.337522] urandom-seed: Seeding with /etc/urandom.seed
[ 7.435532] eth0: link down
[ 7.447458] procd: - early -
[ 7.450449] procd: - watchdog -
[ 8.057837] procd: - watchdog -
[ 8.061236] procd: - ubus -
[ 8.168116] procd: - init -
[ 8.539737] kmodloader: loading kernel modules from /etc/modules.d/*
[ 8.571268] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 8.585643] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[ 8.593800] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[ 8.602959] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 8.614861] nf_conntrack version 0.5.0 (2048 buckets, 8192 max)
[ 8.662930] xt_time: kernel timezone is -0000
[ 8.831336] PPP generic driver version 2.4.2
[ 8.837672] NET: Registered protocol family 24
[ 8.861154] PCI: Enabling device 0000:00:00.0 (0000 → 0002)
[ 8.867107] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0
[ 9.145193] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
[ 9.156072] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 9.344946] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
[ 9.355293] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-6.bin failed with error -2
[ 9.366187] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 9.494658] firmware ath10k!QCA988X!hw2.0!firmware-6.bin: firmware_loading_store: map pages failed
[ 9.508099] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
[ 9.517496] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1
[ 9.530543] ath10k_pci 0000:00:00.0: firmware ver 10.2.4-1.0-00033 api 5 features no-p2p,raw-mode,mfp,allows-mesh-bcast crc32 c41417d0
[ 9.624942] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
[ 9.635573] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 9.705908] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
[ 9.735953] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
[ 10.826902] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1
[ 10.963073] ath: EEPROM regdomain: 0×0 [ 10.963080] ath: EEPROM indicates default country code should be used
[ 10.963083] ath: doing EEPROM country→regdmn map search
[ 10.963093] ath: country maps to regdmn code: 0x3a
[ 10.963098] ath: Country alpha2 being used: US
[ 10.963101] ath: Regpair used: 0x3a
[ 11.080469] ath: EEPROM regdomain: 0×0 [ 11.080476] ath: EEPROM indicates default country code should be used
[ 11.080478] ath: doing EEPROM country→regdmn map search
[ 11.080490] ath: country maps to regdmn code: 0x3a
[ 11.080495] ath: Country alpha2 being used: US
[ 11.080497] ath: Regpair used: 0x3a
[ 11.096449] ieee80211 phy1: Selected rate control algorithm ‘minstrel_ht’ [ 11.099579] ieee80211 phy1: Atheros AR9550 Rev:0 mem=0xb8100000, irq=47
[ 11.109568] kmodloader: done loading kernel modules from /etc/modules.d/*
[ 18.492806] br-lan: port 1(eth0) entered blocking state
[ 18.498113] br-lan: port 1(eth0) entered disabled state
[ 18.503728] device eth0 entered promiscuous mode
[ 18.522482] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[ 19.776056] ath: EEPROM regdomain: 0×8242 [ 19.776063] ath: EEPROM indicates we should expect a country code
[ 19.776069] ath: doing EEPROM country→regdmn map search
[ 19.776075] ath: country maps to regdmn code: 0×37 [ 19.776079] ath: Country alpha2 being used: NO
[ 19.776082] ath: Regpair used: 0×37 [ 19.776087] ath: regdomain 0×8242 dynamically updated by user
[ 19.776168] ath: EEPROM regdomain: 0×8242 [ 19.776171] ath: EEPROM indicates we should expect a country code
[ 19.776176] ath: doing EEPROM country→regdmn map search
[ 19.776181] ath: country maps to regdmn code: 0×37 [ 19.776184] ath: Country alpha2 being used: NO
[ 19.776186] ath: Regpair used: 0×37 [ 19.776190] ath: regdomain 0×8242 dynamically updated by user
[ 23.441522] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[ 23.460824] br-lan: port 2(wlan0-1) entered blocking state
[ 23.466441] br-lan: port 2(wlan0-1) entered disabled state
[ 23.472324] device wlan0-1 entered promiscuous mode
[ 23.813439] eth0: link up (1000Mbps/Full duplex)
[ 23.818173] br-lan: port 1(eth0) entered blocking state
[ 23.823505] br-lan: port 1(eth0) entered forwarding state
[ 23.830482] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 85.892280] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 88.008800] wlan0: authenticate with a0:40:a0:7c:cb:b9
[ 88.018939] wlan0: send auth to a0:40:a0:7c:cb:b9 (try 1/3)
[ 88.026654] wlan0: authenticated
[ 88.031919] wlan0: associate with a0:40:a0:7c:cb:b9 (try 1/3)
[ 88.039518] wlan0: RX AssocResp from a0:40:a0:7c:cb:b9 (capab=0×111 status=0 aid=5)
[ 88.048975] wlan0: associated
[ 88.058784] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 88.071867] wlan0: Limiting TX power to 23 (26 - 3) dBm as advertised by a0:40:a0:7c:cb:b9
[ 113.239791] random: crng init done


huaracheguarache commented on 26.02.2018 09:43

Fri Feb 23 18:37:28 2018 daemon.err hostapd: Failed to set beacon parameters

Just pointing this out, but it seems to be related to hostapd.

Ales Vizdal commented on 19.03.2018 14:15

I can confirm the same issue for TP-Link RE355. Apart from hostapd (regardless wpad-mini, wpad or hostapd), it may be also related to the ath10k kernel driver.

The issue also occurs in WDS mode, in case RE355 is acting as a WDS client connected to a WDS AP as an uplink and providing an AP functionality to the hosts in the downlink direction.

Daniel Miranda commented on 13.07.2018 18:36

I am observing a similar issue while trying to set up a Linksys WRT1900ACS as a repeater for another WRT1900ACS. My 5GHz network is not actually even configured in a DFS channel (using channel 149), but the repeater's log gets spammed with DFS errors. Since it uses the mwlwifi driver, I seems like it's not an ath10k-specific issue.

Fri Jul 13 15:30:29 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Jul 13 15:30:35 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:35 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:35 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Jul 13 15:30:41 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:41 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:41 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Jul 13 15:30:47 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:47 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:47 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Jul 13 15:30:53 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:53 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:53 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Jul 13 15:30:59 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:59 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:30:59 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Jul 13 15:31:05 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:31:05 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Fri Jul 13 15:31:05 2018 daemon.err hostapd: Failed to set beacon parameters

howl commented on 31.07.2018 19:35

I have this problem too since upgrading from lede 17.01.5 to openwrt 18.06 using an Archer C7 v2.

The setup I have in 5 GHz is two AP nets and 1 STA net.

With lede 17.01.5 I had a little problem when the primary router jumps to another channel and the log says something that DFS cannot be checked (can't remember exactly), the Archer C7 v2 jump the STA net to the new channel but the two AP nets refuse to start up.

With openwrt 18.06 the AP nets never get up while having the STA net, and the error seems to be related also to DFS as I can see also the log reported in this bug:

Tue Jul 31 21:18:21 2018 daemon.notice hostapd: wlan5g-ap: interface state HT_SCAN->DFS
Tue Jul 31 21:18:21 2018 daemon.notice hostapd: wlan5g-ap: DFS-CAC-START freq=5500 chan=100 sec_chan=1, width=1, seg0=106, seg1=0, cac_time=60s
Tue Jul 31 21:19:25 2018 daemon.notice hostapd: wlan5g-ap: DFS-CAC-COMPLETED success=1 freq=5500 ht_enabled=0 chan_offset=0 chan_width=3 cf1=5530 cf2=0
Tue Jul 31 21:19:25 2018 daemon.notice hostapd: wlan5g-ap: interface state DFS->ENABLED
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5500 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5500 cf2=0
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5520 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5520 cf2=0
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5540 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5540 cf2=0
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5560 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5560 cf2=0

If I disable the STA net the AP nets works as expected and no DFS expires appears in log.

It seems to be a problem related only to hostap and a logic issue while managing DFS channels when the same network devices is set up as AP and STA at the same time. In lede 17.01.5 there is an issue when the primary router jumps to another channel (in 2 GHz the STA channel is set also to the AP nets even if another channel is configured) and with openwrt 18.06 there is another problem that makes hostapd unable to set up AP and STA net over the same device even without channel hopping, just when the DFS scan finish DFS expires.

Jérôme Benoit commented on 30.08.2018 09:13

The howl's bug analysis is correct.

I have exactly the same issue with an Archer C7 v4 running OpenWRT 18.06.X that trigger exactly the same symptoms and errors in log with the 5GHz wifi adapter using DFS channel on AP and STA mode.

The only difference between the AP only and AP and STA configuration using DFS channel in the hostapd configuration file is that the start_disabled directive is set.

Thks.

howl commented on 27.09.2018 00:00

With OpenWrt 18.06.1 the log message has changed from:

Tue Jul 31 21:18:21 2018 daemon.notice hostapd: wlan5g-ap: interface state HT_SCAN->DFS
Tue Jul 31 21:18:21 2018 daemon.notice hostapd: wlan5g-ap: DFS-CAC-START freq=5500 chan=100 sec_chan=1, width=1, seg0=106, seg1=0, cac_time=60s
Tue Jul 31 21:19:25 2018 daemon.notice hostapd: wlan5g-ap: DFS-CAC-COMPLETED success=1 freq=5500 ht_enabled=0 chan_offset=0 chan_width=3 cf1=5530 cf2=0
Tue Jul 31 21:19:25 2018 daemon.notice hostapd: wlan5g-ap: interface state DFS->ENABLED
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5500 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5500 cf2=0
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5520 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5520 cf2=0
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5540 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5540 cf2=0
Tue Jul 31 21:19:27 2018 daemon.notice hostapd: wlan5g-ap: DFS-PRE-CAC-EXPIRED freq=5560 ht_enabled=0 chan_offset=0 chan_width=0 cf1=5560 cf2=0

to:

Thu Sep 27 01:42:06 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Thu Sep 27 01:42:06 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Thu Sep 27 01:42:08 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Thu Sep 27 01:42:08 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Thu Sep 27 01:42:38 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Thu Sep 27 01:42:38 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Thu Sep 27 01:42:40 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Thu Sep 27 01:42:40 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
...

Now it doesn't even try to do DFS check when AP+STA config is over the same wireless device, it just fail with that error and it doesn't stop repeating.

There has been updates to hostapd so I suppose there has been changes in places that takes account about this kind of setups.

howl commented on 15.12.2018 00:39

Tested today and the messages are now a little different.

...
Sat Dec 15 01:37:41 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:41 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:41 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:37:47 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:47 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:47 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:37:53 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:53 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:53 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:37:59 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:59 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:37:59 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:38:05 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:05 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:05 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:38:11 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:11 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:11 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:38:17 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:17 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:17 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:38:23 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:23 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:23 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:38:29 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:29 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:29 2018 daemon.err hostapd: Failed to set beacon parameters
Sat Dec 15 01:38:35 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:35 2018 daemon.warn hostapd: Failed to check if DFS is required; ret=-1
Sat Dec 15 01:38:35 2018 daemon.err hostapd: Failed to set beacon parameters
...
Klaus Voigt commented on 26.12.2018 10:26

same here on archer c7
see also:
FS#1158

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing