Turris Omnia: AP-STA-DISCONNECTED - just for one device both 2,4 and 5GHz

michalko58 · October 17, 2021, 6:27pm

Hi all,

First, sorry for long text. My device is Turris Omnia from Indiegogo, HBS branch.

I have bought new wiffi antennas from Aliexpress, exchanged them, and started to check if signal is better or worse than with original antennas. All seemed to be ok, but after few minutes I have noticed very strange wifi behaviour on my phone (Blackberry Key2).
It was unable to connect to 5GHz wifi, the 2,4 GHz wifi seemed to be ok. I thought, that antennas were piece of crap and went to back original antennas, connection was stable on that day.
But morning next day no wifi network was accessible for two phones (Key2 and One Plus Nord). After few minutes it went online without any intervention from my side. I have restarted router during that day and put there new antennas to check it again, all went fine, but during the evening, problem occured again for Key2 - unable to connect to any of my networks. All other devices (OnePlus Nord and notebook) connections were ok and stable.

I have tried:
Exchange of antennas - put there original antennas - no help
Restart of the phone, turn the phone on/off for several times, 32s reset of the phone - no help
Boot to safe mode on the phone - no help
Reset network setting on the phone - no help

In router system log, there was AP-STA-DISCONNECTED message for Key2. I have noticed in LuCI, that signal strenght was quite bad -112/114dB - other devices in the same room had around -89dB. Key2 was even unable to find my networks several times. It lasted for about 1 hour.

Today mornig, problem occured again, only for Key2 - unable to connect to any of my networks. System log for this case is attached below.

Can someone explain me, where could be the problem? Phone went form repair 2 weeks ago (keyboard) and wifi was stable until 2 days ago. Is it possible that ordinary little fall of the phone could cause antenna cable damage/disconnection inside the phone, or they did not properly connect the cable and it has disconnected?
If yes, why there was wifi failure for another phone?

I am frustrated due this all and thinking about to send phone back for repair…

