The following is an log example of ezhotspot extender trying to connect to a remote hotspot (multiple access points with the same SSID) with Auto Recovery function disabled.
Below examples show the possible problems:
- Cannot establish physical wireless handshaking due to weak wireless signal.
- Can establish physical wireless handshaking but link is not possible for data or remote hotspot error.
Mon Apr 26 05:05:06 2021 kern.info kernel: [278869.964928] wlan1: associated
Mon Apr 26 05:05:06 2021 daemon.notice netifd: Network device 'wlan1' link is up
Mon Apr 26 05:05:06 2021 daemon.notice netifd: Interface 'hotspot24' has link connectivity
Mon Apr 26 05:05:06 2021 daemon.notice netifd: Interface 'hotspot24' is setting up now
Mon Apr 26 05:05:06 2021 kern.debug kernel: [278870.006052] wlan1: Limiting TX power to 20 (20 - 0) dBm as advertised by ae:22: 15:54:55:23
REMARKS: Extender established physical handshaking with remote hotspot ae:22: 15:54:55:23.
Mon Apr 26 05:05:06 2021 daemon.notice netifd: hotspot24 (10884): udhcpc: started, v1.25.1
Mon Apr 26 05:05:06 2021 daemon.notice netifd: hotspot24 (10884): udhcpc: sending discover
Mon Apr 26 05:05:09 2021 daemon.notice netifd: hotspot24 (10884): udhcpc: sending discover
Mon Apr 26 05:05:12 2021 daemon.notice netifd: hotspot24 (10884): udhcpc: sending discover
Mon Apr 26 05:05:36 2021 daemon.notice hostapd: handle_probe_req: send failed
Mon Apr 26 05:06:09 2021 kern.info kernel: [278933.076309] wlan1: deauthenticating from ae:22:15:54:55:23 by local choice (Reason: 3=DEAUTH_LEAVING)
REMARKS: Remote hotspot did not provide IP address to extender. Extender will re-try if the Auto Recovery is disabled. Possible wireless link is not good or remote DHCP error.
Mon Apr 26 05:06:10 2021 daemon.notice netifd: Network device 'wlan1' link is down
Mon Apr 26 05:06:10 2021 daemon.notice netifd: Interface 'hotspot24' has link connectivity loss
Mon Apr 26 05:06:10 2021 daemon.notice netifd: hotspot24 (10884): udhcpc: received SIGTERM
Mon Apr 26 05:06:10 2021 daemon.notice netifd: Interface 'hotspot24' is now down
Mon Apr 26 05:06:10 2021 daemon.notice netifd: Interface 'hotspot24' is disabled
Mon Apr 26 05:06:10 2021 daemon.notice netifd: Interface 'hotspot24' is enabled
Mon Apr 26 05:06:11 2021 kern.info kernel: [278934.679139] wlan1: authenticate with 82:29:94:f3:90:e5
REMARKS: Extender found another remote hotspot with same SSID, 82:29:94:f3:90:e5.
Mon Apr 26 05:06:11 2021 kern.info kernel: [278934.698664] wlan1: send auth to 82:29:94:f3:90:e5 (try 1/3)
Mon Apr 26 05:06:11 2021 kern.info kernel: [278934.707859] wlan1: authenticated
Mon Apr 26 05:06:11 2021 kern.info kernel: [278934.738621] wlan1: associate with 82:29:94:f3:90:e5 (try 1/3)
Mon Apr 26 05:06:11 2021 kern.info kernel: [278934.888709] wlan1: associate with 82:29:94:f3:90:e5 (try 2/3)
Mon Apr 26 05:06:11 2021 kern.info kernel: [278935.030252] wlan1: associate with 82:29:94:f3:90:e5 (try 3/3)
Mon Apr 26 05:06:11 2021 kern.info kernel: [278935.204446] wlan1: association with 82:29:94:f3:90:e5 timed out
REMARKS: Extender could not establish a good wireless connection with 82:29:94:f3:90:e5.
Mon Apr 26 05:06:12 2021 kern.info kernel: [278935.498806] wlan1: authenticate with ae:22:15:54:55:23
REMARKS: Extender re-try with remote hotspot ae:22: 15:54:55:23.
Mon Apr 26 05:06:12 2021 kern.info kernel: [278935.518303] wlan1: send auth to ae:22:15:54:55:23 (try 1/3)
Mon Apr 26 05:06:12 2021 kern.info kernel: [278935.526126] wlan1: authenticated
Mon Apr 26 05:06:12 2021 kern.info kernel: [278935.568617] wlan1: associate with ae:22:15:54:55:23 (try 1/3)
Mon Apr 26 05:06:12 2021 kern.info kernel: [278935.598611] wlan1: RX AssocResp from ae:22:15:54:55:23 (capab=0x1011 status=0 aid=7)
Mon Apr 26 05:06:12 2021 kern.info kernel: [278935.607281] wlan1: associated
Mon Apr 26 05:06:12 2021 daemon.notice netifd: Network device 'wlan1' link is up
Mon Apr 26 05:06:12 2021 daemon.notice netifd: Interface 'hotspot24' has link connectivity
REMARKS: Extender established physical handshaking with remote hotspot ae:22: 15:54:55:23.
Mon Apr 26 05:06:12 2021 daemon.notice netifd: Interface 'hotspot24' is setting up now
Mon Apr 26 05:06:12 2021 kern.debug kernel: [278935.845269] wlan1: Limiting TX power to 20 (20 - 0) dBm as advertised by ae:22:15:54:55:23
Mon Apr 26 05:06:12 2021 daemon.notice netifd: hotspot24 (11044): udhcpc: started, v1.25.1
Mon Apr 26 05:06:12 2021 daemon.notice netifd: hotspot24 (11044): udhcpc: sending discover
Mon Apr 26 05:06:15 2021 daemon.notice netifd: hotspot24 (11044): udhcpc: sending discover
Mon Apr 26 05:06:18 2021 daemon.notice netifd: hotspot24 (11044): udhcpc: sending discover
Mon Apr 26 05:06:34 2021 kern.info kernel: [278957.703056] wlan1: deauthenticating from ae:22:15:54:55:23 by local choice (Reason: 3=DEAUTH_LEAVING)
REMARKS: Remote hotspot did not provide IP address to extender. Extender will re-try if the Auto Recovery is disabled. Possible wireless link is not good or remote DHCP error.
Mon Apr 26 05:06:34 2021 daemon.notice netifd: Network device 'wlan1' link is down
Mon Apr 26 05:06:34 2021 daemon.notice netifd: Interface 'hotspot24' has link connectivity loss
Mon Apr 26 05:06:34 2021 daemon.notice netifd: hotspot24 (11044): udhcpc: received SIGTERM
Mon Apr 26 05:06:34 2021 daemon.notice netifd: Interface 'hotspot24' is now down
Mon Apr 26 05:06:34 2021 daemon.notice netifd: Interface 'hotspot24' is disabled
Mon Apr 26 05:06:34 2021 daemon.notice netifd: Interface 'hotspot24' is enabled
Mon Apr 26 05:06:36 2021 daemon.notice hostapd: handle_probe_req: send failed
Mon Apr 26 05:06:36 2021 daemon.notice hostapd: handle_probe_req: send failed
Mon Apr 26 05:06:46 2021 kern.info kernel: [278969.632387] wlan1: authenticate with 2a:35:d1:c3:8f:b1
Mon Apr 26 05:06:46 2021 kern.info kernel: [278969.651770] wlan1: send auth to 2a:35:d1:c3:8f:b1 (try 1/3)
Mon Apr 26 05:06:46 2021 kern.info kernel: [278969.662345] wlan1: authenticated
Mon Apr 26 05:06:46 2021 kern.info kernel: [278969.668742] wlan1: associate with 2a:35:d1:c3:8f:b1 (try 1/3)
Mon Apr 26 05:06:46 2021 kern.info kernel: [278969.789138] wlan1: associate with 2a:35:d1:c3:8f:b1 (try 2/3)
Mon Apr 26 05:06:46 2021 kern.info kernel: [278969.810126] wlan1: RX AssocResp from 2a:35:d1:c3:8f:b1 (capab=0x431 status=0 aid=1)
Mon Apr 26 05:06:46 2021 kern.info kernel: [278969.818506] wlan1: associated
REMARKS: Extender found another remote hotspot with same SSID, 2a:35:d1:c3:8f:b1.
Mon Apr 26 05:06:46 2021 daemon.notice netifd: Network device 'wlan1' link is up
Mon Apr 26 05:06:46 2021 daemon.notice netifd: Interface 'hotspot24' has link connectivity
Mon Apr 26 05:06:46 2021 daemon.notice netifd: Interface 'hotspot24' is setting up now
Mon Apr 26 05:06:46 2021 daemon.notice netifd: hotspot24 (11235): udhcpc: started, v1.25.1
Mon Apr 26 05:06:46 2021 daemon.notice netifd: hotspot24 (11235): udhcpc: sending discover
Mon Apr 26 05:06:46 2021 daemon.notice netifd: hotspot24 (11235): udhcpc: sending select for 10.43.24.144
Mon Apr 26 05:06:46 2021 daemon.notice netifd: hotspot24 (11235): udhcpc: lease of 10.43.24.144 obtained, lease time 3600
REMARKS: Remote hotspot assign 3600 seconds (60mins) of IP address. It means extender must authenticate with remote hotspot every 60 mins.
Mon Apr 26 05:06:47 2021 kern.info kernel: [278970.367379] br-my_network: port 3(wlan1-1) entered blocking state
Mon Apr 26 05:06:47 2021 kern.info kernel: [278970.373994] br-my_network: port 3(wlan1-1) entered listening state
Mon Apr 26 05:06:47 2021 daemon.notice netifd: Network device 'wlan1-1' link is up
Mon Apr 26 05:06:47 2021 daemon.notice netifd: Interface 'hotspot24' is now up
REMARKS: Extender finally connected to 2a:35:d1:c3:8f:b1 with assign IP address for 60 mins.