Wireless stops working; soft hang

Networking
1

I have started testing the ipfire 64 bit version (latest core, 144) in a VM. I have set it up to use hostapd. It was working well for about a day or so, then suddenly the wireless (blue0) died on me; the ESSID no longer shows up in the wireless APs. The wireless is a USB dongle. The same arrangements work fairly faithfully in the 32 bit version as well. Sometimes a reset is required on the 32 bit version.

The 64 bit version seems to be all verklempt. Not only is blue0 no longer working, the web interface took forever to shut down the blue0 so I could restart it (hoping that would correct whatever the problem is). When I tried to investigate by logging in on a console, the environment seems to be completely awry. I attempted to type a command and the shell hung. It would not break out with ^C or even ^Z.

I was able to copy the messages log by scp, but this lead to problems. I opened a different console, but that console quickly got messed up also, with the login prompt suddenly appearing in the shell! It was as if there were 2 different sets of the normal I/O streams attached to the same shell (or its tty). I was unable to type complete commands there. Some of the characters were echoed; others were gobbled up to who knows where.

I was able to do a df(1) and I can see there is no disk space problem. I was also able to clip an interesting segment of /var/log/messages, below (note that the first few lines are present just to give some context):

May  1 09:36:36 ipfire64 kernel: DROP_NEWNOTSYN IN=blue0 OUT=red0 MAC=08:86:3b:02:30:97:e4:e1:30:09:dc:be:08:00 SRC=192.168.50.20 DST=77.88.21.207 LEN=83 TOS=0x00 PREC=0x00 TTL=63 ID=10017 DF PROTO=TCP SPT=48112 DPT=443 WINDOW=394 RES=0x00 ACK PSH URGP=0 
May  1 09:38:05 ipfire64 kernel: DROP_NEWNOTSYN IN=blue0 OUT=red0 MAC=08:86:3b:02:30:97:d0:c5:d3:b2:91:4d:08:00 SRC=192.168.50.21 DST=104.77.8.16 LEN=83 TOS=0x00 PREC=0x00 TTL=63 ID=7403 DF PROTO=TCP SPT=60062 DPT=443 WINDOW=364 RES=0x00 ACK PSH URGP=0 
May  1 09:38:20 ipfire64 kernel: DROP_NEWNOTSYN IN=blue0 OUT=red0 MAC=08:86:3b:02:30:97:d0:c5:d3:b2:91:4d:08:00 SRC=192.168.50.21 DST=69.192.108.20 LEN=83 TOS=0x00 PREC=0x00 TTL=63 ID=20833 DF PROTO=TCP SPT=39570 DPT=443 WINDOW=364 RES=0x00 ACK PSH URGP=0 
May  1 09:55:04 ipfire64 dhcpd: DHCPREQUEST for 192.168.50.21 from d0:c5:d3:b2:91:4d (laptop-devuan) via blue0
May  1 09:55:04 ipfire64 dhcpd: DHCPACK on 192.168.50.21 to d0:c5:d3:b2:91:4d (laptop-devuan) via blue0
May  1 09:55:05 ipfire64 kernel: DROP_NEWNOTSYN IN=blue0 OUT=red0 MAC=08:86:3b:02:30:97:e4:e1:30:09:dc:be:08:00 SRC=192.168.50.20 DST=77.88.21.207 LEN=83 TOS=0x00 PREC=0x00 TTL=63 ID=29728 DF PROTO=TCP SPT=48144 DPT=443 WINDOW=394 RES=0x00 ACK PSH URGP=0 
May  1 09:58:17 ipfire64 kernel: rtl_usb: Urb has error status 0xFFFFFFB9
May  1 09:58:18 ipfire64 kernel: usb 1-2: USB disconnect, device number 3
May  1 09:58:18 ipfire64 kernel: rtl_usb: reg 0xe08, usbctrl_vendorreq TimeOut! status:0xffffffed value=0xd3c000
May  1 09:58:18 ipfire64 kernel: rtl_usb: reg 0x86c, usbctrl_vendorreq TimeOut! status:0xffffffed value=0xd30000
May  1 09:58:18 ipfire64 kernel: rtl_usb: reg 0x86c, usbctrl_vendorreq TimeOut! status:0xffffffed value=0x80390004
May  1 09:58:18 ipfire64 kernel: rtl_usb: reg 0x838, usbctrl_vendorreq TimeOut! status:0xffffffed value=0x1000100
May  1 09:58:18 ipfire64 dhcpd: receive_packet failed on blue0: Network is down
May  1 09:58:18 ipfire64 kernel: usb 1-2: new high-speed USB device number 5 using xhci_hcd
May  1 09:58:18 ipfire64 hostapd: blue0: STA d0:c5:d3:b2:91:4d MLME: MLME-DEAUTHENTICATE.indication(d0:c5:d3:b2:91:4d, 1)
May  1 09:58:18 ipfire64 hostapd: blue0: STA d0:c5:d3:b2:91:4d MLME: MLME-DELETEKEYS.request(d0:c5:d3:b2:91:4d)
May  1 09:58:18 ipfire64 hostapd: blue0: STA e4:e1:30:09:dc:be MLME: MLME-DEAUTHENTICATE.indication(e4:e1:30:09:dc:be, 1)
May  1 09:58:18 ipfire64 hostapd: blue0: STA e4:e1:30:09:dc:be MLME: MLME-DELETEKEYS.request(e4:e1:30:09:dc:be)
May  1 09:58:19 ipfire64 kernel: usb 1-2: New USB device found, idVendor=050d, idProduct=2103
May  1 09:58:19 ipfire64 kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
May  1 09:58:19 ipfire64 kernel: usb 1-2: Product: Belkin Wireless Adapter
May  1 09:58:19 ipfire64 kernel: usb 1-2: Manufacturer: Realtek
May  1 09:58:19 ipfire64 kernel: usb 1-2: SerialNumber: 00e04c000001
May  1 09:58:19 ipfire64 kernel: rtl8192cu: Chip version 0x11
May  1 09:58:19 ipfire64 kernel: rtl8192cu: Board Type 0
May  1 09:58:19 ipfire64 kernel: rtl_usb: rx_max_size 15360, rx_urb_num 8, in_ep 1
May  1 09:58:19 ipfire64 kernel: rtl8192cu: Loading firmware rtlwifi/rtl8192cufw_TMSC.bin
May  1 09:58:19 ipfire64 kernel: usb 1-3: USB disconnect, device number 4
May  1 09:58:20 ipfire64 kernel: usb 1-3: new high-speed USB device number 6 using xhci_hcd
May  1 09:58:20 ipfire64 ntpd[2639]: Deleting interface #5 blue0, 192.168.50.1#123, interface stats: received=0, sent=0, dropped=0, active_time=131285 secs
May  1 09:58:20 ipfire64 kernel: rtl8192cu 1-2:1.0 blue0: renamed from wlan1
May  1 09:58:20 ipfire64 kernel: rtl8192cu: MAC auto ON okay!
May  1 09:58:20 ipfire64 kernel: rtl8192cu: Tx queue select: 0x05
May  1 09:58:21 ipfire64 kernel: usb 1-3: New USB device found, idVendor=13b1, idProduct=0020
May  1 09:58:21 ipfire64 kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
May  1 09:58:21 ipfire64 kernel: usb 1-3: Product: Compact Wireless-G USB Adapter
May  1 09:58:21 ipfire64 kernel: usb 1-3: Manufacturer: Cisco-Linksys
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86130 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86160 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86190 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf861c0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf861f0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86220 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86250 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86280 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf862b0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf862e0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86310 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86340 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86370 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf863a0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf863d0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86400 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86430 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86460 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86490 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf864c0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf864f0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86520 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86550 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86580 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf865b0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf865e0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86610 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86640 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf86670 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf866a0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: Looking for event-dma 000000000bf866d0 trb-start 000000000bd16e90 trb-end 000000000bd16eb0 seg-start 000000000bd16000 seg-end 000000000bd16ff0
May  1 09:58:21 ipfire64 kernel: xhci_hcd 0000:00:0c.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 0 comp_code 1

