[OpenWrt-Devel] Custom init.d script switching LAN static -> dhcp triggers reload <-> hostapd race

Rafał Miłecki zajec5 at gmail.com
Fri Sep 14 07:48:23 EDT 2018 

My custom OpenWrt 18.06 builds have LAN interface configured as static:

config interface lan
	option ifname   'eth0.1'
	option type     'bridge'
	option proto    'static'
	option ipaddr   '192.168.0.1'
	option netmask  '255.255.255.0'


Then I have a following init.d script:

START=99
start() {
	[ "$(nvram get use_dhcp)" = "1" ] && {
		logger -t "lan-switcher" "Switching lan to DHCP"
		uci set network.lan.proto=dhcp
		uci commit network
		/etc/init.d/network reload
	}
}


The first observation: booting order always differs slightly. Using the
same firmware & firstboot produces slightly different log on every boot.


The real problem: in some cases my init.d script kicks in in such a
moment that causes a race between netifd reload (triggered by a script)
and hostapd start. If hostapd happens to start while netifd is reloading
lan config (it removes interface to switch it into dhcp mode) it results
in:

Thu Sep 13 18:11:16 2018 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Thu Sep 13 18:11:16 2018 daemon.err hostapd: nl80211: Failed to add interface wlan1 into bridge br-lan: No such device


My question is whether there is any good & simple solution to that?
Should I fix my script? Or can netifd get some internal mutex/sync that
will stop it from reloading interfaces & starting hostapd at the same
time?


