Wpad 802.11w no longer working in trunk?

Hi,

I began running into a problem involving wpad when compiling from source.git. This issue only popped up for me about 7 days ago.

When wpad (not wpad-mini) is used, and /etc/config/wireless is set to use "option ieee80211w '1'" in either wifi-iface, neither radio will start and log will show errors.

If "option ieee80211w '1'" is removed the radios will start normally as expected.

I have been building LEDE from source.git for many months using wpad for 802.11w functionality without issue.

Hardware: Netgear WNDR3800

Steps to reproduce:

  1. fresh download from git clone https://git.lede-project.org/source.git
  2. cd source
  3. ./scripts/feeds update -a && ./scripts/feeds install -a
  4. make menuconfig (choose Target Profile > Netgear WNDR3800)
  5. make defconfig
  6. make menuconfig (unselect Network > wpad-mini, select Network > wpad)
  7. make
  8. flash the factory.img file using the tftp recovery method

a) add "option ieee80211w '1'" in either (or both) wifi-iface config and enable. Radios will not start.
b) remove "option ieee80211w '1'" and enable. Radios work ok.

The /etc/config/wireless being used is below.

config wifi-device 'radio0'
        option type 'mac80211'
        option channel '11'
        option hwmode '11g'
        option path 'pci0000:00/0000:00:11.0'
        option htmode 'HT20'
        option country 'US'

config wifi-iface 'default_radio0'
        option device 'radio0'
        option network 'lan'
        option mode 'ap'
        option ssid 'LEDE2'
	option ieee80211w '1'
        option encryption 'psk2+ccmp'
        option key 'MyWifiPassword2'

config wifi-device 'radio1'
        option type 'mac80211'
        option channel '36'
        option hwmode '11a'
        option path 'pci0000:00/0000:00:12.0'
        option htmode 'HT20'
        option country 'US'

config wifi-iface 'default_radio1'
        option device 'radio1'
        option network 'lan'
        option mode 'ap'
        option ssid 'LEDE5'
	option ieee80211w '1'
        option encryption 'psk2+ccmp'
        option key 'MyWifiPassword5'

Here is the log on boot when ieee80211w is enabled in the config:

(truncated to fit into this message - tmomas)

Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.000000] NR_IRQS:51
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.000000] Clocks: CPU:680.000MHz, DDR:340.000MHz, AHB:170.000MHz, Ref:40.000MHz
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5621354254 ns
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.000008] sched_clock: 32 bits at 340MHz, resolution 2ns, wraps every 6316128254ns
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.007784] Calibrating delay loop... 452.19 BogoMIPS (lpj=2260992)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.070505] pid_max: default: 32768 minimum: 301
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.075220] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.081827] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.090905] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.100777] futex hash table entries: 256 (order: -1, 3072 bytes)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.107490] NET: Registered protocol family 16
Sun Oct  8 18:29:07 2017 kern.info kernel: [    0.113120] MIPS: machine is NETGEAR WNDR3700/WNDR3800/WNDRMAC
Sun Oct  8 18:29:07 2017 kern.warn kernel: [    2.752318] registering PCI controller with io_map_base unset
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.765632] PCI host bridge to bus 0000:00
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.769781] pci_bus 0000:00: root bus resource [mem 0x10000000-0x16ffffff]
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.776645] pci_bus 0000:00: root bus resource [io  0x0000]
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.782214] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.788992] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.796952] pci 0000:00:11.0: [168c:ff1d] type 00 class 0x020000
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.796973] pci 0000:00:11.0: fixup device configuration
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.803491] pci 0000:00:11.0: reg 0x10: [mem 0x00000000-0x0000ffff]
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.803547] pci 0000:00:11.0: PME# supported from D0 D3hot
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.803726] pci 0000:00:12.0: [168c:ff1d] type 00 class 0x020000
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.803745] pci 0000:00:12.0: fixup device configuration
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.810285] pci 0000:00:12.0: reg 0x10: [mem 0x00000000-0x0000ffff]
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.810336] pci 0000:00:12.0: PME# supported from D0 D3hot
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.810525] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.810557] pci 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff]
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.817337] pci 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff]
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.824153] pci 0000:00:11.0: using irq 40 for pin 1
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.829105] pci 0000:00:12.0: using irq 41 for pin 1
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.834742] clocksource: Switched to clocksource MIPS
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.840812] NET: Registered protocol family 2
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.845894] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.852861] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.859254] TCP: Hash tables configured (established 1024 bind 1024)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.865695] UDP hash table entries: 256 (order: 0, 4096 bytes)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.871529] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.878014] NET: Registered protocol family 1
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    2.882442] PCI: CLS 0 bytes, default 32
Sun Oct  8 18:29:07 2017 kern.warn kernel: [    2.886032] Crashlog allocated RAM at address 0x3f00000
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.902264] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.908150] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.920079] io scheduler noop registered
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.924003] io scheduler deadline registered (default)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.929448] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.936174] console [ttyS0] disabled
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.959797] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 10625000) is a 16550A
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.968505] console [ttyS0] enabled
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.975464] bootconsole [early0] disabled
Sun Oct  8 18:29:07 2017 kern.warn kernel: [    2.986058] m25p80 spi0.0: found mx25l12805d, expected m25p80
Sun Oct  8 18:29:07 2017 kern.info kernel: [    2.991813] m25p80 spi0.0: mx25l12805d (16384 Kbytes)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    2.996929] 4 cmdlinepart partitions found on MTD device spi0.0
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.002834] Creating 4 MTD partitions on "spi0.0":
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.007638] 0x000000000000-0x000000050000 : "u-boot"
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.014414] 0x000000050000-0x000000070000 : "u-boot-env"
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.021106] 0x000000070000-0x000000ff0000 : "firmware"
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.039062] 2 netgear-fw partitions found on MTD device firmware
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.045108] 0x000000070000-0x0000001b6440 : "kernel"
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.051199] 0x0000001b6440-0x000000ff0000 : "rootfs"
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.057519] mtd: device 4 (rootfs) set to be root filesystem
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.063200] 1 squashfs-split partitions found on MTD device rootfs
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.069419] 0x0000003d0000-0x000000ff0000 : "rootfs_data"
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.076155] 0x000000ff0000-0x000001000000 : "art"
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    3.083126] Realtek RTL8366S ethernet switch driver version 0.2.2
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.089303] rtl8366s rtl8366s: using GPIO pins 5 (SDA) and 7 (SCK)
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.095566] rtl8366s rtl8366s: RTL8366 ver. 1 chip found
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.140771] libphy: rtl8366s: probed
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.466424] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.786545] ag71xx ag71xx.1: connected to PHY at rtl8366s:04 [uid=001cc960, driver=Generic PHY]
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.795858] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:RGMII
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.803730] NET: Registered protocol family 10
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.811143] NET: Registered protocol family 17
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.815712] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.828417] 8021q: 802.1Q VLAN Support v1.8
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.840067] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
Sun Oct  8 18:29:07 2017 kern.info kernel: [    3.848132] Freeing unused kernel memory: 320K
Sun Oct  8 18:29:07 2017 user.info kernel: [    4.751165] init: Console is alive
Sun Oct  8 18:29:07 2017 user.info kernel: [    4.754808] init: - watchdog -
Sun Oct  8 18:29:07 2017 user.info kernel: [    5.622098] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.693720] usbcore: registered new interface driver usbfs
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.699318] usbcore: registered new interface driver hub
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.704702] usbcore: registered new device driver usb
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.714043] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.722010] ehci-platform: EHCI generic platform driver
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.727363] ehci-platform ehci-platform: EHCI Host Controller
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.733122] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.741142] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.764774] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.771802] hub 1-0:1.0: USB hub found
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.775882] hub 1-0:1.0: 2 ports detected
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.782348] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.789727] ohci-platform: OHCI generic platform driver
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.795081] ohci-platform ohci-platform: Generic Platform OHCI controller
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.801873] ohci-platform ohci-platform: new USB bus registered, assigned bus number 2
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.809860] ohci-platform ohci-platform: irq 14, io mem 0x1c000000
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.879560] hub 2-0:1.0: USB hub found
Sun Oct  8 18:29:07 2017 kern.info kernel: [    5.883580] hub 2-0:1.0: 2 ports detected
Sun Oct  8 18:29:07 2017 user.info kernel: [    5.888354] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Sun Oct  8 18:29:07 2017 user.info kernel: [    5.905998] init: - preinit -
Sun Oct  8 18:29:07 2017 kern.debug kernel: [    6.463570] ar71xx: pll_reg 0xb8050010: 0x11110000
Sun Oct  8 18:29:07 2017 kern.info kernel: [    6.463598] eth0: link up (1000Mbps/Full duplex)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    6.485623] random: procd: uninitialized urandom read (4 bytes read, 10 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [    9.644892] jffs2: notice: (389) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
Sun Oct  8 18:29:07 2017 user.info kernel: [    9.661749] mount_root: switching to jffs2 overlay
Sun Oct  8 18:29:07 2017 user.warn kernel: [    9.685571] urandom-seed: Seeding with /etc/urandom.seed
Sun Oct  8 18:29:07 2017 kern.info kernel: [    9.858893] eth0: link down
Sun Oct  8 18:29:07 2017 user.info kernel: [    9.870936] procd: - early -
Sun Oct  8 18:29:07 2017 user.info kernel: [    9.873904] procd: - watchdog -
Sun Oct  8 18:29:07 2017 user.info kernel: [   10.473712] procd: - watchdog -
Sun Oct  8 18:29:07 2017 user.info kernel: [   10.477167] procd: - ubus -
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.511787] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.584258] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.593664] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.602759] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.611952] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.620999] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.630454] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   10.639645] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Sun Oct  8 18:29:07 2017 user.info kernel: [   10.648974] procd: - init -
Sun Oct  8 18:29:07 2017 user.info kernel: [   10.917974] kmodloader: loading kernel modules from /etc/modules.d/*
Sun Oct  8 18:29:07 2017 kern.info kernel: [   10.929852] ip6_tables: (C) 2000-2006 Netfilter Core Team
Sun Oct  8 18:29:07 2017 kern.info kernel: [   10.948867] Loading modules backported from Linux version v4.14-rc2-0-ge19b205be43d
Sun Oct  8 18:29:07 2017 kern.info kernel: [   10.956580] Backport generated by backports.git v4.14-rc2-1-0-g8bfe0960
Sun Oct  8 18:29:07 2017 kern.info kernel: [   10.965807] ip_tables: (C) 2000-2006 Netfilter Core Team
Sun Oct  8 18:29:07 2017 kern.info kernel: [   10.978375] nf_conntrack version 0.5.0 (1959 buckets, 7836 max)
Sun Oct  8 18:29:07 2017 kern.info kernel: [   11.023648] xt_time: kernel timezone is -0000
Sun Oct  8 18:29:07 2017 kern.info kernel: [   11.072808] PPP generic driver version 2.4.2
Sun Oct  8 18:29:07 2017 kern.info kernel: [   11.079312] NET: Registered protocol family 24
Sun Oct  8 18:29:07 2017 kern.warn kernel: [   11.111903] PCI: Enabling device 0000:00:11.0 (0000 -> 0002)
Sun Oct  8 18:29:07 2017 kern.info kernel: [   11.122458] ath: phy0: Ignoring endianness difference in EEPROM magic bytes.
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.131060] ath: EEPROM regdomain: 0x0
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.131069] ath: EEPROM indicates default country code should be used
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.131077] ath: doing EEPROM country->regdmn map search
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.131092] ath: country maps to regdmn code: 0x3a
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.131102] ath: Country alpha2 being used: US
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.131110] ath: Regpair used: 0x3a
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.144404] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Sun Oct  8 18:29:07 2017 kern.info kernel: [   11.148391] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xb0000000, irq=40
Sun Oct  8 18:29:07 2017 kern.warn kernel: [   11.245921] PCI: Enabling device 0000:00:12.0 (0000 -> 0002)
Sun Oct  8 18:29:07 2017 kern.info kernel: [   11.256488] ath: phy1: Ignoring endianness difference in EEPROM magic bytes.
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.265073] ath: EEPROM regdomain: 0x0
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.265082] ath: EEPROM indicates default country code should be used
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.265090] ath: doing EEPROM country->regdmn map search
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.265106] ath: country maps to regdmn code: 0x3a
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.265116] ath: Country alpha2 being used: US
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.265123] ath: Regpair used: 0x3a
Sun Oct  8 18:29:07 2017 kern.debug kernel: [   11.280660] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
Sun Oct  8 18:29:07 2017 kern.info kernel: [   11.284639] ieee80211 phy1: Atheros AR9280 Rev:2 mem=0xb0010000, irq=41
Sun Oct  8 18:29:07 2017 user.info kernel: [   11.293680] kmodloader: done loading kernel modules from /etc/modules.d/*
Sun Oct  8 18:29:07 2017 kern.notice kernel: [   12.263357] random: jshn: uninitialized urandom read (4 bytes read, 20 bits of entropy available)
Sun Oct  8 18:29:08 2017 user.notice : Added device handler type: tunnel
Sun Oct  8 18:29:08 2017 user.notice : Added device handler type: Network device
Sun Oct  8 18:29:08 2017 user.notice : Added device handler type: bridge
Sun Oct  8 18:29:08 2017 user.notice : Added device handler type: veth
Sun Oct  8 18:29:08 2017 user.notice : Added device handler type: macvlan
Sun Oct  8 18:29:08 2017 user.notice : Added device handler type: 8021ad
Sun Oct  8 18:29:08 2017 user.notice : Added device handler type: 8021q
Sun Oct  8 18:29:09 2017 authpriv.info dropbear[785]: Not backgrounding
Sun Oct  8 18:29:10 2017 daemon.notice procd: /etc/rc.d/S96led: setting up led WAN LED (green)
Sun Oct  8 18:29:10 2017 daemon.notice procd: /etc/rc.d/S96led: setting up led USB
Sun Oct  8 18:29:11 2017 kern.debug kernel: [   17.210276] ar71xx: pll_reg 0xb8050010: 0x11110000
Sun Oct  8 18:29:11 2017 kern.info kernel: [   17.210321] eth0: link up (1000Mbps/Full duplex)
Sun Oct  8 18:29:11 2017 kern.info kernel: [   17.229305] device eth0.1 entered promiscuous mode
Sun Oct  8 18:29:11 2017 kern.info kernel: [   17.234098] device eth0 entered promiscuous mode
Sun Oct  8 18:29:11 2017 kern.info kernel: [   17.248895] br-lan: port 1(eth0.1) entered forwarding state
Sun Oct  8 18:29:11 2017 kern.info kernel: [   17.254503] br-lan: port 1(eth0.1) entered forwarding state
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'lan' is enabled
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'lan' is setting up now
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'lan' is now up
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'loopback' is enabled
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'loopback' is setting up now
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'loopback' is now up
Sun Oct  8 18:29:11 2017 kern.info kernel: [   17.309791] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'wan' is enabled
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'wan6' is enabled
Sun Oct  8 18:29:11 2017 daemon.notice netifd: bridge 'br-lan' link is up
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'lan' has link connectivity
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Network device 'eth0' link is up
Sun Oct  8 18:29:11 2017 daemon.notice netifd: VLAN 'eth0.1' link is up
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Network device 'lo' link is up
Sun Oct  8 18:29:11 2017 daemon.notice netifd: Interface 'loopback' has link connectivity
Sun Oct  8 18:29:12 2017 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Sun Oct  8 18:29:13 2017 daemon.info procd: - init complete -
Sun Oct  8 18:29:13 2017 kern.info kernel: [   19.244766] br-lan: port 1(eth0.1) entered forwarding state
Sun Oct  8 18:29:14 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Sun Oct  8 18:29:14 2017 kern.info kernel: [   19.920904] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sun Oct  8 18:29:14 2017 kern.info kernel: [   19.948330] device wlan0 entered promiscuous mode
Sun Oct  8 18:29:14 2017 kern.info kernel: [   19.953095] br-lan: port 2(wlan0) entered forwarding state
Sun Oct  8 18:29:14 2017 kern.info kernel: [   19.958654] br-lan: port 2(wlan0) entered forwarding state
Sun Oct  8 18:29:14 2017 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
Sun Oct  8 18:29:14 2017 daemon.err hostapd: Using interface wlan0 with hwaddr 4c:60:de:d8:e9:59 and ssid "LEDE2"
Sun Oct  8 18:29:14 2017 kern.info kernel: [   19.980307] br-lan: port 2(wlan0) entered disabled state
Sun Oct  8 18:29:14 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Sun Oct  8 18:29:14 2017 kern.info kernel: [   20.132058] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Sun Oct  8 18:29:14 2017 kern.info kernel: [   20.188188] device wlan1 entered promiscuous mode
Sun Oct  8 18:29:14 2017 kern.info kernel: [   20.192967] br-lan: port 3(wlan1) entered forwarding state
Sun Oct  8 18:29:14 2017 kern.info kernel: [   20.198522] br-lan: port 3(wlan1) entered forwarding state
Sun Oct  8 18:29:14 2017 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->COUNTRY_UPDATE
Sun Oct  8 18:29:14 2017 daemon.err hostapd: Using interface wlan1 with hwaddr 4c:60:de:d8:e9:5b and ssid "LEDE5"
Sun Oct  8 18:29:15 2017 kern.info kernel: [   20.974806] br-lan: port 3(wlan1) entered disabled state
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.105492] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.111998] br-lan: port 2(wlan0) entered forwarding state
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.117561] br-lan: port 2(wlan0) entered forwarding state
Sun Oct  8 18:29:15 2017 daemon.err hostapd: Interface initialization failed
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->DISABLED
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Sun Oct  8 18:29:15 2017 daemon.err hostapd: wlan0: Unable to setup interface.
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan0: interface state DISABLED->DISABLED
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING
Sun Oct  8 18:29:15 2017 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan0 wasn't started
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.231413] device wlan0 left promiscuous mode
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.236003] br-lan: port 2(wlan0) entered disabled state
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.291964] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.298499] br-lan: port 3(wlan1) entered forwarding state
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.304039] br-lan: port 3(wlan1) entered forwarding state
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: ELOOP: remaining socket: sock=20 eloop_data=0x77674b50 user_data=0 handler=0x4217c9
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio0 (853): cat: can't open '/var/run/wifi-phy0.pid': No such file or directory
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio0 (853): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path ()
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio0 (853): Command failed: Invalid argument
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio0 (853): Device setup failed: HOSTAPD_START_FAILED
Sun Oct  8 18:29:15 2017 daemon.err hostapd: Interface initialization failed
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan1: interface state COUNTRY_UPDATE->DISABLED
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan1: AP-DISABLED
Sun Oct  8 18:29:15 2017 daemon.err hostapd: wlan1: Unable to setup interface.
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan1: interface state DISABLED->DISABLED
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan1: AP-DISABLED
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING
Sun Oct  8 18:29:15 2017 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan1 wasn't started
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.417328] device wlan1 left promiscuous mode
Sun Oct  8 18:29:15 2017 kern.info kernel: [   21.421858] br-lan: port 3(wlan1) entered disabled state
Sun Oct  8 18:29:15 2017 daemon.notice hostapd: ELOOP: remaining socket: sock=21 eloop_data=0x774a89a0 user_data=0 handler=0x4217c9
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio1 (854): cat: can't open '/var/run/wifi-phy1.pid': No such file or directory
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio1 (854): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path ()
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio1 (854): Command failed: Invalid argument
Sun Oct  8 18:29:15 2017 daemon.notice netifd: radio1 (854): Device setup failed: HOSTAPD_START_FAILED
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: started, version 2.78 cachesize 150
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: DNS service limited to local subnets
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: 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
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq-dhcp[1210]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 12h
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain test
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain onion
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain localhost
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain local
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain invalid
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain example.net
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain example.org
Sun Oct  8 18:29:18 2017 daemon.info dnsmasq[1210]: using local addresses only for domain example.com

I tested with my own 3700v2 and 802.11w option seems to work normally with LEDE master r5028-34b153d7ad

May I ask which OS are you compiling with hnyman? I am using Void Linux with kernel 4.9.

I compiled with Ubuntu 16.04 x64 and yesterday's build was otherwise normal, but I used experimental kernel 4.9 support from the developer mailing list (instead of the old default kernel 4.4 for a71xx). But i think that the LEDE kernel should have no impact on ieee802.11w support in hostapd/wpad.

Hostapd package was updated two days ago, so please check that you use up-to-date code.
https://git.lede-project.org/?p=source.git;a=commit;h=2f78034c3ef71bdeb1f2955aa8844b1d1ec70d3a

Thanks, I will try Ubuntu 16.04 x64 distro to compile with. My previous compile was from source.git at 11pm UTC Oct 8, less than 12 hours ago.

I installed Ubuntu 16.04 x64 on my Lenovo T400 laptop (bare metal, no VM) and am getting the same result on a fresh compile with ieee80211w enabled in the config. I will install Ubuntu on a spare Lenovo TS140 small Xeon server and compile on that platform and report back.


Mon Oct 9 13:20:21 2017 daemon.notice hostapd: wlan1: interface state COUNTRY_UPDATE->DISABLED
Mon Oct 9 13:20:21 2017 daemon.notice hostapd: wlan1: AP-DISABLED
Mon Oct 9 13:20:21 2017 daemon.err hostapd: wlan1: Unable to setup interface.
Mon Oct 9 13:20:21 2017 daemon.notice hostapd: wlan1: interface state DISABLED->DISABLED
Mon Oct 9 13:20:21 2017 daemon.notice hostapd: wlan1: AP-DISABLED
Mon Oct 9 13:20:21 2017 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING
Mon Oct 9 13:20:21 2017 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan1 wasn't started
Mon Oct 9 13:20:21 2017 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Mon Oct 9 13:20:21 2017 kern.info kernel: [ 26.323293] device wlan1 left promiscuous mode
Mon Oct 9 13:20:21 2017 kern.info kernel: [ 26.327905] br-lan: port 3(wlan1) entered disabled state
Mon Oct 9 13:20:22 2017 daemon.notice hostapd: ELOOP: remaining socket: sock=21 eloop_data=0x7732a9a0 user_data=0 handler=0x4217c9
Mon Oct 9 13:20:22 2017 daemon.notice netifd: radio1 (868): cat: can't open '/var/run/wifi-phy1.pid': No such file or directory
Mon Oct 9 13:20:22 2017 daemon.notice netifd: radio1 (868): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process path ()
Mon Oct 9 13:20:22 2017 daemon.notice netifd: radio1 (868): Command failed: Invalid argument
Mon Oct 9 13:20:22 2017 daemon.notice netifd: radio1 (868): Device setup failed: HOSTAPD_START_FAILED

sorry, I usedf 17.04 for compilation, not 16.04. but I do not think that it should have any impact.

That line in your log looks strange:

Almost looks like something goes wrong in the initialisation and country setting.

I have both 3800 and 3700v2 and both start wifi ok with 802.11w

Sorry for that, I was using an alternate wireless config that specified country code and country_ie for ieee80211d. That is probably why that is showing. The radios worked fine with ieee80211d enabled. Just not ieee80211w.

The build using Ubuntu 16.04 x64 on the Xeon server should be done in the next hour or so. I will run the wireless config from earlier in this thread for consistency when I test it.

Still the same result compiling on a fresh fully updated Ubuntu 16.04 x64 and fresh pull from source.git on a TS140 server.

I used the wireless config from above for consistency. I agree that 17.04 shouldn't make any difference in compiling, but I will reinstall with it and compile for completeness. Have you tried compiling from source.git using default 4.4 kernel only substituting wpad for wpad-mini? I do appreciate your analysis of this issue!

I have three WNDR3800 units and all are having the same behavior, so I don't think its a bum hardware issue.

This particular error only started a little more than a week ago for me when enabling ieee80211w from trunk builds:

Mon Oct 9 15:37:41 2017 daemon.notice netifd: radio0 (854): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process path ()
Mon Oct 9 15:37:41 2017 daemon.notice netifd: radio0 (854): Command failed: Invalid argument

Full log is below, minus some trailing stuff like dnsmasq messages that are not relevant.


root@LEDE:~# logread
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    0.000000] Linux version 4.4.91 (matt@matt-70A4003AUX) (gcc version 5.4.0 (LEDE GCC 5.4.0 r5039-b73986a) ) #0 Mon Oct 9 15:34:41 2017
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000] MyLoader: sysp=aaaa5554, boardp=aaaa5554, parts=aaaa5554
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] bootconsole [early0] enabled
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] SoC: Atheros AR7161 rev 2
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Determined physical RAM map:
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000]  memory: 08000000 @ 00000000 (usable)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Initrd not found or empty - disabling initrd
Mon Oct  9 15:37:33 2017 kern.warn kernel: [    0.000000] No valid device tree found, continuing without
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Zone ranges:
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Movable zone start for each node
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Early memory node ranges
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000] On node 0 totalpages: 32768
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000] free_area_init_node: node 0, pgdat 804123d0, node_mem_map 81000000
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000]   Normal zone: 256 pages used for memmap
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000]   Normal zone: 0 pages reserved
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000]   Normal zone: 32768 pages, LIFO batch:7
Mon Oct  9 15:37:33 2017 kern.warn kernel: [    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
Mon Oct  9 15:37:33 2017 kern.warn kernel: [    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    0.000000] Kernel command line:  board=WNDR3700 mtdparts=spi0.0:320k(u-boot)ro,128k(u-boot-env)ro,15872k(firmware),64k(art)ro console=ttyS0,115200 rootfstype=squashfs noinitrd
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Writing ErrCtl register=00000000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Readback ErrCtl register=00000000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Memory: 125076K/131072K available (2996K kernel code, 144K rwdata, 756K rodata, 320K init, 200K bss, 5996K reserved, 0K cma-reserved)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] NR_IRQS:51
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] Clocks: CPU:680.000MHz, DDR:340.000MHz, AHB:170.000MHz, Ref:40.000MHz
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5621354254 ns
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.000008] sched_clock: 32 bits at 340MHz, resolution 2ns, wraps every 6316128254ns
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.007783] Calibrating delay loop... 452.19 BogoMIPS (lpj=2260992)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.070505] pid_max: default: 32768 minimum: 301
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.075220] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.081827] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.090904] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.100769] futex hash table entries: 256 (order: -1, 3072 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.107481] NET: Registered protocol family 16
Mon Oct  9 15:37:33 2017 kern.info kernel: [    0.113110] MIPS: machine is NETGEAR WNDR3700/WNDR3800/WNDRMAC
Mon Oct  9 15:37:33 2017 kern.warn kernel: [    2.752308] registering PCI controller with io_map_base unset
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.765622] PCI host bridge to bus 0000:00
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.769772] pci_bus 0000:00: root bus resource [mem 0x10000000-0x16ffffff]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.776636] pci_bus 0000:00: root bus resource [io  0x0000]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.782205] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.788983] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.796944] pci 0000:00:11.0: [168c:ff1d] type 00 class 0x020000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.796965] pci 0000:00:11.0: fixup device configuration
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.803483] pci 0000:00:11.0: reg 0x10: [mem 0x00000000-0x0000ffff]
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.803538] pci 0000:00:11.0: PME# supported from D0 D3hot
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.803717] pci 0000:00:12.0: [168c:ff1d] type 00 class 0x020000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.803737] pci 0000:00:12.0: fixup device configuration
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.810276] pci 0000:00:12.0: reg 0x10: [mem 0x00000000-0x0000ffff]
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.810327] pci 0000:00:12.0: PME# supported from D0 D3hot
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.810516] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.810549] pci 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.817329] pci 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.824144] pci 0000:00:11.0: using irq 40 for pin 1
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.829096] pci 0000:00:12.0: using irq 41 for pin 1
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.834732] clocksource: Switched to clocksource MIPS
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.840803] NET: Registered protocol family 2
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.845887] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.852860] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.859253] TCP: Hash tables configured (established 1024 bind 1024)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.865695] UDP hash table entries: 256 (order: 0, 4096 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.871529] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.878014] NET: Registered protocol family 1
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    2.882441] PCI: CLS 0 bytes, default 32
Mon Oct  9 15:37:33 2017 kern.warn kernel: [    2.886033] Crashlog allocated RAM at address 0x3f00000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.902278] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.908168] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.920099] io scheduler noop registered
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.924029] io scheduler deadline registered (default)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.929473] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.936201] console [ttyS0] disabled
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.959823] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 10625000) is a 16550A
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.968529] console [ttyS0] enabled
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.975488] bootconsole [early0] disabled
Mon Oct  9 15:37:33 2017 kern.warn kernel: [    2.986088] m25p80 spi0.0: found mx25l12805d, expected m25p80
Mon Oct  9 15:37:33 2017 kern.info kernel: [    2.991845] m25p80 spi0.0: mx25l12805d (16384 Kbytes)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    2.996961] 4 cmdlinepart partitions found on MTD device spi0.0
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.002866] Creating 4 MTD partitions on "spi0.0":
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.007671] 0x000000000000-0x000000050000 : "u-boot"
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.014451] 0x000000050000-0x000000070000 : "u-boot-env"
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.021149] 0x000000070000-0x000000ff0000 : "firmware"
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.039101] 2 netgear-fw partitions found on MTD device firmware
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.045149] 0x000000070000-0x0000001b6440 : "kernel"
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.051242] 0x0000001b6440-0x000000ff0000 : "rootfs"
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.057569] mtd: device 4 (rootfs) set to be root filesystem
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.063250] 1 squashfs-split partitions found on MTD device rootfs
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.069469] 0x0000003d0000-0x000000ff0000 : "rootfs_data"
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.076206] 0x000000ff0000-0x000001000000 : "art"
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    3.083177] Realtek RTL8366S ethernet switch driver version 0.2.2
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.089352] rtl8366s rtl8366s: using GPIO pins 5 (SDA) and 7 (SCK)
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.095616] rtl8366s rtl8366s: RTL8366 ver. 1 chip found
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.140765] libphy: rtl8366s: probed
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.466415] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.786536] ag71xx ag71xx.1: connected to PHY at rtl8366s:04 [uid=001cc960, driver=Generic PHY]
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.795847] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:RGMII
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.803720] NET: Registered protocol family 10
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.811134] NET: Registered protocol family 17
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.815701] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.828405] 8021q: 802.1Q VLAN Support v1.8
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.840152] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
Mon Oct  9 15:37:33 2017 kern.info kernel: [    3.848217] Freeing unused kernel memory: 320K
Mon Oct  9 15:37:33 2017 user.info kernel: [    4.751885] init: Console is alive
Mon Oct  9 15:37:33 2017 user.info kernel: [    4.755538] init: - watchdog -
Mon Oct  9 15:37:33 2017 user.info kernel: [    5.622994] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.694499] usbcore: registered new interface driver usbfs
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.700119] usbcore: registered new interface driver hub
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.705525] usbcore: registered new device driver usb
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.714922] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.722849] ehci-platform: EHCI generic platform driver
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.728201] ehci-platform ehci-platform: EHCI Host Controller
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.733969] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.741988] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.764764] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.771792] hub 1-0:1.0: USB hub found
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.775873] hub 1-0:1.0: 2 ports detected
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.782337] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.789718] ohci-platform: OHCI generic platform driver
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.795071] ohci-platform ohci-platform: Generic Platform OHCI controller
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.801863] ohci-platform ohci-platform: new USB bus registered, assigned bus number 2
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.809850] ohci-platform ohci-platform: irq 14, io mem 0x1c000000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.879551] hub 2-0:1.0: USB hub found
Mon Oct  9 15:37:33 2017 kern.info kernel: [    5.883569] hub 2-0:1.0: 2 ports detected
Mon Oct  9 15:37:33 2017 user.info kernel: [    5.888343] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Mon Oct  9 15:37:33 2017 user.info kernel: [    5.905990] init: - preinit -
Mon Oct  9 15:37:33 2017 kern.debug kernel: [    6.463404] ar71xx: pll_reg 0xb8050010: 0x11110000
Mon Oct  9 15:37:33 2017 kern.info kernel: [    6.463432] eth0: link up (1000Mbps/Full duplex)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    6.485469] random: procd: uninitialized urandom read (4 bytes read, 10 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [    9.645135] jffs2: notice: (389) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
Mon Oct  9 15:37:33 2017 user.info kernel: [    9.661983] mount_root: switching to jffs2 overlay
Mon Oct  9 15:37:33 2017 user.warn kernel: [    9.686080] urandom-seed: Seeding with /etc/urandom.seed
Mon Oct  9 15:37:33 2017 kern.info kernel: [    9.859596] eth0: link down
Mon Oct  9 15:37:33 2017 user.info kernel: [    9.871734] procd: - early -
Mon Oct  9 15:37:33 2017 user.info kernel: [    9.874700] procd: - watchdog -
Mon Oct  9 15:37:33 2017 user.info kernel: [   10.474157] procd: - watchdog -
Mon Oct  9 15:37:33 2017 user.info kernel: [   10.477604] procd: - ubus -
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.512294] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.584701] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.594099] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.603193] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.612373] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.621417] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.630555] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   10.640049] random: ubusd: uninitialized urandom read (4 bytes read, 17 bits of entropy available)
Mon Oct  9 15:37:33 2017 user.info kernel: [   10.649368] procd: - init -
Mon Oct  9 15:37:33 2017 user.info kernel: [   10.917857] kmodloader: loading kernel modules from /etc/modules.d/*
Mon Oct  9 15:37:33 2017 kern.info kernel: [   10.929722] ip6_tables: (C) 2000-2006 Netfilter Core Team
Mon Oct  9 15:37:33 2017 kern.info kernel: [   10.948753] Loading modules backported from Linux version v4.14-rc2-0-ge19b205be43d
Mon Oct  9 15:37:33 2017 kern.info kernel: [   10.956465] Backport generated by backports.git v4.14-rc2-1-0-g8bfe0960
Mon Oct  9 15:37:33 2017 kern.info kernel: [   10.965719] ip_tables: (C) 2000-2006 Netfilter Core Team
Mon Oct  9 15:37:33 2017 kern.info kernel: [   10.978294] nf_conntrack version 0.5.0 (1959 buckets, 7836 max)
Mon Oct  9 15:37:33 2017 kern.info kernel: [   11.023919] xt_time: kernel timezone is -0000
Mon Oct  9 15:37:33 2017 kern.info kernel: [   11.072907] PPP generic driver version 2.4.2
Mon Oct  9 15:37:33 2017 kern.info kernel: [   11.079401] NET: Registered protocol family 24
Mon Oct  9 15:37:33 2017 kern.warn kernel: [   11.112012] PCI: Enabling device 0000:00:11.0 (0000 -> 0002)
Mon Oct  9 15:37:33 2017 kern.info kernel: [   11.122584] ath: phy0: Ignoring endianness difference in EEPROM magic bytes.
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.131181] ath: EEPROM regdomain: 0x0
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.131190] ath: EEPROM indicates default country code should be used
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.131198] ath: doing EEPROM country->regdmn map search
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.131214] ath: country maps to regdmn code: 0x3a
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.131223] ath: Country alpha2 being used: US
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.131231] ath: Regpair used: 0x3a
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.144548] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Mon Oct  9 15:37:33 2017 kern.info kernel: [   11.148537] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xb0000000, irq=40
Mon Oct  9 15:37:33 2017 kern.warn kernel: [   11.245927] PCI: Enabling device 0000:00:12.0 (0000 -> 0002)
Mon Oct  9 15:37:33 2017 kern.info kernel: [   11.256497] ath: phy1: Ignoring endianness difference in EEPROM magic bytes.
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.265085] ath: EEPROM regdomain: 0x0
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.265094] ath: EEPROM indicates default country code should be used
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.265102] ath: doing EEPROM country->regdmn map search
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.265118] ath: country maps to regdmn code: 0x3a
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.265128] ath: Country alpha2 being used: US
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.265136] ath: Regpair used: 0x3a
Mon Oct  9 15:37:33 2017 kern.debug kernel: [   11.280668] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
Mon Oct  9 15:37:33 2017 kern.info kernel: [   11.284660] ieee80211 phy1: Atheros AR9280 Rev:2 mem=0xb0010000, irq=41
Mon Oct  9 15:37:33 2017 user.info kernel: [   11.293710] kmodloader: done loading kernel modules from /etc/modules.d/*
Mon Oct  9 15:37:33 2017 kern.notice kernel: [   12.259069] random: jshn: uninitialized urandom read (4 bytes read, 20 bits of entropy available)
Mon Oct  9 15:37:34 2017 user.notice : Added device handler type: tunnel
Mon Oct  9 15:37:34 2017 user.notice : Added device handler type: Network device
Mon Oct  9 15:37:34 2017 user.notice : Added device handler type: bridge
Mon Oct  9 15:37:34 2017 user.notice : Added device handler type: veth
Mon Oct  9 15:37:34 2017 user.notice : Added device handler type: macvlan
Mon Oct  9 15:37:34 2017 user.notice : Added device handler type: 8021ad
Mon Oct  9 15:37:34 2017 user.notice : Added device handler type: 8021q
Mon Oct  9 15:37:36 2017 authpriv.info dropbear[785]: Not backgrounding
Mon Oct  9 15:37:36 2017 daemon.notice procd: /etc/rc.d/S96led: setting up led WAN LED (green)
Mon Oct  9 15:37:36 2017 daemon.notice procd: /etc/rc.d/S96led: setting up led USB
Mon Oct  9 15:37:37 2017 kern.debug kernel: [   17.270978] ar71xx: pll_reg 0xb8050010: 0x11110000
Mon Oct  9 15:37:37 2017 kern.info kernel: [   17.271023] eth0: link up (1000Mbps/Full duplex)
Mon Oct  9 15:37:37 2017 kern.info kernel: [   17.289327] device eth0.1 entered promiscuous mode
Mon Oct  9 15:37:37 2017 kern.info kernel: [   17.294119] device eth0 entered promiscuous mode
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'lan' is enabled
Mon Oct  9 15:37:37 2017 kern.info kernel: [   17.309448] br-lan: port 1(eth0.1) entered forwarding state
Mon Oct  9 15:37:37 2017 kern.info kernel: [   17.315110] br-lan: port 1(eth0.1) entered forwarding state
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'lan' is setting up now
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'lan' is now up
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'loopback' is enabled
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'loopback' is setting up now
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'loopback' is now up
Mon Oct  9 15:37:37 2017 kern.info kernel: [   17.370977] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'wan' is enabled
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'wan6' is enabled
Mon Oct  9 15:37:37 2017 daemon.notice netifd: bridge 'br-lan' link is up
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'lan' has link connectivity
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Network device 'eth0' link is up
Mon Oct  9 15:37:37 2017 daemon.notice netifd: VLAN 'eth0.1' link is up
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Network device 'lo' link is up
Mon Oct  9 15:37:37 2017 daemon.notice netifd: Interface 'loopback' has link connectivity
Mon Oct  9 15:37:38 2017 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Mon Oct  9 15:37:39 2017 daemon.info procd: - init complete -
Mon Oct  9 15:37:39 2017 kern.info kernel: [   19.417279] br-lan: port 1(eth0.1) entered forwarding state
Mon Oct  9 15:37:40 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Mon Oct  9 15:37:40 2017 kern.info kernel: [   19.972138] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.009308] device wlan0 entered promiscuous mode
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.014079] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.019633] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:37:40 2017 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
Mon Oct  9 15:37:40 2017 daemon.err hostapd: Using interface wlan0 with hwaddr 20:4e:7f:74:4f:00 and ssid "LEDE2"
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.055568] br-lan: port 2(wlan0) entered disabled state
Mon Oct  9 15:37:40 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.202029] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.242770] device wlan1 entered promiscuous mode
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.247620] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:37:40 2017 kern.info kernel: [   20.253147] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:37:40 2017 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->COUNTRY_UPDATE
Mon Oct  9 15:37:40 2017 daemon.err hostapd: Using interface wlan1 with hwaddr 20:4e:7f:74:4f:02 and ssid "LEDE5"
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.054795] br-lan: port 3(wlan1) entered disabled state
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.164179] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.170755] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.176298] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:37:41 2017 daemon.err hostapd: Interface initialization failed
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->DISABLED
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Mon Oct  9 15:37:41 2017 daemon.err hostapd: wlan0: Unable to setup interface.
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan0: interface state DISABLED->DISABLED
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING
Mon Oct  9 15:37:41 2017 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan0 wasn't started
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.291417] device wlan0 left promiscuous mode
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.296010] br-lan: port 2(wlan0) entered disabled state
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: ELOOP: remaining socket: sock=20 eloop_data=0x779f4b50 user_data=0 handler=0x4217c9
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.366044] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.372530] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.378081] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio0 (854): cat: can't open '/var/run/wifi-phy0.pid': No such file or directory
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio0 (854): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path ()
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio0 (854): Command failed: Invalid argument
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio0 (854): Device setup failed: HOSTAPD_START_FAILED
Mon Oct  9 15:37:41 2017 daemon.err hostapd: Interface initialization failed
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan1: interface state COUNTRY_UPDATE->DISABLED
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan1: AP-DISABLED
Mon Oct  9 15:37:41 2017 daemon.err hostapd: wlan1: Unable to setup interface.
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan1: interface state DISABLED->DISABLED
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan1: AP-DISABLED
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING
Mon Oct  9 15:37:41 2017 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan1 wasn't started
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.497485] device wlan1 left promiscuous mode
Mon Oct  9 15:37:41 2017 kern.info kernel: [   21.502058] br-lan: port 3(wlan1) entered disabled state
Mon Oct  9 15:37:41 2017 daemon.notice hostapd: ELOOP: remaining socket: sock=21 eloop_data=0x772449a0 user_data=0 handler=0x4217c9
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio1 (855): cat: can't open '/var/run/wifi-phy1.pid': No such file or directory
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio1 (855): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path ()
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio1 (855): Command failed: Invalid argument
Mon Oct  9 15:37:41 2017 daemon.notice netifd: radio1 (855): Device setup failed: HOSTAPD_START_FAILED

Not sure if it will mean anything, but removing ieee80211w from the config the radios will start normally with the following log messages:


Mon Oct  9 15:39:03 2017 daemon.info procd: - init complete -
Mon Oct  9 15:39:03 2017 kern.info kernel: [   19.304780] br-lan: port 1(eth0.1) entered forwarding state
Mon Oct  9 15:39:04 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Mon Oct  9 15:39:04 2017 kern.info kernel: [   19.952065] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mon Oct  9 15:39:04 2017 kern.info kernel: [   19.989856] device wlan0 entered promiscuous mode
Mon Oct  9 15:39:04 2017 kern.info kernel: [   19.994649] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:39:04 2017 kern.info kernel: [   20.000191] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:39:04 2017 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
Mon Oct  9 15:39:04 2017 daemon.err hostapd: Using interface wlan0 with hwaddr 20:4e:7f:74:4f:00 and ssid "LEDE2"
Mon Oct  9 15:39:04 2017 kern.info kernel: [   20.035571] br-lan: port 2(wlan0) entered disabled state
Mon Oct  9 15:39:04 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Mon Oct  9 15:39:04 2017 kern.info kernel: [   20.182114] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Mon Oct  9 15:39:04 2017 kern.info kernel: [   20.222709] device wlan1 entered promiscuous mode
Mon Oct  9 15:39:04 2017 kern.info kernel: [   20.227564] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:39:04 2017 kern.info kernel: [   20.233059] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:39:04 2017 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->COUNTRY_UPDATE
Mon Oct  9 15:39:04 2017 daemon.err hostapd: Using interface wlan1 with hwaddr 20:4e:7f:74:4f:02 and ssid "LEDE5"
Mon Oct  9 15:39:05 2017 kern.info kernel: [   21.034797] br-lan: port 3(wlan1) entered disabled state
Mon Oct  9 15:39:05 2017 kern.info kernel: [   21.145366] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mon Oct  9 15:39:05 2017 kern.info kernel: [   21.151869] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:39:05 2017 kern.info kernel: [   21.157448] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:39:05 2017 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->ENABLED
Mon Oct  9 15:39:05 2017 daemon.notice hostapd: wlan0: AP-ENABLED
Mon Oct  9 15:39:05 2017 kern.info kernel: [   21.345464] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Mon Oct  9 15:39:05 2017 kern.info kernel: [   21.351982] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:39:05 2017 kern.info kernel: [   21.357538] br-lan: port 3(wlan1) entered forwarding state
Mon Oct  9 15:39:05 2017 daemon.notice hostapd: wlan1: interface state COUNTRY_UPDATE->ENABLED
Mon Oct  9 15:39:05 2017 daemon.notice hostapd: wlan1: AP-ENABLED
Mon Oct  9 15:39:05 2017 daemon.notice netifd: Network device 'wlan0' link is up
Mon Oct  9 15:39:05 2017 daemon.notice netifd: Network device 'wlan1' link is up
Mon Oct  9 15:39:07 2017 kern.info kernel: [   23.154764] br-lan: port 2(wlan0) entered forwarding state
Mon Oct  9 15:39:07 2017 kern.info kernel: [   23.354788] br-lan: port 3(wlan1) entered forwarding state

Compiled using Ubuntu 17.04 x64 and fresh pull of source.git. ieee80211w option continues to cause the radios to not start on WNDR3800. Same error message as in previous logs.

Compiled using the same Ubuntu 17.04 box and this time with a fresh pull from the libreCMC git (they re-based off LEDE 17.01) and ieee80211w works fine again on my WNDR3800.

https://bugs.lede-project.org/index.php?do=details&task_id=898

have you tried using hostapd instead?

I have same problem on wzr-hp-ag300h, builded on Ubuntu 17.04 x64, snapshot r5025.
When 80211w option removed, everything work well.
(logread |grep hostapd)
Tue Oct 10 18:07:56 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Tue Oct 10 18:07:57 2017 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
Tue Oct 10 18:07:57 2017 daemon.err hostapd: Using interface wlan0 with hwaddr 4c:e6:76:xx:xx:xx and ssid "XXX"
Tue Oct 10 18:07:58 2017 daemon.err hostapd: Interface initialization failed
Tue Oct 10 18:07:58 2017 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->DISABLED
Tue Oct 10 18:07:58 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Tue Oct 10 18:07:58 2017 daemon.err hostapd: wlan0: Unable to setup interface.
Tue Oct 10 18:07:58 2017 daemon.notice hostapd: wlan0: interface state DISABLED->DISABLED
Tue Oct 10 18:07:58 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Tue Oct 10 18:07:58 2017 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING
Tue Oct 10 18:07:58 2017 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan0 wasn't started
Tue Oct 10 18:07:58 2017 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Tue Oct 10 18:07:58 2017 daemon.notice hostapd: ELOOP: remaining socket: sock=20 eloop_data=0x777afec0 user_data=0 handler=0x4395dc
Tue Oct 10 18:08:09 2017 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
Tue Oct 10 18:08:09 2017 daemon.err hostapd: Using interface wlan0 with hwaddr 4c:e6:76:xx:xx:xx and ssid "XXX"
Tue Oct 10 18:08:09 2017 daemon.err hostapd: Interface initialization failed
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->DISABLED
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Tue Oct 10 18:08:09 2017 daemon.err hostapd: wlan0: Unable to setup interface.
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: wlan0: interface state DISABLED->DISABLED
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: wlan0: AP-DISABLED
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING
Tue Oct 10 18:08:09 2017 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan0 wasn't started
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Tue Oct 10 18:08:09 2017 daemon.notice hostapd: ELOOP: remaining socket: sock=20 eloop_data=0x774c1ec0 user_data=0 handler=0x4395dc

Ok, the 11W setting is supposed to be enabled by passing a -DCONFIG_IEEE80211W cflag:
https://github.com/lede-project/source/blame/master/package/network/services/hostapd/Makefile#L75

This should be working fine, if it wasn't for some checks in hostapd's makefiles:
http://w1.fi/cgit/hostap/tree/hostapd/Makefile#n286
Because the makefile doesn't know about cflags and will only care about the contents of the ".config"
which as you can see here:

disables the symbol - (it's commented out so the =y does nothing. + check out the git blame history of the 11W line there.)

Can you edit the hostapd-full.config file (remove the leading #) in your repository, recompile wpad and install the new package?

Yep.
root@AG300H:~# opkg list-installed |grep hostapd
hostapd-common - 2017-08-24-c2d4f2eb-1
root@AG300H:~# opkg list-installed |grep wpad
wpad - 2017-08-24-c2d4f2eb-1

Oops, I think I accidentally edited the previous reply, instead of making a new one.
Please see: Wpad 802.11w no longer working in trunk?

Hmm. That option in hostapd-full.config did not actually change recently, but instead wpa_supplicant-full.config did recently change due to this commit ( cc @stintel ):
https://git.lede-project.org/?p=source.git;a=commitdiff;h=65113799d734d4f6a2cf10f9d704a94b71340fbe

Commented CONFIG_IEEE80211W=y flag because it is
set in the Makefile, only if the driver supports it.

Removed NEED_80211_COMMON flag because it is not part
of config file, it is set by the hostapd upstream Makefile.

The curious part here is why this would affect only some users.

If I had to guess, it's probably because of

disabling CONFIG_WPS. But we won't know, unless someone reverts it and tests whenever ieee80211w=1 does work or not.

I'm edit hostapd-full.config and/or wpa_supplicant-full.config, recompiled&install but still not work.

(logread |grep wpad)
 daemon.notice netifd: radio0 (977): WARNING (wireless_add_process): executable path /usr/sbin/wpad does not match process  path ()
(logread |grep hostapd)
daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->HT_SCAN
 daemon.notice hostapd: 20/40 MHz operation not permitted on channel pri=11 sec=7 based on overlapping BSSes
 daemon.err hostapd: Using interface wlan0 with hwaddr 4c:e6:76::xx:xx:xx and ssid "XXX"
 daemon.err hostapd: Interface initialization failed
 daemon.notice hostapd: wlan0: interface state HT_SCAN->DISABLED
 daemon.notice hostapd: wlan0: AP-DISABLED 
 daemon.notice hostapd: wlan0: interface state DISABLED->DISABLED
 daemon.notice hostapd: wlan0: AP-DISABLED 
 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING 
 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan0 wasn't started
 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
 daemon.notice hostapd: ELOOP: remaining socket: sock=20 eloop_data=0x77489e90 user_data=0 handler=0x4394d0