# dmesg:

: [00:19:3b:13:16:bc]_ieee80211_free_node: vap=0x832c6000, nodecount=2,

ni=0x83b4e000, ni_bss_node=0x83b4e000 bss_ref=0
[ 3891.735000] vap-0: [00:19:3b:13:16:bc]node_cleanup: vap=0x832c6000, nodecount=2,
ni=0x83b4e000, ni_bss_node=0x83b4e000 bss_ref=0
[ 3891.747000] vap-0: ieee80211_state_event: VAP state event 11
[ 3891.753000] vap-0: ieee80211_state_event: VAP EVENT_NODE_FREED node count 1
[ 3891.760000] vap-0: osif_sta_sm_evhandler : event 1 reason 0
[ 3891.766000] vap-0: osif_sta_sm_evhandler :sta disconnected
[ 3891.777000] vap-0: DELETE CRYPTO KEY index 0, addr 00:00:00:00:00:00
[ 3891.783000] vap-0: wlan_del_key: invalid keyvap-0: DELETE CRYPTO KEY index 1,
addr 00:00:00:00:00:00
[ 3891.807000] vap-0: _ieee80211_crypto_delkey: TKIP keyix 1 flags 0x6 tsc 0 len 16
[ 3891.840000] vap-0: DELETE CRYPTO KEY index 0, addr 00:19:3b:13:16:bc
[ 3891.846000] vap-0: wlan_del_key: invalid key
[ 3891.861000] br0: port 1(ath0) entering disabled state
[ 3891.878000] vap-0: set IEEE80211_IOC_SCAN_FLUSH
[ 3891.883000] ieee80211_scan_table_flush: 18:E8:29:82:2C:66
[ 3891.888000] ieee80211_scan_table_flush: 00:19:3B:13:16:BC
[ 3891.904000] vap-0: set IEEE80211_IOC_SCAN_REQ, len=200
[ 3891.909000] vap-0: start running, ifname=ath0, opmode=1
[ 3891.914000] 3591.914 | ieee80211_scan_run: module=osif_umac ID=0 flag=0000008E
type=1 elapsed=0/80000 multiple_ports=0 preempted=0 runctr=31
[ 3891.927000] nchans=259 dwell=(100,100) (130,500) repeat_probe=50
max_offchan_time=0 offchan_retry=(100,03) VAP=832c6000
[ 3891.939000] 3591.939 | HSM: scan :current state IDLE event SCAN_REQUEST[9]
[ 3891.946000] scanner_state_idle_event state=IDLE event=SCAN_REQUEST
[ 3891.952000] vap-0: ieee80211_vap_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 0 reason 0
[ 3891.962000] vap-0: ieee80211_state_event: VAP state event 2
[ 3891.967000] vap-0: ieee80211_state_event: VAP state transition INIT -> SCAN
[ 3891.985000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 0 reason 0
[ 3891.995000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 0
reason 0
[ 3892.003000] 3592.003 | HSM: scan: transition IDLE => FOREIGN_CHANNEL
[ 3892.009000] 3592.009 | scanner_state_foreign_channel_entry: entering channel=161
freq=4805 passive=0 probe_delay=0
[ 3892.027000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 6 reason 0
[ 3892.036000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 6
reason 0
[ 3892.044000] vap-0: HSM: connection :current state INIT event SCAN_START[9]
[ 3892.051000] vap-0: HSM: connection : event 9 not handled in state INIT
[ 3892.095000] 3592.095 | HSM: scan :current state FOREIGN_CHANNEL event
PROBE_REQUEST_TIMER[15]
[ 3892.103000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=PROBE_REQUEST_TIMER
[ 3892.112000] scanner_can_leave_foreign_channel: FALSE: elapsed_dwell_time=68
current_time=3592112 enter_channel_time=3592044
[ 3892.157000] vap-0: ieee80211_ioctl_getparam parameter is 0x130 get1 0
[ 3892.175000] 3592.175 | HSM: scan :current state FOREIGN_CHANNEL event
MAXDWELL_EXPIRE[3]
[ 3892.183000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=MAXDWELL_EXPIRE
[ 3892.191000] 3592.191 | HSM: scan: transition FOREIGN_CHANNEL => FOREIGN_CHANNEL
[ 3892.199000] scanner_state_foreign_channel_exit: wait_for_beacon=0
[ 3892.205000] 3592.205 | scanner_state_foreign_channel_entry: entering channel=162
freq=4810 passive=0 probe_delay=0
[ 3892.216000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 8 reason 1
[ 3892.225000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 8
reason 1
[ 3892.234000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 6 reason 0
[ 3892.243000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 6
reason 0
[ 3892.265000] calling '/usr/share/lua/tc-helper.lua -d', ret=0
[ 3892.303000] 3592.303 | HSM: scan :current state FOREIGN_CHANNEL event
PROBE_REQUEST_TIMER[15]
[ 3892.311000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=PROBE_REQUEST_TIMER
[ 3892.320000] scanner_can_leave_foreign_channel: FALSE: elapsed_dwell_time=68
current_time=3592320 enter_channel_time=3592252
[ 3892.335000] ieee80211_create_new_scan_entry: 00:19:3B:13:16:BC SSID=Cerro la
Barra AxURE chan=162 p=824efa00
[ 3892.345000] ieee80211_scan_entry_update: MAC=00:19:3B:13:16:BC ssid=Cerro la
Barra AxURE l=20 BSSID=00:19:3B:13:16:BC se_timestamp=3592335 subtype=50 ch=162
rssi=28 avg_rssi=28 ssid_match=1 ch_mismatch=0
[ 3892.364000] ieee80211_scan_entry_update: MAC=00:19:3B:13:16:BC ssid=Cerro la
Barra AxURE l=20 BSSID=00:19:3B:13:16:BC se_timestamp=3592364 subtype=50 ch=162
rssi=28 avg_rssi=28 ssid_match=1 ch_mismatch=0
[ 3892.382000] ieee80211_scan_entry_update: MAC=00:19:3B:13:16:BC ssid=Cerro la
Barra AxURE l=20 BSSID=00:19:3B:13:16:BC se_timestamp=3592382 subtype=50 ch=162
rssi=27 avg_rssi=28 ssid_match=1 ch_mismatch=0
[ 3892.401000] 3592.401 | HSM: scan :current state FOREIGN_CHANNEL event
MAXDWELL_EXPIRE[3]
[ 3892.409000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=MAXDWELL_EXPIRE
[ 3892.418000] 3592.418 | HSM: scan: transition FOREIGN_CHANNEL => FOREIGN_CHANNEL
[ 3892.425000] scanner_state_foreign_channel_exit: wait_for_beacon=0
[ 3892.431000] 3592.431 | scanner_state_foreign_channel_entry: entering channel=163
freq=4815 passive=0 probe_delay=0
[ 3892.442000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 8 reason 1
[ 3892.451000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 8
reason 1
[ 3892.460000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 6 reason 0
[ 3892.470000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 6
reason 0
[ 3892.478000] ieee80211_scan_table_update: error parsing beacon
MACADDR=00:19:3B:13:16:BC
[ 3892.529000] 3592.529 | HSM: scan :current state FOREIGN_CHANNEL event
PROBE_REQUEST_TIMER[15]
[ 3892.537000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=PROBE_REQUEST_TIMER
[ 3892.546000] scanner_can_leave_foreign_channel: FALSE: elapsed_dwell_time=68
current_time=3592546 enter_channel_time=3592478
[ 3892.588000] vap-0: ieee80211_ioctl_getparam parameter is 0x27 get1 0
[ 3892.609000] 3592.609 | HSM: scan :current state FOREIGN_CHANNEL event
MAXDWELL_EXPIRE[3]
[ 3892.617000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=MAXDWELL_EXPIRE
[ 3892.625000] 3592.625 | HSM: scan: transition FOREIGN_CHANNEL => FOREIGN_CHANNEL
[ 3892.633000] scanner_state_foreign_channel_exit: wait_for_beacon=0
[ 3892.639000] 3592.639 | scanner_state_foreign_channel_entry: entering channel=164
freq=4820 passive=0 probe_delay=0
[ 3892.650000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 8 reason 1
[ 3892.659000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 8
reason 1
[ 3892.668000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 6 reason 0
[ 3892.678000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 6
reason 0
[ 3892.687000] vap-0: ieee80211_ioctl_getparam parameter is 0x126 get1 0
[ 3892.694000] vap-0: ieee80211_ioctl_getparam parameter is 0x13 get1 0
[ 3892.706000] vap-0: ieee80211_ioctl_getparam parameter is 0xf2 get1 0
[ 3892.719000] vap-0: ieee80211_ioctl_getparam parameter is 0x25 get1 0
[ 3892.727000] vap-0: ieee80211_ioctl_getparam parameter is 0x64 get1 0
[ 3892.734000] vap-0: ieee80211_ioctl_getparam parameter is 0x7a get1 0
[ 3892.741000] 3592.741 | HSM: scan :current state FOREIGN_CHANNEL event
PROBE_REQUEST_TIMER[15]
[ 3892.749000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=PROBE_REQUEST_TIMER
[ 3892.758000] scanner_can_leave_foreign_channel: FALSE: elapsed_dwell_time=72
current_time=3592758 enter_channel_time=3592686
[ 3892.771000] vap-0: ieee80211_ioctl_getparam parameter is 0x76 get1 0
[ 3892.785000] vap-0: ieee80211_ioctl_getparam parameter is 0x124 get1 0
[ 3892.799000] vap-0: wlan_get_key: invalid key3592.821 | HSM: scan :current state
FOREIGN_CHANNEL event MAXDWELL_EXPIRE[3]
[ 3892.828000] scanner_state_foreign_channel_event state=FOREIGN_CHANNEL
event=MAXDWELL_EXPIRE
[ 3892.836000] 3592.836 | HSM: scan: transition FOREIGN_CHANNEL => FOREIGN_CHANNEL
[ 3892.844000] scanner_state_foreign_channel_exit: wait_for_beacon=0
[ 3892.850000] 3592.850 | scanner_state_foreign_channel_entry: entering channel=165
freq=4825 passive=0 probe_delay=0
[ 3892.860000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 8 reason 1
[ 3892.870000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 8
reason 1
[ 3892.879000] vap-0: mlme_sta_scan_event_handler: orig=832c6000 vap=832c6000
scan_id 0 event 6 reason 0
[ 3892.888000] vap-0: ieee80211_connection_sm_scan_evhandler scan_id 0 event 6
reason 0
[ 3983.513000] [ieee80211_ioctl_setmlme] set desired bssid 00:19:3b:13:16:bc
[ 3984.535000] calling '/usr/share/lua/tc-helper.lua -a', ret=0
[ 3984.541000] br0: port 1(ath0) entering learning state
[ 3985.546000] br0: port 1(ath0) entering forwarding state
[ 4085.019000] vap-0: tkip_demic: check for PN gap: TID=16, from 0 26 to 0 28.
Frame seq=0xb058
[ 4085.028000] vap-0: tkip_demic: PN gap detected: TID=16, from 0 26 to 0 28. Frame
seq=0xb058
[ 4085.048000] vap-0: wlan_get_key: invalid key
[ 4085.105000] port_map:0 mask:1
[ 4086.296000] vap-0: [00:19:3b:13:16:bc]ieee80211_recv_action: action mgt frame
(cat 127, act 0)
[ 4086.927000] vap-0: [00:19:3b:13:16:bc]ath_net80211_do_atpc: tx ATPC frame to:
00:19:3b:13:16:bc, rssi=40, local link margin=35
[ 4087.509000] vap-0: tkip_demic: check for PN gap: TID=16, from 0 28 to 0 29.
Frame seq=0x405a
[ 4090.024000] vap-0: tkip_demic: check for PN gap: TID=16, from 0 29 to 0 2a.
Frame seq=0xe05b
[ 4091.296000] vap-0: [00:19:3b:13:16:bc]ieee80211_recv_action: action mgt frame
(cat 127, act 0)
[98900.756000] tc_peer_del: 00:19:3b:13:16:bc peer removed, reason=beacon_miss
[98900.797000] br0: port 1(ath0) entering disabled state
[98900.921000] calling '/usr/share/lua/tc-helper.lua -d', ret=0
[98991.043000] [ieee80211_ioctl_setmlme] set desired bssid 00:19:3b:13:16:bc
[98992.065000] calling '/usr/share/lua/tc-helper.lua -a', ret=0
[98992.071000] br0: port 1(ath0) entering learning state
[98993.076000] br0: port 1(ath0) entering forwarding state
[99024.730000] tc_peer_del: 00:19:3b:13:16:bc peer removed, reason=beacon_miss
[99024.760000] br0: port 1(ath0) entering disabled state
[99024.891000] calling '/usr/share/lua/tc-helper.lua -d', ret=0
[99083.293000] [ieee80211_ioctl_setmlme] set desired bssid 00:19:3b:13:16:bc
[99084.316000] calling '/usr/share/lua/tc-helper.lua -a', ret=0
[99084.322000] br0: port 1(ath0) entering learning state
[99085.327000] br0: port 1(ath0) entering forwarding state
[99441.891000] tc_peer_del: 00:19:3b:13:16:bc peer removed, reason=beacon_miss
[99441.913000] br0: port 1(ath0) entering disabled state
[99442.048000] calling '/usr/share/lua/tc-helper.lua -d', ret=0
[99500.453000] [ieee80211_ioctl_setmlme] set desired bssid 00:19:3b:13:16:bc
[99501.475000] calling '/usr/share/lua/tc-helper.lua -a', ret=0
[99501.481000] br0: port 1(ath0) entering learning state
[99502.486000] br0: port 1(ath0) entering forwarding state
[99582.753000] tc_peer_del: 00:19:3b:13:16:bc peer removed, reason=beacon_miss
[99582.775000] br0: port 1(ath0) entering disabled state
[99582.910000] calling '/usr/share/lua/tc-helper.lua -d', ret=0
[99990.335000] [ieee80211_ioctl_setmlme] set desired bssid 00:19:3b:13:16:bc
[99991.370000] calling '/usr/share/lua/tc-helper.lua -a', ret=0
[99991.376000] br0: port 1(ath0) entering learning state
[99992.381000] br0: port 1(ath0) entering forwarding state
[101045.600000] vap-0: [00:19:3b:13:16:bc]ieee80211_recv_action: action mgt frame
(cat 127, act 0)
[101045.695000] vap-0: wlan_get_key: invalid key
[101045.752000] port_map:0 mask:1
[101046.960000] vap-0: [00:19:3b:13:16:bc]ath_net80211_do_atpc: tx ATPC frame to:
00:19:3b:13:16:bc, rssi=39, local link margin=34
[101048.113000] vap-0: tkip_demic: check for PN gap: TID=16, from 0 16f to 0 170.
Frame seq=0xf016
[101050.600000] vap-0: [00:19:3b:13:16:bc]ieee80211_recv_action: action mgt frame
(cat 127, act 0)
[101051.133000] vap-0: tkip_demic: check for PN gap: TID=16, from 0 170 to 0 173.
Frame seq=0x19
[101051.142000] vap-0: tkip_demic: PN gap detected: TID=16, from 0 170 to 0 173.
Frame seq=0x19
[101051.971000] vap-0: [00:19:3b:13:16:bc]ath_net80211_do_atpc: tx ATPC frame to:
00:19:3b:13:16:bc, rssi=39, local link margin=34
[101052.133000] vap-0: tkip_demic: check for PN gap: TID=16, from 0 173 to 0 174.
Frame seq=0xb019
[101052.392000] vap-0: ieee80211_ioctl_getparam parameter is 0x27 get1 0
[101052.424000] vap-0: ieee80211_ioctl_getparam parameter is 0x126 get1 0
[101052.431000] vap-0: ieee80211_ioctl_getparam parameter is 0x13 get1 0
[101052.448000] vap-0: ieee80211_ioctl_getparam parameter is 0xf2 get1 0
[101052.465000] vap-0: ieee80211_ioctl_getparam parameter is 0x25 get1 0
[101052.472000] vap-0: ieee80211_ioctl_getparam parameter is 0x64 get1 0
[101052.489000] vap-0: ieee80211_ioctl_getparam parameter is 0x7a get1 0
[101052.506000] vap-0: ieee80211_ioctl_getparam parameter is 0x76 get1 0
[101052.523000] vap-0: ieee80211_ioctl_getparam parameter is 0x124 get1 0
[101052.531000] vap-0: wlan_get_key: invalid keyvap-0: ieee80211_ioctl_getparam
parameter is 0x126 get1 0
[101124.272000] pktgen: interface: ath0 is already stopped
[101976.715000] tc_peer_del: 00:19:3b:13:16:bc peer removed, reason=rx_disassoc
[101976.753000] br0: port 1(ath0) entering disabled state
[101976.879000] calling '/usr/share/lua/tc-helper.lua -d', ret=0
[102067.009000] [ieee80211_ioctl_setmlme] set desired bssid 00:19:3b:13:16:bc
[102068.031000] calling '/usr/share/lua/tc-helper.lua -a', ret=0
[102068.037000] br0: port 1(ath0) entering learning state
[102069.042000] br0: port 1(ath0) entering forwarding state
[102362.188000] vap-0: wlan_get_key: invalid key
[102362.244000] port_map:0 mask:1
[102362.387000] vap-0: ieee80211_ioctl_getparam parameter is 0x27 get1 0
[102362.394000] vap-0: ieee80211_ioctl_getparam parameter is 0x126 get1 0
[102362.402000] vap-0: ieee80211_ioctl_getparam parameter is 0x13 get1 0
[102362.435000] vap-0: ieee80211_ioctl_getparam parameter is 0xf2 get1 0
[102362.453000] vap-0: ieee80211_ioctl_getparam parameter is 0x25 get1 0
[102362.463000] vap-0: ieee80211_ioctl_getparam parameter is 0x64 get1 0
[102362.477000] vap-0: ieee80211_ioctl_getparam parameter is 0x7a get1 0
[102362.491000] vap-0: ieee80211_ioctl_getparam parameter is 0x76 get1 0
[102362.498000] vap-0: ieee80211_ioctl_getparam parameter is 0x124 get1 0
[102362.506000] vap-0: wlan_get_key: invalid keyvap-0: ieee80211_ioctl_getparam
parameter is 0x126 get1 0
[102362.530000] vap-0: ieee80211_ioctl_getparam parameter is 0x130 get1 0
[102364.470000] vap-0: set IEEE80211_IOC_MCASTCIPHER to TKIP
[102364.476000] vap-0: ieee80211_set_default_key: Default keyID 1, persist 0,
algoId TKIP, keyLen 16, mac = ff:ff:ff:ff:ff:ff
[102364.487000] vap-0: ieee80211_set_key: init. TKIP PN to 0x00, tsc=0x00.
[102364.494000] vap-0: _ieee80211_crypto_setkey: TKIP keyix 1 flags 0x6 mac
ff:ff:ff:ff:ff:ff tsc 0 len 16
[102364.504000] vap-0: key is valid
[102364.507000] vap-0:
[102364.507000]
[102364.507000] ieee80211_ioctl_setkey: ******** SETKEY : error: 0 , key_type=1,
macaddr=ff:ff:ff:ff:ff:ff, key_len=16
[102364.507000]
[102365.616000] vap-0: [00:19:3b:13:16:bc]ieee80211_recv_action: action mgt frame
(cat 127, act 0)
[102367.006000] vap-0: [00:19:3b:13:16:bc]ath_net80211_do_atpc: tx ATPC frame to:
00:19:3b:13:16:bc, rssi=41, local link margin=36