Oct 17 06:19:10 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:19:12 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:19:12 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 1)
Oct 17 06:19:12 turris hostapd: wlan0: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:19:12 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 55A85CEB51728882
Oct 17 06:19:12 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:19:12 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:19:12 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:19:12 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:19:12 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:19:13 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:19:13 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:20:01 turris crond[1437]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:20:01 turris crond[1438]: (root) CMD (/usr/bin/notifier)
Oct 17 06:20:01 turris crond[1436]: (root) CMDOUT (There is no message to send.)
Oct 17 06:21:01 turris crond[1533]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:22:01 turris crond[1606]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:23:01 turris crond[1681]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:24:01 turris crond[1756]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:25:01 turris crond[1831]: (root) CMD (/usr/bin/notifier)
Oct 17 06:25:01 turris crond[1830]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:25:01 turris crond[1829]: (root) CMDOUT (There is no message to send.)
Oct 17 06:25:20 turris hostapd: wlan0: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:26:01 turris crond[1936]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:26:51 turris sentinel-dynfw-client[3297]: ipset v7.3: Error in line 1: Element cannot be deleted from the set: it's not added
Oct 17 06:26:51 turris sentinel-dynfw-client[3297]: 2021-10-17 08:26:51,574 - WARNING - Error running ipset command: return code 1.
Oct 17 06:27:01 turris crond[2030]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:27:02 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: did not acknowledge authentication response
Oct 17 06:27:09 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:27:09 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 17 06:27:09 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:27:09 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session CD9EA1E6319B6059
Oct 17 06:27:09 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:27:09 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:27:09 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:27:09 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:27:09 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:27:09 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:27:09 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:27:12 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:27:36 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:27:36 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 17 06:27:36 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:27:36 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 776CAB6F1821E0A9
Oct 17 06:27:36 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:27:36 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:27:36 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:27:36 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:27:36 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:27:36 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:27:36 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:27:39 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:28:01 turris crond[2119]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:28:03 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:28:03 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 17 06:28:03 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:28:03 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session E777A1A6277548FC
Oct 17 06:28:03 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:28:03 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:28:03 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:28:03 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:28:03 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:28:03 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:28:03 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:28:06 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:28:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:28:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 17 06:28:10 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:28:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session B42FC61C401C6EA6
Oct 17 06:28:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:28:10 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:28:10 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:28:10 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:28:10 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:28:10 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:28:10 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:28:13 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:28:17 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:28:17 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 17 06:28:17 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:28:17 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 7EC2C64A1A151018
Oct 17 06:28:17 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:28:17 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:28:17 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:28:17 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:28:17 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:28:17 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:28:17 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:28:20 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:29:01 turris crond[2226]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:30:01 turris crond[2298]: (root) CMD (/bin/sh -c "source /lib/functions/sentinel.sh; allowed_to_run "nikola" && exec sentinel-nikola --random-sleep")
Oct 17 06:30:01 turris crond[2300]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:30:01 turris crond[2299]: (root) CMD (/usr/bin/notifier)
Oct 17 06:30:01 turris crond[2297]: (root) CMDOUT (There is no message to send.)
Oct 17 08:30:41 turris syslog-ng[5364]: Configuration reload request received, reloading configuration;
Oct 17 08:30:41 turris syslog-ng[5364]: Configuration reload finished;
Oct 17 08:30:41 turris sentinel_nikola: Logrotate took 0.105562 seconds
Oct 17 06:30:41 turris crond[2296]: (root) CMDOUT (Logrotate took 0.105562 seconds)
Oct 17 08:30:41 turris sentinel_nikola: Syslog parsing took 0.095932 seconds
Oct 17 06:30:41 turris crond[2296]: (root) CMDOUT (Syslog parsing took 0.095932 seconds)
Oct 17 08:30:41 turris sentinel_nikola: Records parsed: 146
Oct 17 06:30:41 turris crond[2296]: (root) CMDOUT (Records parsed: 146)
Oct 17 08:30:41 turris sentinel_nikola: Sending records took 0.004254 seconds
Oct 17 06:30:41 turris crond[2296]: (root) CMDOUT (Sending records took 0.004254 seconds)
Oct 17 06:31:01 turris crond[2420]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:32:01 turris crond[2503]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:32:21 turris haas-proxy-start[6823]: 2021-10-17T08:32:21 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 17 06:33:01 turris crond[2576]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:34:01 turris crond[2653]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:35:01 turris crond[2724]: (root) CMD (/usr/bin/notifier)
Oct 17 06:35:01 turris crond[2725]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:35:01 turris crond[2723]: (root) CMDOUT (There is no message to send.)
Oct 17 06:35:20 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:35:20 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 17 06:35:20 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:35:20 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 741A6513A613B0F0
Oct 17 06:35:20 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:35:20 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:35:20 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:35:20 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:35:20 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:35:20 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:35:21 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:36:01 turris crond[2834]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:36:23 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:36:23 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 1)
Oct 17 06:36:23 turris hostapd: wlan0: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:36:23 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session F516F69CE1CDA15A
Oct 17 06:36:23 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:36:23 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:36:23 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:36:24 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:36:24 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:36:24 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:36:24 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:37:01 turris crond[2926]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:38:01 turris crond[2995]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:38:59 turris haas-proxy-start[6823]: 2021-10-17T08:38:59 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 17 06:39:01 turris crond[3065]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:40:01 turris crond[3143]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:40:01 turris crond[3144]: (root) CMD (/usr/bin/notifier)
Oct 17 06:40:01 turris crond[3142]: (root) CMDOUT (There is no message to send.)
Oct 17 06:41:01 turris crond[3241]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:41:40 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:41:40 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: disassociated due to inactivity
Oct 17 06:41:41 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Oct 17 06:42:01 turris crond[3310]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:43:01 turris crond[3382]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:44:01 turris crond[3452]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:45:01 turris crond[3525]: (root) CMD (/bin/sh -c "source /lib/functions/sentinel.sh; allowed_to_run "nikola" && exec sentinel-nikola --random-sleep")
Oct 17 06:45:01 turris crond[3528]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:45:01 turris crond[3526]: (root) CMD (/usr/bin/notifier)
Oct 17 06:45:01 turris crond[3524]: (root) CMDOUT (There is no message to send.)
Oct 17 06:45:42 turris haas-proxy-start[6823]: 2021-10-17T08:45:42 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 17 08:45:42 turris syslog-ng[5364]: Configuration reload request received, reloading configuration;
Oct 17 08:45:42 turris syslog-ng[5364]: Configuration reload finished;
Oct 17 08:45:42 turris sentinel_nikola: Logrotate took 0.104999 seconds
Oct 17 06:45:42 turris crond[3523]: (root) CMDOUT (Logrotate took 0.104999 seconds)
Oct 17 08:45:43 turris sentinel_nikola: Syslog parsing took 0.101448 seconds
Oct 17 06:45:43 turris crond[3523]: (root) CMDOUT (Syslog parsing took 0.101448 seconds)
Oct 17 08:45:43 turris sentinel_nikola: Records parsed: 155
Oct 17 06:45:43 turris crond[3523]: (root) CMDOUT (Records parsed: 155)
Oct 17 08:45:43 turris sentinel_nikola: Sending records took 0.004363 seconds
Oct 17 06:45:43 turris crond[3523]: (root) CMDOUT (Sending records took 0.004363 seconds)
Oct 17 06:46:01 turris crond[3649]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:46:19 turris hostapd: wlan0: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:46:19 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e IEEE 802.11: did not acknowledge authentication response
Oct 17 06:46:19 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 1)
Oct 17 06:46:19 turris hostapd: wlan0: Prune association for 88:9e:33:e8:6a:8e
Oct 17 06:46:19 turris hostapd: guest_turris_0: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:46:19 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 126C94FC6F7D1838
Oct 17 06:46:19 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:46:23 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-guest_turris) 88:9e:33:e8:6a:8e 
Oct 17 08:46:23 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-guest_turris) 10.111.222.175 88:9e:33:e8:6a:8e 
Oct 17 08:46:25 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-guest_turris) 88:9e:33:e8:6a:8e 
Oct 17 08:46:25 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-guest_turris) 10.111.222.175 88:9e:33:e8:6a:8e 
Oct 17 06:46:48 turris hostapd: guest_turris_0: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:46:48 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e IEEE 802.11: did not acknowledge authentication response
Oct 17 06:46:49 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Oct 17 06:47:01 turris crond[3723]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:48:01 turris crond[3805]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:49:01 turris crond[3877]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:50:01 turris crond[3953]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:50:01 turris crond[3952]: (root) CMD (/usr/bin/notifier)
Oct 17 06:50:01 turris crond[3951]: (root) CMDOUT (There is no message to send.)
Oct 17 06:50:32 turris haas-proxy-start[6823]: 2021-10-17T08:50:32 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 17 06:51:01 turris crond[4052]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:51:19 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Oct 17 06:51:23 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: did not acknowledge authentication response
Oct 17 06:52:01 turris crond[4124]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:52:54 turris updater-supervisor: Running pkgupdate
Oct 17 06:52:56 turris updater[4188]: repository.lua.lua:49 (Globals): Target Turris OS: 5.2.7
Oct 17 06:53:01 turris crond[4198]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package luci-i18n-sqm-sk that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package luci-i18n-rainbow-sk that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package reforis-data-collection-plugin-l10n-sk that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package reforis-haas-plugin-l10n-sk that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package reforis-netmetr-plugin-l10n-sk that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package reforis-diagnostics-plugin-l10n-sk that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package reforis-snapshots-plugin-l10n-sk that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package luci-i18n-rainbow-en that is missing, ignoring as requested.
Oct 17 06:53:04 turris updater[4188]: planner.lua:356 (pkg_plan): Requested package luci-i18n-sqm-en that is missing, ignoring as requested.
Oct 17 06:53:05 turris updater-supervisor: pkgupdate reported no errors
Oct 17 06:54:01 turris crond[4297]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:54:04 turris haas-proxy-start[6823]: 2021-10-17T08:54:04 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 17 06:55:01 turris crond[4365]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:55:01 turris crond[4366]: (root) CMD (/usr/bin/notifier)
Oct 17 06:55:01 turris crond[4364]: (root) CMDOUT (There is no message to send.)
Oct 17 06:55:48 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:55:48 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 17 06:55:48 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:55:48 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 41CEF664E9A2A7B7
Oct 17 06:55:48 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:55:48 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 17 08:55:48 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:55:48 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 17 08:55:48 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 17 06:55:48 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 17 06:55:48 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:56:01 turris crond[4497]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:56:23 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Oct 17 06:57:01 turris crond[4566]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:57:04 turris sentinel-dynfw-client[3297]: ipset v7.3: Error in line 1: Element cannot be deleted from the set: it's not added
Oct 17 06:57:04 turris sentinel-dynfw-client[3297]: 2021-10-17 08:57:04,516 - WARNING - Error running ipset command: return code 1.
Oct 17 06:57:49 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:57:49 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:58:01 turris crond[4640]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:59:01 turris crond[4729]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 17 06:59:33 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 17 06:59:33 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 1)
Oct 17 06:59:33 turris hostapd: guest_turris_0: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 17 06:59:33 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 0CC0F5B52076B313
Oct 17 06:59:33 turris hostapd: guest_turris_0: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 17 08:59:36 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-guest_turris) 88:9e:33:e8:6a:8e 
Oct 17 08:59:36 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-guest_turris) 10.111.222.175 88:9e:33:e8:6a:8e 
Oct 17 08:59:37 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-guest_turris) 88:9e:33:e8:6a:8e 
Oct 17 08:59:37 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-guest_turris) 10.111.222.175 88:9e:33:e8:6a:8e 
Oct 17 08:59:37 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-guest_turris) 10.111.222.175 88:9e:33:e8:6a:8e 
Oct 17 08:59:37 turris dnsmasq-dhcp[17519]: DHCPACK(br-guest_turris) 10.111.222.175 88:9e:33:e8:6a:8e android-14861d96eacaa2c0
Oct 17 06:59:37 turris /dhcp_host_domain_ng.py: DHCP remove hostname [Key2,192.168.1.175]
Oct 17 06:59:37 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 17 06:59:38 turris /dhcp_host_domain_ng.py: DHCP add new hostname [android-14861d96eacaa2c0,10.111.222.175]
Oct 17 06:59:38 turris /dhcp_host_domain_ng.py: Refresh kresd leases