I'm attaching two logs with netifd's debugging included.
-------------- next part --------------
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: tunnel
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: Network device
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: bridge
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: macvlan
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: 8021ad
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: 8021q
Thu Sep 13 18:11:14 2018 daemon.notice netifd: netifd_ubus_init(1163): connected as e21b0329
Thu Sep 13 18:11:14 2018 daemon.notice netifd: proto_shell_add_handler(916): Add handler for script ./dhcp.sh: dhcp
Thu Sep 13 18:11:14 2018 daemon.err lighttpd[968]: (log.c.217) server started 
Thu Sep 13 18:11:14 2018 daemon.notice netifd: wireless_add_handler(542): Add handler for script ./mac80211.sh: mac80211
Thu Sep 13 18:11:14 2018 daemon.notice netifd: interface_update(1252): Create interface 'loopback'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_create_default(521): Create simple device 'lo'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'lo'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_set_present(624): Network device 'lo' is now present
Thu Sep 13 18:11:14 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'lo', refcount=1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: interface_set_available(433): Interface 'loopback', available=1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'lo', refcount=2
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_create(961): Create new device 'br-lan' (bridge)
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'br-lan'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: interface_add_dns_server(1136): Add IPv4 DNS server: 192.168.0.1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: interface_update(1252): Create interface 'lan'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=2
Thu Sep 13 18:11:14 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:14 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:14 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:14 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:14 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:14 2018 daemon.notice netifd: wdev_update(501): Create wireless device 'radio0'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: wdev_update(501): Create wireless device 'radio1'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: vif_update(610): Create new wireless interface 0 on device radio0
Thu Sep 13 18:11:14 2018 daemon.notice netifd: vif_update(610): Create new wireless interface 0 on device radio1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_create_default(521): Create simple device 'eth0'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'eth0'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_set_present(624): Network device 'eth0' is now present
Thu Sep 13 18:11:14 2018 daemon.notice netifd: get_vlan_device(122): Create vlan device 'eth0.1'
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_init_virtual(481): Initialize device ''
Thu Sep 13 18:11:14 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'eth0', refcount=1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_set_present(624): VLAN 'eth0.1' is now present
Thu Sep 13 18:11:14 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'eth0.1', refcount=1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_set_present(624): bridge 'br-lan' is now present
Thu Sep 13 18:11:14 2018 daemon.notice netifd: interface_set_available(433): Interface 'lan', available=1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_claim(416): Claim bridge br-lan, new active count: 1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_claim(416): Claim VLAN eth0.1, new active count: 1
Thu Sep 13 18:11:14 2018 daemon.notice netifd: device_claim(416): Claim Network device eth0, new active count: 1
Thu Sep 13 18:11:14 2018 kern.info kernel: [   11.336903] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Thu Sep 13 18:11:14 2018 kern.info kernel: [   11.374907] device eth0.1 entered promiscuous mode
Thu Sep 13 18:11:14 2018 kern.info kernel: [   11.379724] device eth0 entered promiscuous mode
Thu Sep 13 18:11:15 2018 kern.info kernel: [   11.399078] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'lan' is enabled
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'lan' is setting up now
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'lan', event 'ifup'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: call_hotplug(97): Call hotplug handler for interface 'lan', event 'ifup' (br-lan)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'lan' is now up
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_claim(416): Claim Network device lo, new active count: 1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' is enabled
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' is setting up now
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'loopback', event 'ifup'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' is now up
Thu Sep 13 18:11:15 2018 daemon.notice netifd: wireless_device_run_handler(253): Wireless device 'radio0' run setup handler
Thu Sep 13 18:11:15 2018 daemon.notice netifd: wireless_device_run_handler(253): Wireless device 'radio1' run setup handler
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Network device 'lo' link is up
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' has link connectivity 
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'loopback', event 'iflink'
Thu Sep 13 18:11:15 2018 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Thu Sep 13 18:11:15 2018 daemon.err procd: instance1: not starting seccomp as /etc/seccomp/umdns.json is missing
Thu Sep 13 18:11:15 2018 daemon.notice netifd: task_complete(105): Complete hotplug handler for interface 'lan'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: call_hotplug(97): Call hotplug handler for interface 'loopback', event 'ifup' (lo)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1018): command failed: No error information (-524)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1019): command failed: No error information (-524)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1019): command failed: Not supported (-95)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: task_complete(105): Complete hotplug handler for interface 'loopback'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1019): command failed: I/O error (-5)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1018): command failed: Not supported (-95)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1018): command failed: I/O error (-5)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1018): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1019): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:16 2018 authpriv.info dropbear[1289]: Not backgrounding
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.850813] bgmac_bcma bcma0:3 eth0: Link is Up - 1Gbps/Full - flow control off
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.858198] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Thu Sep 13 18:11:16 2018 daemon.notice netifd: Network device 'eth0' link is up
Thu Sep 13 18:11:16 2018 daemon.notice netifd: VLAN 'eth0.1' link is up
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.878912] br-lan: port 1(eth0.1) entered forwarding state
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.884590] br-lan: port 1(eth0.1) entered forwarding state
Thu Sep 13 18:11:16 2018 daemon.info procd: - init complete -
Thu Sep 13 18:11:16 2018 daemon.notice netifd: bridge 'br-lan' link is up
Thu Sep 13 18:11:16 2018 daemon.notice netifd: Interface 'lan' has link connectivity 
Thu Sep 13 18:11:16 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'lan', event 'iflink'
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.916763] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready

Thu Sep 13 18:11:16 2018 user.notice lan-switcher: Switching lan to DHCP