(These messages repeat ad infinitum.) The system got to a point that I could no longer work with it. Key presses seemed to disappear into neverland. And the shell would kind of “soft” hang… I mean, the system clearly is not totally hung up. But console shells are not usable.

Again, I have been using the 32 bit version of ipfire for years without major issues (again, sometimes I lose the wireless, which often requires a restart of the VM. But this is not frequent.) The environment is configured the same way, and I am only using the hostapd addon in 64 bit, whereas on 32 bit I have a number of packages installed.

This is apparently some kind of (known!) issue w/r/t USB devices in the kernel after about 4.x or so. Googling the strings above will provide lots of hits.

2

Hi,

please file a bug at https://bugzilla.ipfire.org/ for this.
Further information about reporting bugs is available at https://wiki.ipfire.org/devel/bugzilla .

Thanks, and best regards,
Peter Müller

3

Have you tried a powered hub between the dongle and the pc. usb wlan adapters are known for high power requirements. Also Realtek dongles are known to be unstable.

4

Geez, Arne. I have forgotten all about that. Good idea, thanks!

Of course, EVERY piece of hardware I own has some known problem or another…

5

Tried a powered USB port. No difference. One potential issue with this trial is that my hub is 2.0, and I think the N adapter is 3.0.

I have been able to narrow down this problem to hostapd (the daemon itself):