.

AreYouLoco · October 18, 2021, 2:28pm

Please check the signal strenght with WifiAnalyzer app. Maybe you have loose antenna connections?

michalko58 · October 18, 2021, 6:34pm

Can you give me advice which one of plenty of wifi analyzers for Android is most accurate? I have downloaded this one: WiFi Analyzer (open-source)

Signal strenght in phone varies from-81dBm to -88dBm for 5GHz network and about -73dBm for 2,4GHz (LuCI shows -91/91dBm for 5GHz - with original antennas. There were many disconnections again.

Oct 18 17:49:24 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:49:31 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: did not acknowledge authentication response
Oct 18 17:49:44 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 18 17:49:44 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 18 17:49:44 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:49:44 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session B9700F293496C2A9
Oct 18 17:49:44 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 18 19:49:44 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 18 19:49:44 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:49:44 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:49:44 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 18 17:49:45 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 17:49:45 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:49:49 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:49:58 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 18 17:49:58 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 18 17:49:58 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:49:58 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session F5B865C3BEE23435
Oct 18 17:49:58 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 18 19:49:58 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 18 19:49:58 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:49:58 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:49:58 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 18 17:49:59 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 17:49:59 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:50:01 turris crond[12420]: (root) CMD (/usr/bin/notifier)
Oct 18 17:50:01 turris crond[12430]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:50:01 turris crond[12419]: (root) CMDOUT (There is no message to send.)
Oct 18 17:50:05 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:50:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 18 17:50:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 18 17:50:10 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:50:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 27E9ABECA6F15CE5
Oct 18 17:50:10 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 18 19:50:11 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 18 19:50:11 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:50:11 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:50:11 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 18 17:50:11 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 17:50:11 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:50:14 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:50:16 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 18 17:50:16 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 18 17:50:16 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 18 17:50:16 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session CCE3604E5CD2EC12
Oct 18 17:50:16 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 18 19:50:16 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 18 19:50:16 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:50:16 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 19:50:16 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 18 17:50:17 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 17:50:17 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:01 turris crond[12550]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:51:19 turris hostapd: wlan1: STA 94:e6:f7:2d:76:bb IEEE 802.11: authenticated
Oct 18 17:51:19 turris hostapd: wlan1: STA 94:e6:f7:2d:76:bb IEEE 802.11: associated (aid 3)
Oct 18 17:51:19 turris hostapd: wlan1: STA-OPMODE-SMPS-MODE-CHANGED 94:e6:f7:2d:76:bb off
Oct 18 19:51:19 turris kernel: [196289.573261] ath10k_pci 0000:02:00.0: NIC rx-max-rate: 0 calculated-max: 0 rxnss_override: 0x80000000  nss160: 1  spatial-streams: 2
Oct 18 17:51:19 turris hostapd: wlan1: AP-STA-CONNECTED 94:e6:f7:2d:76:bb
Oct 18 17:51:19 turris hostapd: wlan1: STA 94:e6:f7:2d:76:bb RADIUS: starting accounting session 4B231666EC3978F6
Oct 18 17:51:19 turris hostapd: wlan1: STA 94:e6:f7:2d:76:bb WPA: pairwise key handshake completed (RSN)
Oct 18 19:51:20 turris dnsmasq-dhcp[17519]: read /etc/ethers - 0 addresses
Oct 18 17:51:20 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:20 turris /dhcp_host_domain_ng.py: DHCP update hostname [shellyflood-7657E6,192.168.1.234]
Oct 18 17:51:20 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:20 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 17:51:20 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:21 turris /dhcp_host_domain_ng.py: DHCP update hostname [OnePlus-Nord,192.168.1.127]
Oct 18 17:51:21 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:21 turris /dhcp_host_domain_ng.py: DHCP update hostname [55oled854,192.168.1.224]
Oct 18 17:51:21 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:21 turris /dhcp_host_domain_ng.py: DHCP update hostname [Philips-hue,192.168.1.171]
Oct 18 17:51:21 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 19:51:23 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 192.168.1.177 94:e6:f7:2d:76:bb 
Oct 18 19:51:23 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.177 94:e6:f7:2d:76:bb 
Oct 18 19:51:23 turris dnsmasq-dhcp[17519]: read /etc/ethers - 0 addresses
Oct 18 19:51:23 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.177 94:e6:f7:2d:76:bb 
Oct 18 19:51:23 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.177 94:e6:f7:2d:76:bb VN7-592G
Oct 18 17:51:23 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:23 turris /dhcp_host_domain_ng.py: DHCP update hostname [shellyflood-7657E6,192.168.1.234]
Oct 18 17:51:23 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: DHCP update hostname [OnePlus-Nord,192.168.1.127]
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: DHCP update hostname [55oled854,192.168.1.224]
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: DHCP update hostname [Philips-hue,192.168.1.171]
Oct 18 17:51:24 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:51:25 turris /dhcp_host_domain_ng.py: DHCP add new hostname [VN7-592G,192.168.1.177]
Oct 18 17:51:25 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 17:52:01 turris haas-proxy-start[6823]: 2021-10-18T19:52:01 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 18 17:52:01 turris crond[12782]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:53:01 turris crond[12853]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:54:01 turris crond[12976]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:54:31 turris hostapd: wlan0: STA 88:9e:33:e8:6a:8e IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Oct 18 17:55:01 turris crond[13122]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:55:01 turris crond[13124]: (root) CMD (/usr/bin/notifier)
Oct 18 17:55:01 turris crond[13121]: (root) CMDOUT (There is no message to send.)
Oct 18 17:56:01 turris crond[13267]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:57:01 turris crond[13339]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:58:01 turris crond[13425]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 17:58:47 turris haas-proxy-start[6823]: 2021-10-18T19:58:47 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 18 17:59:01 turris crond[13501]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 18:00:01 turris crond[13571]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 18:00:01 turris crond[13574]: (root) CMD (netmetr --autostart --dwlhist --rwait 1800 &)
Oct 18 18:00:01 turris crond[13575]: (root) CMD (/bin/sh -c "source /lib/functions/sentinel.sh; allowed_to_run "nikola" && exec sentinel-nikola --random-sleep")
Oct 18 18:00:01 turris crond[13573]: (root) CMD (/usr/bin/notifier)
Oct 18 18:00:01 turris crond[13572]: (root) CMD (/usr/bin/updater-supervisor -d --autorun --rand-sleep --no-network-fail)
Oct 18 18:00:01 turris crond[13570]: (root) CMDOUT (There is no message to send.)
Oct 18 18:00:03 turris updater-supervisor: Suspending updater start for 3193 seconds
Oct 18 20:00:36 turris syslog-ng[5364]: Configuration reload request received, reloading configuration;
Oct 18 20:00:36 turris syslog-ng[5364]: Configuration reload finished;
Oct 18 20:00:36 turris sentinel_nikola: Logrotate took 0.104854 seconds
Oct 18 18:00:36 turris crond[13567]: (root) CMDOUT (Logrotate took 0.104854 seconds)
Oct 18 20:00:36 turris sentinel_nikola: Syslog parsing took 0.092438 seconds
Oct 18 18:00:36 turris crond[13567]: (root) CMDOUT (Syslog parsing took 0.092438 seconds)
Oct 18 20:00:36 turris sentinel_nikola: Records parsed: 138
Oct 18 18:00:36 turris crond[13567]: (root) CMDOUT (Records parsed: 138)
Oct 18 20:00:36 turris sentinel_nikola: Sending records took 0.003839 seconds
Oct 18 18:00:36 turris crond[13567]: (root) CMDOUT (Sending records took 0.003839 seconds)
Oct 18 18:01:01 turris crond[13735]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 18:01:23 turris haas-proxy-start[6823]: 2021-10-18T20:01:23 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 18 18:02:01 turris crond[13822]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 18:03:01 turris crond[13896]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 18:03:51 turris haas-proxy-start[6823]: 2021-10-18T20:03:51 CRITICAL twisted 'channel open failed, direct-tcpip is not allowed'
Oct 18 18:03:53 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 18 18:03:55 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 18 18:03:55 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 18 18:03:55 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 18 18:03:55 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 181E048F13DE1BB3
Oct 18 18:03:55 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 18 20:03:55 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 18 20:03:55 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 20:03:55 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 20:03:55 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 18 18:03:55 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 18:03:55 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 18:03:58 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 18 18:04:01 turris crond[13980]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
Oct 18 18:04:01 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 18 18:04:01 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 18 18:04:01 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 18 18:04:01 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 204D1B6076458EB0
Oct 18 18:04:01 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 18 20:04:01 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 18 20:04:01 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 20:04:01 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 20:04:01 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 18 18:04:01 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 18:04:01 turris /dhcp_host_domain_ng.py: Refresh kresd leases
Oct 18 18:04:04 turris hostapd: wlan1: AP-STA-DISCONNECTED 88:9e:33:e8:6a:8e
Oct 18 18:04:07 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: authenticated
Oct 18 18:04:07 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e IEEE 802.11: associated (aid 2)
Oct 18 18:04:07 turris hostapd: wlan1: AP-STA-CONNECTED 88:9e:33:e8:6a:8e
Oct 18 18:04:07 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e RADIUS: starting accounting session 4B59CAC250C165C2
Oct 18 18:04:07 turris hostapd: wlan1: STA 88:9e:33:e8:6a:8e WPA: pairwise key handshake completed (RSN)
Oct 18 20:04:08 turris dnsmasq-dhcp[17519]: DHCPDISCOVER(br-lan) 88:9e:33:e8:6a:8e 
Oct 18 20:04:08 turris dnsmasq-dhcp[17519]: DHCPOFFER(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 20:04:08 turris dnsmasq-dhcp[17519]: DHCPREQUEST(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e 
Oct 18 20:04:08 turris dnsmasq-dhcp[17519]: DHCPACK(br-lan) 192.168.1.175 88:9e:33:e8:6a:8e Key2
Oct 18 18:04:08 turris /dhcp_host_domain_ng.py: DHCP update hostname [Key2,192.168.1.175]
Oct 18 18:04:08 turris /dhcp_host_domain_ng.py: Refresh kresd leases

It is very strange behaviour, because no settings were changed (in the phone or router) and that phone worked fine until friday in that place.

When I enter service menu in the phone and check wifi networks, it is able to find them normally (images 1,2, 3rd image is from wifi analyzer). So, maybe its wifi cable/antenna is diconnected only partially?

michalko58 · October 19, 2021, 6:02pm

So did reset of wifi settings on Omnia and full phone reset, problem still persists. Seems that something is broken, will send the phone for repair again.

michalko58 · November 10, 2021, 5:34am

Update:

Phone went back from service centre, they said everything was OK according to their tests, but problem still persisted.

After clean install of TOS 5.2.7, (and now update to 5.3) it is much better, so it seems that there was some conflict between Omnia and phone. There are still occasional dropouts, but not as bat than before.