Thu Sep 13 18:11:16 2018 daemon.notice netifd: radio0 (1018): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:16 2018 daemon.notice netifd: radio1 (1019): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:16 2018 daemon.notice netifd: interface_update(1246): Update interface 'loopback'
Thu Sep 13 18:11:16 2018 daemon.notice netifd: device_apply_config(831): Device 'br-lan': config applied
Thu Sep 13 18:11:16 2018 daemon.notice netifd: interface_add_dns_server(1136): Add IPv4 DNS server: 192.168.0.1
Thu Sep 13 18:11:16 2018 daemon.notice netifd: interface_update(1246): Update interface 'lan'
Thu Sep 13 18:11:16 2018 daemon.notice netifd: interface_change_config(1211): Reload interface 'lan' because of config changes
Thu Sep 13 18:11:16 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'lan', event 'ifdown'
Thu Sep 13 18:11:16 2018 daemon.notice netifd: call_hotplug(97): Call hotplug handler for interface 'lan', event 'ifdown' (none)
Thu Sep 13 18:11:16 2018 daemon.notice netifd: Interface 'lan' is now down
Thu Sep 13 18:11:16 2018 daemon.notice netifd: device_release(452): Release bridge br-lan, new active count: 0
Thu Sep 13 18:11:16 2018 kern.info kernel: [   13.044901] br-lan: port 1(eth0.1) entered disabled state
Thu Sep 13 18:11:16 2018 kern.info kernel: [   13.061225] device eth0.1 left promiscuous mode
Thu Sep 13 18:11:16 2018 kern.info kernel: [   13.065775] device eth0 left promiscuous mode
Thu Sep 13 18:11:16 2018 kern.info kernel: [   13.070420] br-lan: port 1(eth0.1) entered disabled state
Thu Sep 13 18:11:16 2018 daemon.notice netifd: device_release(452): Release VLAN eth0.1, new active count: 0
Thu Sep 13 18:11:16 2018 kern.info kernel: [   13.144298] IPv6: ADDRCONF(NETDEV_UP): eth0.1: link is not ready
Thu Sep 13 18:11:16 2018 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Thu Sep 13 18:11:16 2018 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Thu Sep 13 18:11:16 2018 daemon.notice netifd: device_release(452): Release Network device eth0, new active count: 0
Thu Sep 13 18:11:16 2018 kern.info kernel: [   13.241546] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready

Thu Sep 13 18:11:16 2018 daemon.err hostapd: nl80211: Failed to add interface wlan1 into bridge br-lan: No such device