random: Trying to read entropy from /dev/random
Configuration file: /etc/hostapd.conf
ctrl_interface_group=0
nl80211: Supported cipher 00-0f-ac:1
nl80211: Supported cipher 00-0f-ac:5
nl80211: Supported cipher 00-0f-ac:2
nl80211: Supported cipher 00-0f-ac:4
nl80211: Supported cipher 00-0f-ac:10
nl80211: Supported cipher 00-0f-ac:8
nl80211: Supported cipher 00-0f-ac:9
nl80211: Supported cipher 00-0f-ac:6
nl80211: Supported cipher 00-0f-ac:13
nl80211: Supported cipher 00-0f-ac:11
nl80211: Supported cipher 00-0f-ac:12
nl80211: Using driver-based off-channel TX
nl80211: Driver-advertised extended capabilities (default) - hexdump(len=8): 00 00 00 00 00 00 00 40
nl80211: Driver-advertised extended capabilities mask (default) - hexdump(len=8): 00 00 00 00 00 00 00 40
nl80211: interface blue0 in phy phy0
nl80211: Set mode ifindex 4 iftype 3 (AP)
nl80211: Setup AP(blue0) - device_ap_sme=0 use_monitor=0
nl80211: Subscribe to mgmt frames with AP handle 0x10b1590
nl80211: Register frame type=0xb0 (WLAN_FC_STYPE_AUTH) nl_handle=0x10b1590 match=
nl80211: Register frame type=0x0 (WLAN_FC_STYPE_ASSOC_REQ) nl_handle=0x10b1590 match=
nl80211: Register frame type=0x20 (WLAN_FC_STYPE_REASSOC_REQ) nl_handle=0x10b1590 match=
nl80211: Register frame type=0xa0 (WLAN_FC_STYPE_DISASSOC) nl_handle=0x10b1590 match=
nl80211: Register frame type=0xc0 (WLAN_FC_STYPE_DEAUTH) nl_handle=0x10b1590 match=
nl80211: Register frame type=0x40 (WLAN_FC_STYPE_PROBE_REQ) nl_handle=0x10b1590 match=
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=04
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=0501
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=0503
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=0504
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=06
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=08
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=09
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=0a
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=11
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x10b1590 match=7f
rfkill: initial event: idx=0 type=1 op=0 soft=0 hard=0
nl80211: Add own interface ifindex 4 (ifidx_reason -1)
nl80211: if_indices[16]: 4(-1)
phy: phy0
BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits)
blue0: interface state UNINITIALIZED->COUNTRY_UPDATE
Previous country code US, new country code US 
nl80211: Regulatory information - country=US (DFS-FCC)
nl80211: 2402-2472 @ 40 MHz 30 mBm
nl80211: 5170-5250 @ 80 MHz 23 mBm
nl80211: 5250-5330 @ 80 MHz 23 mBm (DFS)
nl80211: 5490-5730 @ 160 MHz 23 mBm (DFS)
nl80211: 5735-5835 @ 80 MHz 30 mBm
nl80211: 57240-71000 @ 2160 MHz 40 mBm
nl80211: Added 802.11b mode based on 802.11g information
nl80211: Mode IEEE 802.11g: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467[DISABLED][NO_IR] 2472[DISABLED][NO_IR] 2484[DISABLED][NO_IR]
nl80211: Mode IEEE 802.11b: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467[DISABLED][NO_IR] 2472[DISABLED][NO_IR] 2484[DISABLED][NO_IR]
Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=30 dBm
Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=30 dBm
Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=30 dBm
ACS: Automatic channel selection started, this may take a bit
ACS: Scanning 1 / 5
blue0: nl80211: scan request
nl80211: Passive scan requested
nl80211: Scan frequency 2412 MHz
nl80211: Scan frequency 2417 MHz
nl80211: Scan frequency 2422 MHz
nl80211: Scan frequency 2427 MHz
nl80211: Scan frequency 2432 MHz
nl80211: Scan frequency 2437 MHz
nl80211: Scan frequency 2442 MHz
nl80211: Scan frequency 2447 MHz
nl80211: Scan frequency 2452 MHz
nl80211: Scan frequency 2457 MHz
nl80211: Scan frequency 2462 MHz
Scan requested (ret=0) - scan timeout 10 seconds
blue0: interface state COUNTRY_UPDATE->ACS
blue0: ACS-STARTED 
Interface initialization will be completed in a callback (ACS)
ctrl_iface not configured!
random: Got 20/20 bytes from /dev/random
nl80211: Event message available
nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for blue0
blue0: nl80211: Scan trigger
blue0: Event SCAN_STARTED (47) received
Unknown event 47
nl80211: Event message available
nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for blue0
blue0: nl80211: New scan results available
nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
blue0: Event SCAN_RESULTS (3) received
ACS: Using survey based algorithm (acs_num_scans=5)
nl80211: Fetch survey data
blue0: Event SURVEY (46) received
No survey data received
ACS: Scanning 2 / 5
blue0: nl80211: scan request
nl80211: Passive scan requested
nl80211: Scan frequency 2412 MHz
nl80211: Scan frequency 2417 MHz
nl80211: Scan frequency 2422 MHz
nl80211: Scan frequency 2427 MHz
nl80211: Scan frequency 2432 MHz
nl80211: Scan frequency 2437 MHz
nl80211: Scan frequency 2442 MHz
nl80211: Scan frequency 2447 MHz
nl80211: Scan frequency 2452 MHz
nl80211: Scan frequency 2457 MHz
nl80211: Scan frequency 2462 MHz
Scan requested (ret=0) - scan timeout 30 seconds
nl80211: Event message available
nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for blue0
blue0: nl80211: Scan trigger
blue0: Event SCAN_STARTED (47) received
Unknown event 47
RTM_NEWLINK: ifi_index=4 ifname=blue0 wext ifi_family=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK: ifi_index=4 ifname=blue0 wext ifi_family=0 ifi_flags=0x1003 ([UP])
nl80211: Event message available
nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for blue0
blue0: nl80211: New scan results available
nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
blue0: Event SCAN_RESULTS (3) received
ACS: Using survey based algorithm (acs_num_scans=5)
nl80211: Fetch survey data
blue0: Event SURVEY (46) received
No survey data received
ACS: Scanning 3 / 5
blue0: nl80211: scan request
nl80211: Passive scan requested
nl80211: Scan frequency 2412 MHz
nl80211: Scan frequency 2417 MHz
nl80211: Scan frequency 2422 MHz
nl80211: Scan frequency 2427 MHz
nl80211: Scan frequency 2432 MHz
nl80211: Scan frequency 2437 MHz
nl80211: Scan frequency 2442 MHz
nl80211: Scan frequency 2447 MHz
nl80211: Scan frequency 2452 MHz
nl80211: Scan frequency 2457 MHz
nl80211: Scan frequency 2462 MHz
Scan requested (ret=0) - scan timeout 30 seconds
nl80211: Event message available
nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for blue0
blue0: nl80211: Scan trigger
blue0: Event SCAN_STARTED (47) received
Unknown event 47
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff b0 55 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 01 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2412 ssi_signal=-56 fc=0x40 seq_ctrl=0x55b0 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=1
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff c0 55 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 02 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2412 ssi_signal=-50 fc=0x40 seq_ctrl=0x55c0 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=2
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff f0 55 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 03 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2417 ssi_signal=-50 fc=0x40 seq_ctrl=0x55f0 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=3
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 00 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 04 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2417 ssi_signal=-56 fc=0x40 seq_ctrl=0x5600 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=4
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 10 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 04 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2422 ssi_signal=-50 fc=0x40 seq_ctrl=0x5610 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=4
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 20 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 05 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2422 ssi_signal=-56 fc=0x40 seq_ctrl=0x5620 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=5
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 30 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 05 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2422 ssi_signal=-98 fc=0x40 seq_ctrl=0x5630 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=5
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 40 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 06 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2427 ssi_signal=-50 fc=0x40 seq_ctrl=0x5640 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=6
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 50 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 06 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2427 ssi_signal=-50 fc=0x40 seq_ctrl=0x5650 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=6
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 60 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 07 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2432 ssi_signal=-50 fc=0x40 seq_ctrl=0x5660 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=7
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 70 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 07 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2432 ssi_signal=-50 fc=0x40 seq_ctrl=0x5670 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=7
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff 90 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 08 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2437 ssi_signal=-56 fc=0x40 seq_ctrl=0x5690 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=8
nl80211: Event message available
nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for blue0
nl80211: MLME event 59 (NL80211_CMD_FRAME) on blue0(08:86:3b:02:30:97) A1=ff:ff:ff:ff:ff:ff A2=a8:51:5b:55:41:c0
nl80211: MLME event frame - hexdump(len=92): 40 00 00 00 ff ff ff ff ff ff a8 51 5b 55 41 c0 ff ff ff ff ff ff d0 56 00 00 01 08 02 04 0b 0c 12 16 18 24 32 04 30 48 60 6c 03 01 09 2d 1a 6f 01 17 ff 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 07 00 50 f2 08 00 13 00
nl80211: Frame event
nl80211: RX frame da=ff:ff:ff:ff:ff:ff sa=a8:51:5b:55:41:c0 bssid=ff:ff:ff:ff:ff:ff freq=2442 ssi_signal=-56 fc=0x40 seq_ctrl=0x56d0 stype=4 (WLAN_FC_STYPE_PROBE_REQ) len=92
Ignore Probe Request due to DS Params mismatch: chan=0 != ds.chan=9
RTM_NEWLINK: ifi_index=4 ifname=blue0 wext ifi_family=0 ifi_flags=0x1003 ([UP])
nl80211: Event message available
nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for blue0
blue0: nl80211: New scan results available
nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
blue0: Event SCAN_RESULTS (3) received
ACS: Using survey based algorithm (acs_num_scans=5)
nl80211: Fetch survey data
blue0: Event SURVEY (46) received
No survey data received
ACS: Scanning 4 / 5
blue0: nl80211: scan request
nl80211: Passive scan requested
nl80211: Scan frequency 2412 MHz
nl80211: Scan frequency 2417 MHz
nl80211: Scan frequency 2422 MHz
nl80211: Scan frequency 2427 MHz
nl80211: Scan frequency 2432 MHz
nl80211: Scan frequency 2437 MHz
nl80211: Scan frequency 2442 MHz
nl80211: Scan frequency 2447 MHz
nl80211: Scan frequency 2452 MHz
nl80211: Scan frequency 2457 MHz
nl80211: Scan frequency 2462 MHz
Scan requested (ret=0) - scan timeout 30 seconds
nl80211: Event message available
nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for blue0
blue0: nl80211: Scan trigger
blue0: Event SCAN_STARTED (47) received
Unknown event 47
nl80211: Event message available
nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for blue0
blue0: nl80211: New scan results available
nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
blue0: Event SCAN_RESULTS (3) received
ACS: Using survey based algorithm (acs_num_scans=5)
nl80211: Fetch survey data
blue0: Event SURVEY (46) received
No survey data received
ACS: Scanning 5 / 5
blue0: nl80211: scan request
nl80211: Passive scan requested
nl80211: Scan frequency 2412 MHz
nl80211: Scan frequency 2417 MHz
nl80211: Scan frequency 2422 MHz
nl80211: Scan frequency 2427 MHz
nl80211: Scan frequency 2432 MHz
nl80211: Scan frequency 2437 MHz
nl80211: Scan frequency 2442 MHz
nl80211: Scan frequency 2447 MHz
nl80211: Scan frequency 2452 MHz
nl80211: Scan frequency 2457 MHz
nl80211: Scan frequency 2462 MHz
Scan requested (ret=0) - scan timeout 30 seconds
nl80211: Event message available
nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for blue0
blue0: nl80211: Scan trigger
blue0: Event SCAN_STARTED (47) received
Unknown event 47
RTM_NEWLINK: ifi_index=4 ifname=blue0 wext ifi_family=0 ifi_flags=0x1003 ([UP])
nl80211: Event message available
nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for blue0
blue0: nl80211: New scan results available
nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462
blue0: Event SCAN_RESULTS (3) received
ACS: Using survey based algorithm (acs_num_scans=5)
nl80211: Fetch survey data
blue0: Event SURVEY (46) received
No survey data received
ACS: Trying survey-based ACS
ACS: Unable to collect survey data
ACS: All study options have failed
Interface initialization failed
blue0: interface state ACS->DISABLED
blue0: AP-DISABLED 
ACS: Possibly channel configuration is invalid, please report this along with your config file.
ACS: Failed to start
blue0: AP-DISABLED 
blue0: Deauthenticate all stations
nl80211: send_mlme - da= ff:ff:ff:ff:ff:ff noack=0 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0xc0 (WLAN_FC_STYPE_DEAUTH) nlmode=3
nl80211: send_mlme -> send_frame
nl80211: send_frame - Use bss->freq=0
nl80211: send_frame -> send_frame_cmd
nl80211: CMD_FRAME freq=0 wait=0 no_cck=0 no_ack=0 offchanok=0
CMD_FRAME - hexdump(len=26): c0 00 00 00 ff ff ff ff ff ff 08 86 3b 02 30 97 08 86 3b 02 30 97 00 00 03 00
nl80211: Frame command failed: ret=-22 (Invalid argument) (freq=0 wait=0)
hostapd_free_hapd_data: Interface blue0 wasn't started
hostapd_deinit_driver: driver=0x5228e0 drv_priv=0x10b32d0 -> hapd_deinit
nl80211: deinit ifname=blue0 disabled_11b_rates=0
nl80211: Remove monitor interface: refcount=0
nl80211: Remove beacon (ifindex=4)
netlink: Operstate: ifindex=4 linkmode=0 (kernel-control), operstate=6 (IF_OPER_UP)
nl80211: Set mode ifindex 4 iftype 2 (STATION)
nl80211: Teardown AP(blue0) - device_ap_sme=0 use_monitor=0
nl80211: Unsubscribe mgmt frames handle 0x8888888889839d19 (AP teardown)
hostapd_deinit_driver:bss[0]->drv_priv=0x10b32d0
hostapd_cleanup_iface_partial(0x10adfa0)
Interface blue0 disabled
blue0: interface state DISABLED->DISABLED
hostapd_interface_deinit_free(0x10adfa0)
hostapd_interface_deinit_free: num_bss=1 conf->num_bss=1
hostapd_interface_deinit(0x10adfa0)
blue0: interface state DISABLED->DISABLED
hostapd_bss_deinit: deinit bss blue0
blue0: AP-DISABLED 
hostapd_cleanup(hapd=0x10af390 (blue0))
blue0: CTRL-EVENT-TERMINATING 
hostapd_free_hapd_data: Interface blue0 wasn't started
hostapd_interface_deinit_free: driver=0x5228e0 drv_priv=(nil) -> hapd_deinit
hostapd_interface_free(0x10adfa0)
hostapd_interface_free: free hapd 0x10af390
hostapd_cleanup_iface(0x10adfa0)
hostapd_cleanup_iface_partial(0x10adfa0)
hostapd_cleanup_iface: free iface=0x10adfa0

