This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

lost connections after 9.301-2

Hello:

Since 9.301-2 wireless clients lose their DHCP assignment.
(both local and external DHCP server)

They show a strong signal connection to the correct SSID but are not assigned an IP.

Restarting the box corrects this.
It appears that going into WebAdmin and "mucking about" in the Wireless section brings this back as well.

(Seems to happen a few times a day)

Next occurrence, I will try to see what minimal action brings it back.

   Tom


This thread was automatically locked due to age.
  • I'm not seeing this, Tom.  What is in the DHCP Server log when this occurred?

    Cheers - Bob
     
    Sophos UTM Community Moderator
    Sophos Certified Architect - UTM
    Sophos Certified Engineer - XG
    Gold Solution Partner since 2005
    MediaSoft, Inc. USA
  • No DHCP errors.

    However the wireless log show a restart every hour....

    2014:11:17-15:00:57 10.0.100.36 kernel: [ 3564.960000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:17-15:00:57 10.0.100.36 kernel: [ 3564.980000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:17-15:00:57 10.0.100.36 kernel: [ 3565.000000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:17-15:00:57 10.0.100.36 kernel: [ 3565.020000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:17-15:00:57 10.0.100.36 kernel: [ 3565.040000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:17-15:01:28 net awed[4556]: [MASTER] new connection from 10.0.100.36:50491
    2014:11:17-15:01:28 net awed[19017]: [A4000CB9C93FB25] AP30 from 10.0.100.36:50491 identified as A4000CB9C93FB25
    2014:11:17-15:01:28 net awed[4556]: [MASTER] new connection from 10.0.100.26:51793
    2014:11:17-15:01:28 net awed[17170]: [A4000CB9C93FB25] ll_read: dead socket: Interrupted system call
    2014:11:17-15:01:28 net awed[17170]: [A4000CB9C93FB25] disconnected. Close socket and kill process.
    2014:11:17-15:01:28 net awed[19017]: [A4000CB9C93FB25] (Re-)loaded identity and/or configuration
    2014:11:17-15:01:28 net awed[19018]: [A4000B9454EAE41] AP30 from 10.0.100.26:51793 identified as A4000B9454EAE41
    2014:11:17-15:01:28 net awed[19018]: [A4000B9454EAE41] (Re-)loaded identity and/or configuration
    2014:11:13-07:19:56 10.0.100.36 logread[821]: Logread connected to 10.0.100.1:415
    2014:11:13-07:19:56 10.0.100.26 logread[821]: Logread connected to 10.0.100.1:415
    2014:11:17-15:01:31 net awed[6725]: [A4000B9454EAE41] ll_read: dead socket: Interrupted system call
    2014:11:17-15:01:31 net awed[6725]: [A4000B9454EAE41] disconnected. Close socket and kill process.
    2014:11:13-07:19:59 10.0.100.36 netifd: Interface 'vxlan' is enabled
    2014:11:13-07:19:59 10.0.100.36 netifd: Network device 'vxlan' link is up
    2014:11:13-07:19:59 10.0.100.36 netifd: Interface 'vxlan' has link connectivity 
    2014:11:13-07:19:59 10.0.100.36 netifd: Interface 'vxlan' is setting up now
    2014:11:13-07:19:59 10.0.100.36 netifd: Interface 'vxlan' is now up
    2014:11:13-07:19:59 10.0.100.36 netifd: VLAN 'vxlan.101' link is up
    2014:11:13-07:19:59 10.0.100.36 netifd: Interface 'vxlan101' is enabled
    .

    The real date is 2014:11:15, but it seems to fall back to :13 on the reset.
    This appears to be happening exactly every hour.

    This started immediately after the upgrade.

       Tom
  • I suspect that this represents a failed Up2Date.  If this is a unit with a paid license, Tom, please get Sophos Support involved.  If it's a home-use situation, please share the output of version at the command line.

    Cheers - Bob
     
    Sophos UTM Community Moderator
    Sophos Certified Architect - UTM
    Sophos Certified Engineer - XG
    Gold Solution Partner since 2005
    MediaSoft, Inc. USA
  • Exactly the same annoying effect here.
    Did you find a solution?
    We reinstalled the device and went back to 9.209008.

    Best
    Michael
  • exactly the same Problem here since 9.301-2

    The Wireless Clients try to connect but didnt get an IP Adress. We've a Guest Wireless Network using the UTM DHCP Server and an internal Wireless Network which provides a DHCP Adress over the domains DHCP controller. Both Wireless Networks have the problem.

    It seems to be AP individual. A Restart of the AP fixes the problem for a few minutes or hours. Even klicking around in the wireless section seems to bring the functionality back.

    We use ten sophos AP 10 and every AP has the Problem. A restart of the UTM didnt change anything.

    The Output in the Log is the same as above.

    Wireless Security Log:
    2014:11:20-16:00:09 172.16.10.142 hostapd: wlan1: STA b0:34:95:24:49:ac IEEE 802.11: authenticated
    
    2014:11:20-16:00:09 172.16.10.142 hostapd: wlan1: STA b0:34:95:24:49:ac IEEE 802.11: associated (aid 2)
    2014:11:20-16:00:09 172.16.10.142 hostapd: wlan1: STA b0:34:95:24:49:ac WPA: pairwise key handshake completed (RSN)
    2014:11:20-16:00:09 172.16.10.142 awelogger[1194]: id="4103" severity="info" sys="System" sub="WiFi" name="STA authentication" ssid="Gastzugang" ssid_id="WLAN1.0" bssid="00:1a:8c:0e:0c:c1" sta="b0:34:95:24:49:ac" status_code="0"
    2014:11:20-16:00:09 172.16.10.142 awelogger[1194]: id="4104" severity="info" sys="System" sub="WiFi" name="STA association" ssid="" ssid_id="WLAN1.0" bssid="00:1a:8c:0e:0c:c1" sta="b0:34:95:24:49:ac" status_code="0"
    2014:11:20-16:00:09 172.16.10.142 awelogger[1194]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="Gastzugang" ssid_id="WLAN1.0" bssid="00:1a:8c:0e:0c:c1" sta="b0:34:95:24:49:ac"
    2014:11:20-16:00:16 172.16.10.49 kernel: [93830.790000] ieee80211 phy0: rt2x00queue_flush_queue: Warning - Queue 2 failed to flush
    2014:11:20-16:00:17 172.16.10.49 kernel: [93830.990000] ieee80211 phy0: rt2x00queue_flush_queue: Warning - Queue 2 failed to flush
    2014:11:20-16:00:24 172.16.10.142 hostapd: wlan1: STA b0:34:95:24:49:ac IEEE 802.11: disassociated


    2014:11:20-16:14:07 172.16.10.142 awelogger[1194]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="Gastzugang" ssid_id="WLAN1.0" bssid="00:1a:8c:0e:0c:c1" sta="b0:34:95:24:49:ac"
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.560000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.580000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.600000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.620000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.640000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.660000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.680000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.700000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.710000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:20-16:14:10 172.16.10.142 kernel: [88096.730000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2


    Internal DHCP Server Log:
    2014:11:20-16:14:30 ibb-mail01 dhcpd: DHCPDISCOVER from 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    
    2014:11:20-16:14:30 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.203 to 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:32 ibb-mail01 dhcpd: DHCPDISCOVER from 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:32 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.203 to 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:36 ibb-mail01 dhcpd: DHCPDISCOVER from 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:36 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.203 to 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:45 ibb-mail01 dhcpd: DHCPDISCOVER from 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:45 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.203 to 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:53 ibb-mail01 dhcpd: DHCPDISCOVER from 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:14:53 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.203 to 2c:f0:ee:e0[:D]9:71 (iPhone-MWalter) via wlan1
    2014:11:20-16:15:15 ibb-mail01 dhcpd: DHCPREQUEST for 192.168.1.225 from 98:fe:94:3c[:D]1:f5 via wlan1
    2014:11:20-16:15:15 ibb-mail01 dhcpd: DHCPACK on 192.168.1.225 to 98:fe:94:3c[:D]1:f5 via wlan1
    2014:11:20-16:15:21 ibb-mail01 dhcpd: DHCPDISCOVER from 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:22 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.114 to 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:23 ibb-mail01 dhcpd: DHCPDISCOVER from 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:23 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.114 to 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:25 ibb-mail01 dhcpd: DHCPDISCOVER from 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:25 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.114 to 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:30 ibb-mail01 dhcpd: DHCPDISCOVER from 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:30 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.114 to 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:38 ibb-mail01 dhcpd: DHCPDISCOVER from 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:38 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.114 to 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:46 ibb-mail01 dhcpd: DHCPDISCOVER from 90:b9:31:81:74:fe (iPhone-Florian) via wlan1
    2014:11:20-16:15:46 ibb-mail01 dhcpd: DHCPOFFER on 192.168.1.114 to 90:b9:31:81:74:fe (iPhone-Florian) via wlan1


    Version Output

    Current software version...: 9.301002
    Hardware type..............: 320r5
    Serial number..............: ***************x
    Installation image.........: 8.305-3.1
    Installation type..........: msi
    Installed pattern version..: 70563
    Downloaded pattern version.: 70563
    Up2Dates applied...........: 32 (see below)
                                 sys-8.305-8.306-1.25.1.tgz (Sep 22  2012)
                                 sys-8.306-8.307-25.5.1.tgz (Dec 11  2012)
                                 sys-8.307-8.308-5.4.1.tgz (Dec 11  2012)
                                 sys-8.308-9.004-3.33.1.tgz (Dec 11  2012)
                                 sys-9.004-9.004-33.34.1.tgz (Jan  9  2013)
                                 sys-9.004-9.005-29.15.2.tgz (Apr  2  2013)
                                 sys-9.005-9.005-15.16.1.tgz (Apr  2  2013)
                                 sys-9.005-9.006-15.5.2.tgz (May 22  2013)
                                 sys-9.006-9.100-5.16.1.tgz (May 22  2013)
                                 sys-9.100-9.101-16.12.1.tgz (Jun 15  2013)
                                 sys-9.101-9.102-11.8.2.tgz (Jul  1  2013)
                                 sys-9.102-9.103-8.5.2.tgz (Jul 15  2013)
                                 sys-9.103-9.104-5.17.2.tgz (Aug 23  2013)
                                 sys-9.104-9.105-17.9.1.tgz (Aug 23  2013)
                                 sys-9.105-9.106-9.17.1.tgz (Oct  2  2013)
                                 sys-9.106-9.107-17.33.2.tgz (Jan  1  2014)
                                 sys-9.107-9.108-33.23.2.tgz (Feb 11  2014)
                                 sys-9.108-9.109-23.1.2.tgz (Mar 10  2014)
                                 sys-9.109-9.110-1.22.1.tgz (Apr 22  2014)
                                 sys-9.110-9.111-22.7.1.tgz (Apr 23  2014)
                                 sys-9.111-9.111-7.11.1.tgz (Jul  4 08:19)
                                 sys-9.111-9.112-7.12.1.tgz (Jul  4 08:21)
                                 sys-9.112-9.113-12.1.2.tgz (Jul  4 08:21)
                                 sys-9.113-9.203-1.3.1.tgz (Jul 20 00:05)
                                 sys-9.203-9.204-3.20.1.tgz (Jul 24 00:02)
                                 sys-9.204-9.205-20.12.1.tgz (Oct  9 01:00)
                                 sys-9.205-9.206-12.35.1.tgz (Oct  9 01:02)
                                 sys-9.206-9.207-35.19.2.tgz (Oct  9 01:03)
                                 sys-9.207-9.208-19.8.5.tgz (Oct 11 01:07)
                                 sys-9.208-9.209-8.8.1.tgz (Nov  1 00:01)
                                 sys-9.209-9.300-8.5.1.tgz (Nov 17 23:02)
                                 sys-9.300-9.301-5.2.3.tgz (Nov 17 23:03)
    Up2Dates available.........: 0
    Factory resets.............: 0
    Timewarps detected.........: 0
  • Apparently there is a fix for this but support has to install it.  Should be rolled out soon I would think.
  • Support is looking in to this.

    No joy yet.

       Tom
  • I allso have exactly the same error since 9.301-2

    2014:11:21-16:56:30 192.168.1.20 kernel: [65026.270000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.20 kernel: [65026.290000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.20 kernel: [65026.300000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.20 kernel: [65026.320000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.21 kernel: [104096.680000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.21 kernel: [104096.700000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.21 kernel: [104096.720000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.21 kernel: [104096.740000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:56:30 192.168.1.21 kernel: [104096.750000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-16:58:48 192.168.1.21 hostapd: wlan0: STA 00:04:20:29[:D]4:57 WPA: group key handshake completed (RSN)
    2014:11:21-16:58:48 192.168.1.21 hostapd: wlan0: STA 18:34:51[:D]f:c9:1b WPA: group key handshake completed (RSN)
    2014:11:21-16:58:49 192.168.1.21 hostapd: wlan0: STA a8:88:08:c6:e3[:D]0 WPA: group key handshake completed (RSN)
    2014:11:21-17:01:50 192.168.1.21 awelogger[11487]: id="4103" severity="info" sys="System" sub="WiFi" name="STA authentication" ssid="ECKEROTH" ssid_id="WLAN0.0" bssid="00:1a:8c:09:59:40" sta="0c:84[:D]c:78:6f:a9" status_code="0"
    2014:11:21-17:01:50 192.168.1.21 hostapd: wlan0: STA 0c:84[:D]c:78:6f:a9 IEEE 802.11: authenticated
    2014:11:21-17:01:50 192.168.1.21 hostapd: wlan0: STA 0c:84[:D]c:78:6f:a9 IEEE 802.11: associated (aid 3)
    2014:11:21-17:01:50 192.168.1.21 hostapd: wlan0: STA 0c:84[:D]c:78:6f:a9 WPA: pairwise key handshake completed (RSN)
    2014:11:21-17:01:50 192.168.1.21 awelogger[11487]: id="4104" severity="info" sys="System" sub="WiFi" name="STA association" ssid="ECKEROTH" ssid_id="WLAN0.0" bssid="00:1a:8c:09:59:40" sta="0c:84[:D]c:78:6f:a9" status_code="0"
    2014:11:21-17:01:50 192.168.1.21 awelogger[11487]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="ECKEROTH" ssid_id="WLAN0.0" bssid="00:1a:8c:09:59:40" sta="0c:84[:D]c:78:6f:a9"
    2014:11:21-17:01:50 192.168.1.20 kernel: [65346.370000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-17:01:50 192.168.1.20 kernel: [65346.390000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-17:01:50 192.168.1.20 kernel: [65346.410000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-17:01:58 192.168.1.21 hostapd: wlan0: STA 0c:84[:D]c:78:6f:a9 IEEE 802.11: disassociated
    2014:11:21-17:01:58 192.168.1.21 awelogger[11487]: id="4102" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="ECKEROTH" ssid_id="WLAN0.0" bssid="00:1a:8c:09:59:40" sta="0c:84[:D]c:78:6f:a9"
    2014:11:21-17:01:59 192.168.1.21 hostapd: wlan0: STA 0c:84[:D]c:78:6f:a9 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
  • We upgraded to 9.301 last night, and today our AP's have problems.  They are disconnecting all clients, and then reconnecting.  Doesn't seem to be any pattern to it.

    Looking in our wireless log files isn't giving us much clue, but we do see some of the same errors:

    2014:11:21-11:20:23 192.168.84.45 kernel: [   58.010000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    
    2014:11:21-11:20:23 192.168.84.45 kernel: [   58.030000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-11:20:23 192.168.84.45 kernel: [   58.040000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-11:20:23 192.168.84.45 kernel: [   58.060000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
    2014:11:21-11:20:23 192.168.84.45 kernel: [   58.080000] ieee80211 phy0: rt2x00queue_write_tx_frame: Error - Dropping frame due to full tx queue 2
  • Support has logged in to look at the problem a few days ago but I have no word of a solution. 

    Did none of the beta testers use wireless? 

    Good thing I did an internal test before rolling this out to customers, or there would be a lot of unhappy campers!

    Should this update be pulled?

        Tom