Thu Sep 13 18:11:16 2018 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' is disabled
Thu Sep 13 18:11:17 2018 daemon.err hostapd: nl80211 driver initialization failed.
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->DISABLED
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_remove_user(757): Remove user for device 'br-lan', refcount=1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_set_available(433): Interface 'lan', available=0
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan1: AP-DISABLED 
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_remove_user(757): Remove user for device 'br-lan', refcount=0
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING 
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' has link connectivity loss
Thu Sep 13 18:11:17 2018 daemon.err hostapd: hostapd_free_hapd_data: Interface wlan1 wasn't started
Thu Sep 13 18:11:17 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_set_available(433): Interface 'lan', available=1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' has link connectivity 
Thu Sep 13 18:11:17 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=2
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_claim(416): Claim bridge br-lan, new active count: 1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_claim(416): Claim VLAN eth0.1, new active count: 1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_claim(416): Claim Network device eth0, new active count: 1
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.786445] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.795309] device wlan0 entered promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.802043] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Thu Sep 13 18:11:17 2018 daemon.warn hostapd: wlan0: Could not connect to kernel driver
Thu Sep 13 18:11:17 2018 daemon.err hostapd: Using interface wlan0 with hwaddr a4:13:4e:7a:fc:81 and ssid "OpenWrt 2G"
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.850766] bgmac_bcma bcma0:3 eth0: Link is Up - 1Gbps/Full - flow control off
Thu Sep 13 18:11:17 2018 daemon.notice netifd: proto_shell_handler(235): run renew for interface 'lan'
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.017729] device eth0.1 entered promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.022576] device eth0 entered promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.027449] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.034109] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->ENABLED
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan0: AP-ENABLED 
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.044094] br-lan: port 2(eth0.1) entered forwarding state
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.049794] br-lan: port 2(eth0.1) entered forwarding state
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.055452] br-lan: port 1(wlan0) entered forwarding state
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.061058] br-lan: port 1(wlan0) entered forwarding state
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' is enabled
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' is setting up now
Thu Sep 13 18:11:17 2018 daemon.notice netifd: proto_shell_handler(235): run setup for interface 'lan'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: task_complete(105): Complete hotplug handler for interface 'lan'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: radio1 (1019): cat: can't open '/var/run/wifi-phy1.pid': No such file or directory
Thu Sep 13 18:11:17 2018 daemon.notice netifd: radio1 (1019): Command failed: Invalid argument
Thu Sep 13 18:11:17 2018 daemon.notice netifd: radio1 (1019): Device setup failed: HOSTAPD_START_FAILED
Thu Sep 13 18:11:17 2018 daemon.notice netifd: wireless_device_add_process(857): Wireless device 'radio0' add pid 1387
Thu Sep 13 18:11:17 2018 daemon.notice netifd: wireless_device_run_handler(253): Wireless device 'radio1' run teardown handler
Thu Sep 13 18:11:17 2018 daemon.notice netifd: lan (1422): udhcpc: started, v1.25.1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: wireless_device_mark_up(395): Wireless device 'radio0' is now up
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_create_default(521): Create simple device 'wlan0'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'wlan0'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_set_present(624): Network device 'wlan0' is now present
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Network device 'wlan0' link is up
Thu Sep 13 18:11:17 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'wlan0', refcount=1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_claim(416): Claim Network device wlan0, new active count: 1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: proto_shell_handler(235): run renew for interface 'lan'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: radio1 (1410): command failed: No error information (-524)
Thu Sep 13 18:11:17 2018 daemon.notice netifd: wireless_device_mark_down(338): Wireless device 'radio1' is now down
Thu Sep 13 18:11:17 2018 daemon.notice netifd: lan (1422): udhcpc: sending discover
Thu Sep 13 18:11:17 2018 daemon.notice netifd: lan (1422): udhcpc: performing DHCP renew
Thu Sep 13 18:11:17 2018 daemon.notice netifd: lan (1422): udhcpc: sending discover
Thu Sep 13 18:11:17 2018 daemon.notice netifd: lan (1422): udhcpc: sending select for 192.168.0.115
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1422): udhcpc: lease of 192.168.0.115 obtained, lease time 43200
-------------- next part --------------
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: tunnel
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: Network device
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: bridge
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: macvlan
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: 8021ad
Thu Sep 13 18:11:14 2018 user.notice : Added device handler type: 8021q
Thu Sep 13 18:11:14 2018 daemon.notice netifd: netifd_ubus_init(1163): connected as 307e6891
Thu Sep 13 18:11:14 2018 daemon.notice netifd: proto_shell_add_handler(916): Add handler for script ./dhcp.sh: dhcp
Thu Sep 13 18:11:15 2018 daemon.notice netifd: wireless_add_handler(542): Add handler for script ./mac80211.sh: mac80211
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_update(1252): Create interface 'loopback'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_create_default(521): Create simple device 'lo'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'lo'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_set_present(624): Network device 'lo' is now present
Thu Sep 13 18:11:15 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'lo', refcount=1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_set_available(433): Interface 'loopback', available=1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'lo', refcount=2
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_create(961): Create new device 'br-lan' (bridge)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'br-lan'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_add_dns_server(1136): Add IPv4 DNS server: 192.168.0.1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_update(1252): Create interface 'lan'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=2
Thu Sep 13 18:11:15 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:15 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:15 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:15 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:15 2018 daemon.notice netifd: cb_clear_event(814): Remove a rule
Thu Sep 13 18:11:15 2018 daemon.notice netifd: wdev_update(501): Create wireless device 'radio0'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: wdev_update(501): Create wireless device 'radio1'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: vif_update(610): Create new wireless interface 0 on device radio0
Thu Sep 13 18:11:15 2018 daemon.notice netifd: vif_update(610): Create new wireless interface 0 on device radio1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_create_default(521): Create simple device 'eth0'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'eth0'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_set_present(624): Network device 'eth0' is now present
Thu Sep 13 18:11:15 2018 daemon.notice netifd: get_vlan_device(122): Create vlan device 'eth0.1'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_init_virtual(481): Initialize device ''
Thu Sep 13 18:11:15 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'eth0', refcount=1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_set_present(624): VLAN 'eth0.1' is now present
Thu Sep 13 18:11:15 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'eth0.1', refcount=1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_set_present(624): bridge 'br-lan' is now present
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_set_available(433): Interface 'lan', available=1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_claim(416): Claim bridge br-lan, new active count: 1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_claim(416): Claim VLAN eth0.1, new active count: 1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_claim(416): Claim Network device eth0, new active count: 1
Thu Sep 13 18:11:15 2018 kern.info kernel: [   11.487375] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Thu Sep 13 18:11:15 2018 kern.info kernel: [   11.502629] device eth0.1 entered promiscuous mode
Thu Sep 13 18:11:15 2018 kern.info kernel: [   11.507444] device eth0 entered promiscuous mode
Thu Sep 13 18:11:15 2018 daemon.err lighttpd[993]: (log.c.217) server started 
Thu Sep 13 18:11:15 2018 kern.info kernel: [   11.526476] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'lan' is enabled
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'lan' is setting up now
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'lan', event 'ifup'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: call_hotplug(97): Call hotplug handler for interface 'lan', event 'ifup' (br-lan)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'lan' is now up
Thu Sep 13 18:11:15 2018 daemon.notice netifd: device_claim(416): Claim Network device lo, new active count: 1
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' is enabled
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' is setting up now
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'loopback', event 'ifup'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' is now up
Thu Sep 13 18:11:15 2018 daemon.notice netifd: wireless_device_run_handler(253): Wireless device 'radio0' run setup handler
Thu Sep 13 18:11:15 2018 daemon.notice netifd: wireless_device_run_handler(253): Wireless device 'radio1' run setup handler
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Network device 'lo' link is up
Thu Sep 13 18:11:15 2018 daemon.notice netifd: Interface 'loopback' has link connectivity 
Thu Sep 13 18:11:15 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'loopback', event 'iflink'
Thu Sep 13 18:11:15 2018 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: task_complete(105): Complete hotplug handler for interface 'lan'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: call_hotplug(97): Call hotplug handler for interface 'loopback', event 'ifup' (lo)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1001): command failed: No error information (-524)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1000): command failed: No error information (-524)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: task_complete(105): Complete hotplug handler for interface 'loopback'
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1000): command failed: Not supported (-95)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1001): command failed: Not supported (-95)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1000): command failed: I/O error (-5)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1001): command failed: I/O error (-5)
Thu Sep 13 18:11:15 2018 daemon.err procd: instance1: not starting seccomp as /etc/seccomp/umdns.json is missing
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio0 (1000): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:15 2018 daemon.notice netifd: radio1 (1001): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.880717] bgmac_bcma bcma0:3 eth0: Link is Up - 1Gbps/Full - flow control off
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.888089] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.911125] br-lan: port 1(eth0.1) entered forwarding state
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.916829] br-lan: port 1(eth0.1) entered forwarding state
Thu Sep 13 18:11:16 2018 daemon.notice netifd: Network device 'eth0' link is up
Thu Sep 13 18:11:16 2018 daemon.notice netifd: VLAN 'eth0.1' link is up
Thu Sep 13 18:11:16 2018 kern.info kernel: [   12.940662] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Thu Sep 13 18:11:16 2018 daemon.notice netifd: bridge 'br-lan' link is up
Thu Sep 13 18:11:16 2018 daemon.notice netifd: Interface 'lan' has link connectivity 
Thu Sep 13 18:11:16 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'lan', event 'iflink'
Thu Sep 13 18:11:16 2018 daemon.notice netifd: radio0 (1000): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:16 2018 daemon.notice netifd: radio1 (1001): command failed: Too many open files in system (-23)
Thu Sep 13 18:11:16 2018 daemon.info procd: - init complete -