It appears that the ACS (automatic channel selection?) is failing. I have noticed previously that selecting a channel manually does help. Oddly, this feature seemed to work in earlier versions. I don’t recall having to manually set the channel in the past.

6

ACS is only supported by a handfull wlan chips. Mainly “Atheros” but for 5Ghz it is needed. This is another reason for not using Realtek Wlan chips.

7

ACS used to work, afaik. Another problem is that when ACS is enabled, dhcpd also becomes stubborn. This behavior of the ACS and dhcpd is the same on both 32 and 64 bit versions of ipfire.

Sometimes, restarting dhcpd is enough to reset the wireless ecosystem. But other times, A complete reboot of the VM is necessary.

At this point, I’m starting to look at 802.11AC devices. I want to get one that has a base port/station so I can run it off a length of USB extension cable to get it away from my PC and place it up high (avoid interference etc). Is there one that is pretty much fully supported by Ipfire?

Thanks to all who can make constructive suggestions!

8

I’ve been testing the 64 bit version in a Vbox VM for several weeks now. I’m using that Realtek-based N wireless dongle and it has been working consistently since setting the channel (vs ACS). With that one restriction, it seems to be steady.

Thanks for all the feedback. I will avoid ACS for the time being.

As far as my recollections of ACS working, I may have been using a different wireless dongle back then.