Thu Sep 13 18:11:16 2018 user.notice lan-switcher: Switching lan to DHCP

Thu Sep 13 18:11:17 2018 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Thu Sep 13 18:11:17 2018 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_update(1246): Update interface 'loopback'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_apply_config(831): Device 'br-lan': config applied
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_add_dns_server(1136): Add IPv4 DNS server: 192.168.0.1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_update(1246): Update interface 'lan'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_change_config(1211): Reload interface 'lan' because of config changes
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_queue_event(121): Queue hotplug handler for interface 'lan', event 'ifdown'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: call_hotplug(97): Call hotplug handler for interface 'lan', event 'ifdown' (none)
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' is now down
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.452824] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_release(452): Release bridge br-lan, new active count: 0
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.485497] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.492345] br-lan: port 1(eth0.1) entered disabled state
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.498594] device wlan1 entered promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   13.503678] device wlan0 entered promiscuous mode
Thu Sep 13 18:11:17 2018 daemon.warn hostapd: wlan1: Could not connect to kernel driver
Thu Sep 13 18:11:17 2018 daemon.warn hostapd: wlan0: Could not connect to kernel driver
Thu Sep 13 18:11:17 2018 daemon.err hostapd: Using interface wlan1 with hwaddr a4:13:4e:7a:fc:88 and ssid "OpenWrt 5G"
Thu Sep 13 18:11:17 2018 daemon.err hostapd: Using interface wlan0 with hwaddr a4:13:4e:7a:fc:81 and ssid "OpenWrt 2G"
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.114646] device eth0.1 left promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.119217] device eth0 left promiscuous mode
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_release(452): Release VLAN eth0.1, new active count: 0
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.123738] br-lan: port 1(eth0.1) entered disabled state
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.130330] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.136853] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->ENABLED
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->ENABLED
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan0: AP-ENABLED 
Thu Sep 13 18:11:17 2018 daemon.notice hostapd: wlan1: AP-ENABLED 
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.159295] IPv6: ADDRCONF(NETDEV_UP): eth0.1: link is not ready
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_release(452): Release Network device eth0, new active count: 0
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.222204] device wlan0 left promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.226718] br-lan: port 3(wlan0) entered disabled state
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.233551] device wlan1 left promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.238055] br-lan: port 2(wlan1) entered disabled state
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' is disabled
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_remove_user(757): Remove user for device 'br-lan', refcount=1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_set_available(433): Interface 'lan', available=0
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_remove_user(757): Remove user for device 'br-lan', refcount=0
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' has link connectivity loss
Thu Sep 13 18:11:17 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: interface_set_available(433): Interface 'lan', available=1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' has link connectivity 
Thu Sep 13 18:11:17 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'br-lan', refcount=2
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_claim(416): Claim bridge br-lan, new active count: 1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_claim(416): Claim VLAN eth0.1, new active count: 1
Thu Sep 13 18:11:17 2018 daemon.notice netifd: device_claim(416): Claim Network device eth0, new active count: 1
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.283735] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.299680] device eth0.1 entered promiscuous mode
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.304554] device eth0 entered promiscuous mode
Thu Sep 13 18:11:17 2018 daemon.notice netifd: proto_shell_handler(235): run renew for interface 'lan'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' is enabled
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' is setting up now
Thu Sep 13 18:11:17 2018 daemon.notice netifd: proto_shell_handler(235): run setup for interface 'lan'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: task_complete(105): Complete hotplug handler for interface 'lan'
Thu Sep 13 18:11:17 2018 daemon.notice netifd: wireless_device_add_process(857): Wireless device 'radio0' add pid 1326
Thu Sep 13 18:11:17 2018 kern.info kernel: [   14.315903] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
Thu Sep 13 18:11:17 2018 daemon.notice netifd: wireless_device_add_process(857): Wireless device 'radio1' add pid 1325
Thu Sep 13 18:11:17 2018 daemon.notice netifd: VLAN 'eth0.1' link is down
Thu Sep 13 18:11:17 2018 daemon.notice netifd: bridge 'br-lan' link is down
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Interface 'lan' has link connectivity loss
Thu Sep 13 18:11:17 2018 daemon.notice netifd: Network device 'eth0' link is down
Thu Sep 13 18:11:18 2018 daemon.notice netifd: wireless_device_mark_up(395): Wireless device 'radio0' is now up
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_create_default(521): Create simple device 'wlan0'
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'wlan0'
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_set_present(624): Network device 'wlan0' is now present
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Network device 'wlan0' link is up
Thu Sep 13 18:11:18 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'wlan0', refcount=1
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_claim(416): Claim Network device wlan0, new active count: 1
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.418281] device wlan0 entered promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.423083] br-lan: port 2(wlan0) entered forwarding state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.428605] br-lan: port 2(wlan0) entered forwarding state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.435250] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Thu Sep 13 18:11:18 2018 daemon.notice netifd: wireless_device_mark_up(395): Wireless device 'radio1' is now up
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_create_default(521): Create simple device 'wlan1'
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_init_virtual(481): Initialize device 'wlan1'
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_set_present(624): Network device 'wlan1' is now present
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Network device 'wlan1' link is up
Thu Sep 13 18:11:18 2018 daemon.notice netifd: __device_add_user(697): Add user for device 'wlan1', refcount=1
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_claim(416): Claim Network device wlan1, new active count: 1
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.456387] device wlan1 entered promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.461438] br-lan: port 3(wlan1) entered forwarding state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.466989] br-lan: port 3(wlan1) entered forwarding state
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1382): Command failed: Permission denied
Thu Sep 13 18:11:18 2018 daemon.notice netifd: proto_shell_handler(235): run teardown for interface 'lan'
Thu Sep 13 18:11:18 2018 daemon.notice netifd: bridge 'br-lan' link is up
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Interface 'lan' has link connectivity 
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Interface 'lan' is setting up now
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Interface 'lan' is now down
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_release(452): Release bridge br-lan, new active count: 0
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.504380] br-lan: port 3(wlan1) entered disabled state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.509768] br-lan: port 2(wlan0) entered disabled state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.522883] device eth0.1 left promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.527434] device eth0 left promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.532059] br-lan: port 1(eth0.1) entered disabled state
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_release(452): Release VLAN eth0.1, new active count: 0
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.542612] IPv6: ADDRCONF(NETDEV_UP): eth0.1: link is not ready
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_release(452): Release Network device eth0, new active count: 0
Thu Sep 13 18:11:18 2018 daemon.notice netifd: proto_shell_handler(235): run renew for interface 'lan'
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.602549] device wlan0 left promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.607077] br-lan: port 2(wlan0) entered disabled state
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_release(452): Release Network device wlan0, new active count: 0
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.620931] device wlan1 left promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.625461] br-lan: port 3(wlan1) entered disabled state
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_release(452): Release Network device wlan1, new active count: 0
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Interface 'lan' is disabled
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_remove_user(757): Remove user for device 'br-lan', refcount=1
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_claim(416): Claim bridge br-lan, new active count: 1
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_claim(416): Claim VLAN eth0.1, new active count: 1
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_claim(416): Claim Network device eth0, new active count: 1
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.675238] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.693265] device eth0.1 entered promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.698073] device eth0 entered promiscuous mode
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_claim(416): Claim Network device wlan0, new active count: 1
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.705023] device wlan0 entered promiscuous mode
Thu Sep 13 18:11:18 2018 daemon.notice netifd: device_claim(416): Claim Network device wlan1, new active count: 1
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.717018] device wlan1 entered promiscuous mode
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.727307] br-lan: port 3(wlan1) entered forwarding state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.732886] br-lan: port 3(wlan1) entered forwarding state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.738396] br-lan: port 2(wlan0) entered forwarding state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.743947] br-lan: port 2(wlan0) entered forwarding state
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Interface 'lan' is enabled
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Interface 'lan' is setting up now
Thu Sep 13 18:11:18 2018 daemon.notice netifd: proto_shell_handler(235): run setup for interface 'lan'
Thu Sep 13 18:11:18 2018 daemon.notice netifd: proto_shell_handler(235): run renew for interface 'lan'
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1493): udhcpc: started, v1.25.1
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.880883] bgmac_bcma bcma0:3 eth0: Link is Up - 1Gbps/Full - flow control off
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.890666] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Thu Sep 13 18:11:18 2018 daemon.notice netifd: VLAN 'eth0.1' link is up
Thu Sep 13 18:11:18 2018 daemon.notice netifd: Network device 'eth0' link is up
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.897898] br-lan: port 1(eth0.1) entered forwarding state
Thu Sep 13 18:11:18 2018 kern.info kernel: [   14.903568] br-lan: port 1(eth0.1) entered forwarding state
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1493): udhcpc: sending discover
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1493): udhcpc: performing DHCP renew
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1493): udhcpc: sending discover
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1493): udhcpc: sending select for 192.168.0.115
Thu Sep 13 18:11:18 2018 daemon.notice netifd: lan (1493): udhcpc: lease of 192.168.0.115 obtained, lease time 43200
-------------- next part --------------
_______________________________________________
openwrt-devel mailing list
openwrt-devel at lists.openwrt.org
https://lists.openwrt.org/mailman/listinfo/openwrt-devel

More information about the openwrt-devel mailing list