[Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces

linux-wireless.vger.kernel.org archive mirror
 help / color / mirror / Atom feed

* [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces
@ 2020-04-18 11:20 Frank Schäfer
  2020-04-18 16:30 ` Christian Lamparter
  2020-04-27 16:53 ` Frank Schäfer
  0 siblings, 2 replies; 6+ messages in thread
From: Frank Schäfer @ 2020-04-18 11:20 UTC (permalink / raw)
  To: chunkeey, j; +Cc: linux-wireless

Hi,

I'm currently testing Miraclecast 
(https://github.com/albfan/miraclecast), an open source 
Wifi-Display/Miracast implementation.
In one of my setups, I'm using a carl9170 device as sink, which fails 
with the following debugging output:

...
TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
TRACE: wpa: raw message: <3>P2P-PROV-DISC-PBC-REQ 8e:79:67:11:22:33 
p2p_dev_addr=8e:79:67:11:22:33 pri_dev_type=10-0050F204-5 name='MY 
DEVICE' config_methods=0x188 dev_capab=0x25 group_capab=0x0
TRACE: wpa: raw message: <3>P2P-GO-NEG-REQUEST 8e:79:67:11:22:33 
dev_passwd_id=4 go_intent=0
DEBUG: supplicant: GO Negotiation Request from 8e:79:67:11:22:33 
(supplicant_event_p2p_go_neg_request() in 
../../../src/wifi/wifid-supplicant.c:1065)
DEBUG: supplicant: connect to 8e:79:67:11:22:33 via pbc/(null) 
(supplicant_peer_connect() in ../../../src/wifi/wifid-supplicant.c:739)
TRACE: wpa: raw message: <3>P2P-FIND-STOPPED
DEBUG: supplicant: p2p-scanning stopped on wlp0s2f1u8 
(supplicant_event_p2p_find_stopped() in 
../../../src/wifi/wifid-supplicant.c:904)
TRACE: wpa: raw message: OK

TRACE: wpa: raw message: <3>P2P-GO-NEG-SUCCESS role=GO freq=2462 ht40=0 
peer_dev=8e:79:67:11:22:33 peer_iface=8e:79:67:11:22:33 wps_method=PBC
DEBUG: supplicant: set STA-MAC for 8e:79:67:11:22:33 from <none> to 
8e:79:67:11:22:33 (via GO-NEG-SUCCESS) 
(supplicant_event_p2p_go_neg_success() in 
../../../src/wifi/wifid-supplicant.c:1194)
TRACE: wpa: raw message: IFNAME=p2p-0 <3>Failed to start AP functionality
DEBUG: supplicant: unhandled wpas-event: IFNAME=p2p-0 <3>Failed to start 
AP functionality (supplicant_event() in 
../../../src/wifi/wifid-supplicant.c:1498)
TRACE: wpa: raw message: <3>P2P-GROUP-FORMATION-FAILURE
DEBUG: supplicant: peer MY DEVICE connection failed 
(supplicant_event_p2p_group_formation_failure() in 
../../../src/wifi/wifid-supplicant.c:1318)
TRACE: wpa: raw message: <3>P2P-GROUP-REMOVED p2p-0 GO 
reason=FORMATION_FAILED
DEBUG: supplicant: stray P2P-GROUP-REMOVED: <3>P2P-GROUP-REMOVED p2p-0 
GO reason=FORMATION_FAILED (supplicant_event_p2p_group_removed() in 
../../../src/wifi/wifid-supplicant.c:1286)
DEBUG: supplicant: sent P2P_FIND to wpas on wlp0s2f1u8 
(supplicant_p2p_start_scan() in ../../../src/wifi/wifid-supplicant.c:2036)
TRACE: wpa: raw message: OK

DEBUG: supplicant: p2p-scanning now active on wlp0s2f1u8 
(supplicant_p2p_find_fn() in ../../../src/wifi/wifid-supplicant.c:1917)
TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
...

Searching the web for similar problems leads to the following thread:
https://marc.info/?l=hostap&m=135336063022534

As suggested there, I have patched the Miraclecast sources to use the 
wpa_supplicant configuration parameter p2p_no_group_iface=1.
So far, this works fine.

Regards,
Frank Schäfer

^ permalink raw reply	[flat|nested] 6+ messages in thread

* Re: [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces
  2020-04-18 11:20 [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces Frank Schäfer
@ 2020-04-18 16:30 ` Christian Lamparter
  2020-04-19 14:08   ` Frank Schäfer
  2020-04-27 16:53 ` Frank Schäfer
  1 sibling, 1 reply; 6+ messages in thread
From: Christian Lamparter @ 2020-04-18 16:30 UTC (permalink / raw)
  To: Frank Schäfer; +Cc: chunkeey, j, linux-wireless

Hi,

On Saturday, 18 April 2020 13:20:27 CEST Frank Schäfer wrote:
> I'm currently testing Miraclecast 
> (https://github.com/albfan/miraclecast), an open source 
> Wifi-Display/Miracast implementation.
> In one of my setups, I'm using a carl9170 device as sink, which fails 
> with the following debugging output:
> 
It's been a very long time. But I do remember meddling with P2P. Part of the
reason is that the carl9170 driver needed these virtual interfaces initialized
in a specific order.

So for P2P GO+CLIENT, you would have to initialize the P2P-GO interface first
and the P2P Client interface second. Which is backwards of what the wpa_supplicant
does.

Same is true for STA+AP (Repeater). The AP has to start first before the STA
can be brought up (Also the STA must not interfere with channel operations, as
the fast channel change feature doesn't work right on the AR9170 Hardware).

This is documented in the driver:
<https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/tree/drivers/net/wireless/ath/carl9170/main.c#n625>

and was changed as part of this commit:
<https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/net/wireless/ath/carl9170/main.c?id=7f878b0d96e6b3bd27d736fb2fb7e3cc94b16b26>

that identifies the wpa_supplicant commit
["nl80211: Automatically use concurrent P2P if possible"] as the reason.

So since this broke, something must have changed since 2012 I guess.
But I need more details, before I can do something.

Regards,
Christian

^ permalink raw reply	[flat|nested] 6+ messages in thread

* Re: [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces
  2020-04-18 16:30 ` Christian Lamparter
@ 2020-04-19 14:08   ` Frank Schäfer
  0 siblings, 0 replies; 6+ messages in thread
From: Frank Schäfer @ 2020-04-19 14:08 UTC (permalink / raw)
  To: Christian Lamparter; +Cc: chunkeey, j, linux-wireless


Am 18.04.20 um 18:30 schrieb Christian Lamparter:
> Hi,
>
> On Saturday, 18 April 2020 13:20:27 CEST Frank Schäfer wrote:
>> I'm currently testing Miraclecast
>> (https://github.com/albfan/miraclecast), an open source
>> Wifi-Display/Miracast implementation.
>> In one of my setups, I'm using a carl9170 device as sink, which fails
>> with the following debugging output:
>>
> It's been a very long time. But I do remember meddling with P2P. Part of the
> reason is that the carl9170 driver needed these virtual interfaces initialized
> in a specific order.
>
> So for P2P GO+CLIENT, you would have to initialize the P2P-GO interface first
> and the P2P Client interface second. Which is backwards of what the wpa_supplicant
> does.
>
> Same is true for STA+AP (Repeater). The AP has to start first before the STA
> can be brought up (Also the STA must not interfere with channel operations, as
> the fast channel change feature doesn't work right on the AR9170 Hardware).
>
> This is documented in the driver:
> <https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/tree/drivers/net/wireless/ath/carl9170/main.c#n625>
>
> and was changed as part of this commit:
> <https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/drivers/net/wireless/ath/carl9170/main.c?id=7f878b0d96e6b3bd27d736fb2fb7e3cc94b16b26>
>
> that identifies the wpa_supplicant commit
> ["nl80211: Automatically use concurrent P2P if possible"] as the reason.
>
> So since this broke, something must have changed since 2012 I guess.
> But I need more details, before I can do something.
Please let me know what you need. :)

Regards,
Frank

> Regards,
> Christian
>


^ permalink raw reply	[flat|nested] 6+ messages in thread

* Re: [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces
  2020-04-18 11:20 [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces Frank Schäfer
  2020-04-18 16:30 ` Christian Lamparter
@ 2020-04-27 16:53 ` Frank Schäfer
  2020-04-29 16:22   ` Frank Schäfer
  1 sibling, 1 reply; 6+ messages in thread
From: Frank Schäfer @ 2020-04-27 16:53 UTC (permalink / raw)
  To: chunkeey, j; +Cc: linux-wireless

[-- Attachment #1: Type: text/plain, Size: 3173 bytes --]


Am 18.04.20 um 13:20 schrieb Frank Schäfer:
> Hi,
>
> I'm currently testing Miraclecast 
> (https://github.com/albfan/miraclecast), an open source 
> Wifi-Display/Miracast implementation.
> In one of my setups, I'm using a carl9170 device as sink, which fails 
> with the following debugging output:
>
> ...
> TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
> TRACE: wpa: raw message: <3>P2P-PROV-DISC-PBC-REQ 8e:79:67:11:22:33 
> p2p_dev_addr=8e:79:67:11:22:33 pri_dev_type=10-0050F204-5 name='MY 
> DEVICE' config_methods=0x188 dev_capab=0x25 group_capab=0x0
> TRACE: wpa: raw message: <3>P2P-GO-NEG-REQUEST 8e:79:67:11:22:33 
> dev_passwd_id=4 go_intent=0
> DEBUG: supplicant: GO Negotiation Request from 8e:79:67:11:22:33 
> (supplicant_event_p2p_go_neg_request() in 
> ../../../src/wifi/wifid-supplicant.c:1065)
> DEBUG: supplicant: connect to 8e:79:67:11:22:33 via pbc/(null) 
> (supplicant_peer_connect() in ../../../src/wifi/wifid-supplicant.c:739)
> TRACE: wpa: raw message: <3>P2P-FIND-STOPPED
> DEBUG: supplicant: p2p-scanning stopped on wlp0s2f1u8 
> (supplicant_event_p2p_find_stopped() in 
> ../../../src/wifi/wifid-supplicant.c:904)
> TRACE: wpa: raw message: OK
>
> TRACE: wpa: raw message: <3>P2P-GO-NEG-SUCCESS role=GO freq=2462 
> ht40=0 peer_dev=8e:79:67:11:22:33 peer_iface=8e:79:67:11:22:33 
> wps_method=PBC
> DEBUG: supplicant: set STA-MAC for 8e:79:67:11:22:33 from <none> to 
> 8e:79:67:11:22:33 (via GO-NEG-SUCCESS) 
> (supplicant_event_p2p_go_neg_success() in 
> ../../../src/wifi/wifid-supplicant.c:1194)
> TRACE: wpa: raw message: IFNAME=p2p-0 <3>Failed to start AP functionality
> DEBUG: supplicant: unhandled wpas-event: IFNAME=p2p-0 <3>Failed to 
> start AP functionality (supplicant_event() in 
> ../../../src/wifi/wifid-supplicant.c:1498)
> TRACE: wpa: raw message: <3>P2P-GROUP-FORMATION-FAILURE
> DEBUG: supplicant: peer MY DEVICE connection failed 
> (supplicant_event_p2p_group_formation_failure() in 
> ../../../src/wifi/wifid-supplicant.c:1318)
> TRACE: wpa: raw message: <3>P2P-GROUP-REMOVED p2p-0 GO 
> reason=FORMATION_FAILED
> DEBUG: supplicant: stray P2P-GROUP-REMOVED: <3>P2P-GROUP-REMOVED p2p-0 
> GO reason=FORMATION_FAILED (supplicant_event_p2p_group_removed() in 
> ../../../src/wifi/wifid-supplicant.c:1286)
> DEBUG: supplicant: sent P2P_FIND to wpas on wlp0s2f1u8 
> (supplicant_p2p_start_scan() in 
> ../../../src/wifi/wifid-supplicant.c:2036)
> TRACE: wpa: raw message: OK
>
> DEBUG: supplicant: p2p-scanning now active on wlp0s2f1u8 
> (supplicant_p2p_find_fn() in ../../../src/wifi/wifid-supplicant.c:1917)
> TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
> ...
>
>
> Searching the web for similar problems leads to the following thread:
> https://marc.info/?l=hostap&m=135336063022534
>
> As suggested there, I have patched the Miraclecast sources to use the 
> wpa_supplicant configuration parameter p2p_no_group_iface=1.
> So far, this works fine.
>
>
> Regards,
> Frank Schäfer
>

I finally managed to create extended logs and to compare the working 
("GOOD") and non-working ("BAD") case.
See attachment.
If anything is missing, please let me know.

Hth,
Frank



[-- Attachment #2: journal-compared.txt --]
[-- Type: text/plain, Size: 75616 bytes --]


kernel: 3.7.0-rc3
wpa_supplicant: 2.9


wpa_supplicant configuration file used my Miraclecast:
	device_name=unknown
	device_type=1-0050F204-1
	config_methods=pbc
	driver_param=p2p_device=1
	ap_scan=1
=> doesn't work, called "BAD"

with additional wpa_supplicant config parameter p2p_no_group_iface=1:
=> works, called "GOOD"

Note: COMMON parts are taken from "BAD"


--- COMMON:
...
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Sending GO Negotiation Response
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Use ongoing radio work for Action frame TX
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Send action frame: freq=2412 dst=8e:79:67:11:22:33 src=ee:a6:1a:48:55:14 bssid=ee:a6:1a:48:55:14 len=128
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Stored pending action frame (dst=8e:79:67:11:22:33 pending_action_tx=0x1615d60)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Pending TX frame - hexdump(len=128): 04 09 50 6f 9a 09 01 02 dd 4c 50 6f 9a 09 00 01 00 01 02 02 00 25 00 04 01 00 00 05 02 00 64 14 11 05 00 58 58 04 00 00 09 06 00 00 00 00 00 00 00 0b 03 00 58 58 04 0d 1c 00 ee a6 1a 48 55 14 11 88 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 00 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Send Action frame (ifindex=3, freq=2412 MHz wait=100 ms no_cck=1)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: CMD_FRAME freq=2412 wait=100 no_cck=1 no_ack=0 offchanok=1
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CMD_FRAME - hexdump(len=152): d0 00 00 00 8e 79 67 11 22 33 ee a6 1a 48 55 14 ee a6 1a 48 55 14 00 00 04 09 50 6f 9a 09 01 02 dd 4c 50 6f 9a 09 00 01 00 01 02 02 00 25 00 04 01 00 00 05 02 00 64 14 11 05 00 58 58 04 00 00 09 06 00 00 00 00 00 00 00 0b 03 00 58 58 04 0d 1c 00 ee a6 1a 48 55 14 11 88 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 00 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Frame TX command accepted; cookie 0x4d
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Control interface command 'P2P_CONNECT 8e:79:67:11:22:33 pbc'
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Determining shared radio frequencies (max len 1)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Shared frequencies (len=0): completed iteration
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Shared frequencies (len=0): valid for P2P
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Determining shared radio frequencies (max len 1)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Shared frequencies (len=0): completed iteration
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: P2P: num_unused_channels: 1
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Setup freqs: freq=0 num_MCC=1 shared_freqs=0 num_unused=1
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: best_freq=0, go=0
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: No preferred frequency list available
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Current operating channels are not available for P2P. Try to use another channel
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Own frequency preference: 0 MHz

--- BAD ONLY:
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Create a new interface p2p-0 for the group
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Create interface iftype 8 (P2P_CLIENT)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Ignored event 7 (NL80211_CMD_NEW_INTERFACE) for foreign interface (ifindex 4 wdev 0x0)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: New interface p2p-0 created: ifindex=4
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Interface p2p-0 created for P2P - disable 11b rates
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: NL80211_CMD_SET_TX_BITRATE_MASK (ifindex=4 NL80211_TXRATE_LEGACY=OFDM-only)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set TX rates failed: ret=-100 (Network is down)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Created pending virtual interface p2p-0 addr 00:1f:3f:77:88:99

--- COMMON:
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Request to start group negotiation - peer=8e:79:67:11:22:33  GO Intent=7  Intended Interface Address=00:1f:3f:77:88:99 wps_method=3 persistent_group=0 pd_before_go_neg=0 oob_pw_id=0
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Prepare channel - force_freq=0 pref_freq=0 go=0
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Prepare channel best
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Select pre-configured channel as operating channel preference
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: prepared channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: after go/cli filter/add: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Own preference for operation channel: Operating Class 81 Channel 6
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Stopping find
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear timeout (state=SEARCH)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P-FIND-STOPPED
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=3 sndbuf=180224 outq=0 send_len=17
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL_IFACE monitor sent successfully to /tmp/.miracle-wpas-2377-1\x00
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: State SEARCH -> IDLE
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear timeout (state=IDLE)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear drv_in_listen (2412)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Cancel remain-on-channel with cookie 0x4b
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Disable Probe Request reporting nl_preq=0x89e80d49
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Radio work 'p2p-listen'@0x160d390 done in 0.021470 seconds
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: radio_work_free('p2p-listen'@0x160d390): num_active_works --> 0
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Dialog Token: 1
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * P2P IE header
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Capability dev=25 group=08
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * GO Intent: Intent 7 Tie breaker 1
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Configuration Timeout: GO 100 (*10ms)  client 20 (*10ms)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Listen Channel: Regulatory Class 81 Channel 1
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Intended P2P Interface Address 00:1f:3f:77:88:99
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Channel List - hexdump(len=18): 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Device Info
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Operating Channel: Regulatory Class 81 Channel 6
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: WPS:  * Version (hardcoded 0x10)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: WPS IE Device Password ID: 4
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: WPS:  * Version2 (0x20)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Sending GO Negotiation Request
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: State IDLE -> CONNECT
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Schedule new radio work for Action frame TX (listen_freq=-1 send_freq=-1 freq=2462)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Add radio work 'p2p-send-action'@0x1611440
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: First radio work item in the queue - schedule start immediately
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL-DEBUG: global_ctrl_sock-sendto: sock=3 sndbuf=180224 outq=0 send_len=3
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Starting radio work 'p2p-send-action'@0x1611440 after 0.000711 second wait
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Send action frame: freq=2462 dst=8e:79:67:11:22:33 src=ee:a6:1a:48:55:14 bssid=8e:79:67:11:22:33 len=147
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Dropped pending Action frame TX to 8e:79:67:11:22:33 (pending_action_tx=0x1615d60)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Pending TX frame - hexdump(len=128): 04 09 50 6f 9a 09 01 02 dd 4c 50 6f 9a 09 00 01 00 01 02 02 00 25 00 04 01 00 00 05 02 00 64 14 11 05 00 58 58 04 00 00 09 06 00 00 00 00 00 00 00 0b 03 00 58 58 04 0d 1c 00 ee a6 1a 48 55 14 11 88 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 00 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Stored pending action frame (dst=8e:79:67:11:22:33 pending_action_tx=0x1612980)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Pending TX frame - hexdump(len=147): 04 09 50 6f 9a 09 00 01 dd 5f 50 6f 9a 09 02 02 00 25 08 04 01 00 0f 05 02 00 64 14 06 05 00 58 58 04 51 01 09 06 00 00 1f 3f 77 88 99 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0d 1c 00 ee a6 1a 48 55 14 00 80 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 11 05 00 58 58 04 51 06 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 04 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Send Action frame (ifindex=3, freq=2462 MHz wait=500 ms no_cck=1)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: CMD_FRAME freq=2462 wait=500 no_cck=1 no_ack=0 offchanok=1

--- BAD ONLY:
Apr 27 11:42:22 enp0s7 systemd-udevd[2456]: Using default interface naming scheme 'v245'.

--- COMMON:
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CMD_FRAME - hexdump(len=171): d0 00 00 00 8e 79 67 11 22 33 ee a6 1a 48 55 14 8e 79 67 11 22 33 00 00 04 09 50 6f 9a 09 00 01 dd 5f 50 6f 9a 09 02 02 00 25 08 04 01 00 0f 05 02 00 64 14 06 05 00 58 58 04 51 01 09 06 00 00 1f 3f 77 88 99 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0d 1c 00 ee a6 1a 48 55 14 00 80 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 11 05 00 58 58 04 51 06 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 04 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Frame TX command accepted; cookie 0x4e

--- BAD ONLY:
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: RTM_NEWLINK: ifi_index=4 ifname=p2p-0 operstate=2 linkmode=0 ifi_family=0 ifi_flags=0x1002 ()

--- COMMON:
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Event message available
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Drv Event 60 (NL80211_CMD_FRAME_TX_STATUS) received for wlp0s2f1u8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event 60 (NL80211_CMD_FRAME_TX_STATUS) on wlp0s2f1u8(ee:a6:1a:48:55:14) A1=8e:79:67:11:22:33 A2=ee:a6:1a:48:55:14
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event frame - hexdump(len=152): d0 00 00 00 8e 79 67 11 22 33 ee a6 1a 48 55 14 ee a6 1a 48 55 14 00 00 04 09 50 6f 9a 09 01 02 dd 4c 50 6f 9a 09 00 01 00 01 02 02 00 25 00 04 01 00 00 05 02 00 64 14 11 05 00 58 58 04 00 00 09 06 00 00 00 00 00 00 00 0b 03 00 58 58 04 0d 1c 00 ee a6 1a 48 55 14 11 88 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 00 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Frame TX status event
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Action TX status: cookie=0x4d (unknown) (ack=1)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Event message available
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Drv Event 56 (NL80211_CMD_CANCEL_REMAIN_ON_CHANNEL) received for wlp0s2f1u8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Remain-on-channel event (cancel=1 freq=2412 channel_type=0 duration=0 cookie=0x4b (match))
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Event CANCEL_REMAIN_ON_CHANNEL (20) received
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Cancel remain-on-channel callback (p2p_long_listen=0 ms pending_action_tx=0x1612980)
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Driver ended Listen state (freq=2412)

--- BAD ONLY:
Apr 27 11:42:22 enp0s7 systemd-udevd[2456]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Apr 27 11:42:22 enp0s7 systemd-udevd[2456]: Could not set AlternativeName= or apply AlternativeNamesPolicy= on p2p-0: File exists
Apr 27 11:42:22 enp0s7 systemd-udevd[2456]: p2p-0: Could not apply link config, ignoring: File exists

--- COMMON:
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Event message available
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Drv Event 67 (NL80211_CMD_FRAME_WAIT_CANCEL) received for wlp0s2f1u8
Apr 27 11:42:22 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: nl80211: Ignored unknown event (cmd=67)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Event message available
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Drv Event 60 (NL80211_CMD_FRAME_TX_STATUS) received for wlp0s2f1u8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event 60 (NL80211_CMD_FRAME_TX_STATUS) on wlp0s2f1u8(ee:a6:1a:48:55:14) A1=8e:79:67:11:22:33 A2=ee:a6:1a:48:55:14
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event frame - hexdump(len=171): d0 00 00 00 8e 79 67 11 22 33 ee a6 1a 48 55 14 8e 79 67 11 22 33 00 00 04 09 50 6f 9a 09 00 01 dd 5f 50 6f 9a 09 02 02 00 25 08 04 01 00 0f 05 02 00 64 14 06 05 00 58 58 04 51 01 09 06 00 00 1f 3f 77 88 99 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0d 1c 00 ee a6 1a 48 55 14 00 80 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 11 05 00 58 58 04 51 06 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 04 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Frame TX status event
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Action TX status: cookie=0x4e (match) (ack=1)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Event TX_STATUS (16) received
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: EVENT_TX_STATUS dst=8e:79:67:11:22:33 type=0 stype=13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Delete matching pending action frame (dst=8e:79:67:11:22:33 pending_action_tx=0x1612980)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Pending TX frame - hexdump(len=147): 04 09 50 6f 9a 09 00 01 dd 5f 50 6f 9a 09 02 02 00 25 08 04 01 00 0f 05 02 00 64 14 06 05 00 58 58 04 51 01 09 06 00 00 1f 3f 77 88 99 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0d 1c 00 ee a6 1a 48 55 14 00 80 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65 11 05 00 58 58 04 51 06 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 04 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: TX status result=0 cb=0x55e980
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear Action TX work @0x1611440 (wait_time=500)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Action frame TX callback (state=1 freq=2462 dst=8e:79:67:11:22:33 src=ee:a6:1a:48:55:14 bssid=8e:79:67:11:22:33 result=0 p2p_state=CONNECT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: GO Negotiation Request TX callback: success=1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: State CONNECT -> CONNECT
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Set timeout (state=CONNECT): 0.500000 sec
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Event message available
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlp0s2f1u8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlp0s2f1u8(ee:a6:1a:48:55:14) A1=ee:a6:1a:48:55:14 A2=8e:79:67:11:22:33
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event frame - hexdump(len=164): d0 00 3c 00 ee a6 1a 48 55 14 8e 79 67 11 22 33 8e 79 67 11 22 33 60 49 04 09 50 6f 9a 09 01 01 dd 58 50 6f 9a 09 00 01 00 00 02 02 00 25 00 04 01 00 00 05 02 00 64 14 09 06 00 8e 79 67 11 22 33 0b 12 00 44 45 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0d 21 00 8e 79 67 11 22 33 00 80 00 0a 00 50 f2 04 00 05 00 10 11 00 0c 5a 54 45 20 42 4c 41 44 45 20 56 37 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 04 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 10 1c 44 00 32
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Frame event
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: RX frame da=ee:a6:1a:48:55:14 sa=8e:79:67:11:22:33 bssid=8e:79:67:11:22:33 freq=2462 ssi_signal=-19 fc=0xd0 seq_ctrl=0x4960 stype=13 (WLAN_FC_STYPE_ACTION) len=164
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Event RX_MGMT (18) received
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Received Action frame: SA=8e:79:67:11:22:33 Category=4 DataLen=139 freq=2462 MHz
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: RX P2P Public Action from 8e:79:67:11:22:33
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: P2P Public Action contents - hexdump(len=134): 01 01 dd 58 50 6f 9a 09 00 01 00 00 02 02 00 25 00 04 01 00 00 05 02 00 64 14 09 06 00 8e 79 67 11 22 33 0b 12 00 44 45 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0d 21 00 8e 79 67 11 22 33 00 80 00 0a 00 50 f2 04 00 05 00 10 11 00 0c 5a 54 45 20 42 4c 41 44 45 20 56 37 dd 19 00 50 f2 04 10 4a 00 01 10 10 12 00 02 00 04 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 10 1c 44 00 32
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Received GO Negotiation Response from 8e:79:67:11:22:33 (freq=2462)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Parsing the received message
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Dialog Token: 1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Parsing WPS IE
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Device Password ID: 4
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Parsing P2P IE
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Attribute 0 length 1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Status: 0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Attribute 2 length 2
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Device Capability 25 Group Capability 00
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Attribute 4 length 1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * GO Intent: Intent 0 Tie breaker 0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Attribute 5 length 2
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Configuration Timeout
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Attribute 9 length 6
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Intended P2P Interface Address: 8e:79:67:11:22:33
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Attribute 11 length 18
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Channel List: Country String 'DE(0x04)'
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Channel List - hexdump(len=18): 44 45 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Attribute 13 length 33
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Device Info: addr 8e:79:67:11:22:33 primary device type 10-0050F204-5 device name 'ZTE BLADE V7' config methods 0x80
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Peer country - hexdump_ascii(len=3):
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]:      44 45 04                                          DE_
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Own reg_classes 1 peer reg_classes 1 intersection reg_classes 1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Peer using pushbutton
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: own channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: peer channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: intersection: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: intersection after no-GO removal: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: intersection with local channel list: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Try to optimize channel selection with peer information received; previously selected op_class 81 channel 6
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Using original operating class and channel (op_class 81 channel 6) from intersection
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: State CONNECT -> GO_NEG
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear timeout (state=GO_NEG)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: GO Negotiation with 8e:79:67:11:22:33
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Building GO Negotiation Confirm
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Dialog Token: 1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * P2P IE header
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Status: 0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Capability dev=25 group=00
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Operating Channel: Regulatory Class 81 Channel 6
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * Channel List - hexdump(len=18): 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: * P2P Group ID ee:a6:1a:48:55:14
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: P2P Group ID SSID - hexdump_ascii(len=9):
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]:      44 49 52 45 43 54 2d 4f 4d                        DIRECT-OM
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Sending GO Negotiation Confirm
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Use ongoing radio work for Action frame TX
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Send action frame: freq=2462 dst=8e:79:67:11:22:33 src=ee:a6:1a:48:55:14 bssid=8e:79:67:11:22:33 len=85
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Stored pending action frame (dst=8e:79:67:11:22:33 pending_action_tx=0x16139d0)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Pending TX frame - hexdump(len=85): 04 09 50 6f 9a 09 02 01 dd 3c 50 6f 9a 09 00 01 00 00 02 02 00 25 00 11 05 00 58 58 04 51 06 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0f 0f 00 ee a6 1a 48 55 14 44 49 52 45 43 54 2d 4f 4d dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Send Action frame (ifindex=3, freq=2462 MHz wait=50 ms no_cck=1)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: CMD_FRAME freq=2462 wait=50 no_cck=1 no_ack=0 offchanok=1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CMD_FRAME - hexdump(len=109): d0 00 00 00 8e 79 67 11 22 33 ee a6 1a 48 55 14 8e 79 67 11 22 33 00 00 04 09 50 6f 9a 09 02 01 dd 3c 50 6f 9a 09 00 01 00 00 02 02 00 25 00 11 05 00 58 58 04 51 06 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0f 0f 00 ee a6 1a 48 55 14 44 49 52 45 43 54 2d 4f 4d dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Frame TX command accepted; cookie 0x4f
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Event message available
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Drv Event 60 (NL80211_CMD_FRAME_TX_STATUS) received for wlp0s2f1u8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event 60 (NL80211_CMD_FRAME_TX_STATUS) on wlp0s2f1u8(ee:a6:1a:48:55:14) A1=8e:79:67:11:22:33 A2=ee:a6:1a:48:55:14
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: MLME event frame - hexdump(len=109): d0 00 00 00 8e 79 67 11 22 33 ee a6 1a 48 55 14 8e 79 67 11 22 33 00 00 04 09 50 6f 9a 09 02 01 dd 3c 50 6f 9a 09 00 01 00 00 02 02 00 25 00 11 05 00 58 58 04 51 06 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0f 0f 00 ee a6 1a 48 55 14 44 49 52 45 43 54 2d 4f 4d dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Frame TX status event
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Action TX status: cookie=0x4f (match) (ack=1)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Event TX_STATUS (16) received
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: EVENT_TX_STATUS dst=8e:79:67:11:22:33 type=0 stype=13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Delete matching pending action frame (dst=8e:79:67:11:22:33 pending_action_tx=0x16139d0)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Pending TX frame - hexdump(len=85): 04 09 50 6f 9a 09 02 01 dd 3c 50 6f 9a 09 00 01 00 00 02 02 00 25 00 11 05 00 58 58 04 51 06 0b 12 00 58 58 04 51 0d 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0f 0f 00 ee a6 1a 48 55 14 44 49 52 45 43 54 2d 4f 4d dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: TX status result=0 cb=0x55e980
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear Action TX work @0x1611440 (wait_time=500)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Action frame TX callback (state=4 freq=2462 dst=8e:79:67:11:22:33 src=ee:a6:1a:48:55:14 bssid=8e:79:67:11:22:33 result=0 p2p_state=GO_NEG)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: GO Negotiation Confirm TX callback: result=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: Radio work 'p2p-send-action'@0x1611440 done in 1.505367 seconds
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: radio_work_free('p2p-send-action'@0x1611440): num_active_works --> 0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Action frame sequence done notification: pending_action_tx=(nil) drv_offchan_tx=1 action_tx_wait_time=50 off_channel_freq=0 roc_waiting_drv_freq=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Cancel TX frame wait: cookie=0x4f
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Cancel TX frame wait: cookie=0x4e
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Cancel TX frame wait: cookie=0x4d
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: wait cancel failed: ret=-2 (No such file or directory)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: GO Negotiation with 8e:79:67:11:22:33 completed (local end will be GO)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: own channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: peer channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: intersection after no-GO removal: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear timeout (state=GO_NEG)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: State GO_NEG -> PROVISIONING
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P-GO-NEG-SUCCESS role=GO freq=2437 ht40=0 peer_dev=8e:79:67:11:22:33 peer_iface=8e:79:67:11:22:33 wps_method=PBC
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=3 sndbuf=180224 outq=0 send_len=114
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL_IFACE monitor sent successfully to /tmp/.miracle-wpas-2377-1\x00

--- BAD ONLY:
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Initializing interface 'p2p-0' conf 'N/A' driver 'nl80211' ctrl_interface '/run/miracle/wifi' bridge 'N/A'
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: TDLS supported
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: TDLS external setup
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:5
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:2
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:4
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:10
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:9
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:6
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:11
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Supported cipher 00-0f-ac:12
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Using driver-based off-channel TX
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Driver-advertised extended capabilities (default) - hexdump(len=8): 00 00 00 00 00 00 00 40
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Driver-advertised extended capabilities mask (default) - hexdump(len=8): 00 00 00 00 00 00 00 40
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Use separate P2P group interface (driver advertised support)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: interface p2p-0 in phy phy0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set mode ifindex 4 iftype 8 (P2P_CLIENT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Interface p2p-0 mode change to P2P - disable 11b rates
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: NL80211_CMD_SET_TX_BITRATE_MASK (ifindex=4 NL80211_TXRATE_LEGACY=OFDM-only)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set TX rates failed: ret=-100 (Network is down)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Subscribe to mgmt frames with non-AP handle 0x1612a40
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=040a
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=040b
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=040c
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=040d
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=090a
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=090b
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=090c
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=090d
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=0409506f9a09
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=7f506f9a09
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=0801
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=040e
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=06
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=0a07
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=0a11
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=1101
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=1102
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=0505
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1612a40 match=0500
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: rfkill: initial event: idx=0 type=1 op=0 soft=0 hard=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: NL80211_CMD_SET_TX_BITRATE_MASK (ifindex=4 NL80211_TXRATE_LEGACY=OFDM-only)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: netlink: Operstate: ifindex=4 linkmode=1 (userspace-control), operstate=5 (IF_OPER_DORMANT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: driver param='p2p_device=1'
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Add interface p2p-0 to existing radio phy0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Regulatory information - country=DE (DFS-ETSI)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: 2400-2483 @ 40 MHz 20 mBm
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: 5150-5250 @ 80 MHz 23 mBm (no outdoor)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: 5250-5350 @ 80 MHz 20 mBm (no outdoor) (DFS)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: 5470-5725 @ 160 MHz 26 mBm (DFS)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: 5725-5875 @ 80 MHz 13 mBm
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: 57000-66000 @ 2160 MHz 40 mBm
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Added 802.11b mode based on 802.11g information
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Mode IEEE 802.11g: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484[DISABLED]
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Mode IEEE 802.11a: 4920[DISABLED] 4940[DISABLED] 4960[DISABLED] 4980[DISABLED] 5040[DISABLED] 5060[DISABLED] 5080[DISABLED] 5180 5200 5220 5240 5260[NO_IR][RADAR] 5280[NO_IR][RADAR] 5300[NO_IR][RADAR]
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Mode IEEE 802.11b: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484[DISABLED]
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Own MAC address: 00:1f:3f:77:88:99
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wpa_driver_nl80211_set_key: ifindex=4 (p2p-0) alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wpa_driver_nl80211_set_key: ifindex=4 (p2p-0) alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wpa_driver_nl80211_set_key: ifindex=4 (p2p-0) alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wpa_driver_nl80211_set_key: ifindex=4 (p2p-0) alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wpa_driver_nl80211_set_key: ifindex=4 (p2p-0) alg=0 addr=(nil) key_idx=4 set_tx=0 seq_len=0 key_len=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wpa_driver_nl80211_set_key: ifindex=4 (p2p-0) alg=0 addr=(nil) key_idx=5 set_tx=0 seq_len=0 key_len=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: RSN: flushing PMKID list in the driver
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Flush PMKIDs
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: State: DISCONNECTED -> INACTIVE
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Determining shared radio frequencies (max len 1)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Shared frequencies (len=0): completed iteration
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Add operating class 81
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Channels - hexdump(len=13): 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Update channel list
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: cli_channels:
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: TDLS: TDLS operation supported by driver
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: TDLS: Driver uses external link setup
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: TDLS: Driver does not support TDLS channel switching
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: WPS: UUID from the first interface: a53636bc-7210-5da1-8255-65ddd9e69a12
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: ENGINE: Loading builtin engines
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: ENGINE: Loading builtin engines
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAPOL: SUPP_PAE entering state DISCONNECTED
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAPOL: Supplicant port status: Unauthorized
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Skip set_supp_port(unauthorized) while not associated
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAPOL: SUPP_BE entering state INITIALIZE
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAP: EAP entering state DISABLED
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Using existing control interface directory.
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Added interface p2p-0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: State: INACTIVE -> DISCONNECTED
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set p2p-0 operstate 0->0 (DORMANT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Determining shared radio frequencies (max len 1)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Shared frequencies (len=0): completed iteration
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Add operating class 81
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Channels - hexdump(len=13): 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Update channel list
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: cli_channels:

--- COMMON:
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: P2P: Starting GO
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: PSK (from passphrase) - hexdump(len=32): [REMOVED]
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: P2P: Request scan (that will be skipped) to start GO)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Setting scan request: 0.000000 sec
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Start a pre-selected network without scan step
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Setting up AP (SSID='DIRECT-OM')
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Setup AP operations for P2P group (GO)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set mode ifindex 4 iftype 9 (P2P_GO)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Failed to set interface 4 to mode 9: -16 (Device or resource busy)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Try mode change after setting interface down
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set mode ifindex 4 iftype 9 (P2P_GO)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Mode change succeeded while interface is down




----- NOW "BAD" FAILS, "GOOD" CONTINUES:

--- BAD:
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Could not set interface p2p-0 flags (UP): Device or resource busy
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Failed to set interface up after switching mode
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Interface mode change to 9 from 9 failed
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Remove monitor interface: refcount=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Failed to start AP functionality
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=3 sndbuf=180224 outq=0 send_len=32
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL_IFACE monitor sent successfully to /tmp/.miracle-wpas-2377-1\x00
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: State: DISCONNECTED -> DISCONNECTED
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set p2p-0 operstate 0->0 (DORMANT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: RTM_NEWLINK: ifi_index=4 ifname=p2p-0 operstate=0 linkmode=0 ifi_family=0 ifi_flags=0x1043 ([UP][RUNNING])
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: RTM_NEWLINK: ifi_index=4 ifname=p2p-0 operstate=2 linkmode=0 ifi_family=0 ifi_flags=0x1003 ([UP])
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: RTM_NEWLINK: ifi_index=4 ifname=p2p-0 operstate=2 linkmode=1 ifi_family=0 ifi_flags=0x1003 ([UP])
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: RTM_NEWLINK: ifi_index=4 ifname=p2p-0 operstate=2 linkmode=1 ifi_family=0 ifi_flags=0x1002 ()
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Interface down (p2p-0/p2p-0)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Ignore interface down event generated by mode change
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Event message available
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Ignored event 67 (NL80211_CMD_FRAME_WAIT_CANCEL) for foreign interface (ifindex 3 wdev 0x0)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Drv Event 67 (NL80211_CMD_FRAME_WAIT_CANCEL) received for wlp0s2f1u8
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: wlp0s2f1u8: nl80211: Ignored unknown event (cmd=67)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Group Formation timed out
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Group Formation failed with 8e:79:67:11:22:33
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Clear timeout (state=PROVISIONING)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: State PROVISIONING -> IDLE
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: P2P: Stop provisioning state due to failure
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P-GROUP-FORMATION-FAILURE
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=3 sndbuf=180224 outq=0 send_len=28
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL_IFACE monitor sent successfully to /tmp/.miracle-wpas-2377-1\x00
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P-GROUP-REMOVED p2p-0 GO reason=FORMATION_FAILED
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=3 sndbuf=180224 outq=704 send_len=50
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: CTRL_IFACE monitor sent successfully to /tmp/.miracle-wpas-2377-1\x00
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: P2P: Remove group interface p2p-0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Removing interface p2p-0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Request to deauthenticate - bssid=00:00:00:00:00:00 pending_bssid=00:00:00:00:00:00 reason=3 (DEAUTH_LEAVING) state=DISCONNECTED
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: TDLS: Tear down peers
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: State: DISCONNECTED -> DISCONNECTED
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set p2p-0 operstate 0->0 (DORMANT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAPOL: External notification - portEnabled=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAPOL: External notification - portValid=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: EAPOL: External notification - EAP success=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: WPA: Clear old PMK and PTK
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Cancelling scan request
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: p2p-0: Cancelling authentication timeout
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Off-channel: Clear pending Action frame TX (pending_action_tx=(nil)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Remove interface p2p-0 from radio phy0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: deinit ifname=p2p-0 disabled_11b_rates=1
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Remove monitor interface: refcount=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Remove beacon (ifindex=4)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: NL80211_CMD_SET_TX_BITRATE_MASK (ifindex=4 no NL80211_TXRATE_LEGACY constraint)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set TX rates failed: ret=-100 (Network is down)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: netlink: Operstate: ifindex=4 linkmode=0 (kernel-control), operstate=6 (IF_OPER_UP)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set mode ifindex 4 iftype 2 (STATION)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Interface p2p-0 mode changed to non-P2P - re-enable 11b rates
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: NL80211_CMD_SET_TX_BITRATE_MASK (ifindex=4 no NL80211_TXRATE_LEGACY constraint)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Set TX rates failed: ret=-100 (Network is down)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Teardown AP(p2p-0) - device_ap_sme=0 use_monitor=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Unsubscribe mgmt frames handle 0x89e9a2c9 (AP teardown)
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: Control interface directory not empty - leaving it behind
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: wpa_driver_nl80211_if_remove(type=3 ifname=p2p-0) ifindex=4 added_if=0
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: Remove interface ifindex=4
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: if_indices[16]:
Apr 27 11:42:23 enp0s7 miracle-wifid-wlp0s2f1u8-3[2379]: nl80211: if_indices[16]:
...


--- GOOD:
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Interface wlp0s2f1u8 mode change to P2P - disable 11b rates
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: NL80211_CMD_SET_TX_BITRATE_MASK (ifindex=3 NL80211_TXRATE_LEGACY=OFDM-only)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Unsubscribe mgmt frames handle 0x8920ddd9 (start AP)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Setup AP(wlp0s2f1u8) - device_ap_sme=0 use_monitor=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Subscribe to mgmt frames with AP handle 0x1a85550
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xb0 (WLAN_FC_STYPE_AUTH) nl_handle=0x1a85550 match=
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0x0 (WLAN_FC_STYPE_ASSOC_REQ) nl_handle=0x1a85550 match=
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0x20 (WLAN_FC_STYPE_REASSOC_REQ) nl_handle=0x1a85550 match=
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xa0 (WLAN_FC_STYPE_DISASSOC) nl_handle=0x1a85550 match=
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xc0 (WLAN_FC_STYPE_DEAUTH) nl_handle=0x1a85550 match=
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0x40 (WLAN_FC_STYPE_PROBE_REQ) nl_handle=0x1a85550 match=
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=04
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=0501
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=0503
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=0504
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=06
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=08
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=09
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=0a
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=11
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0x1a85550 match=7f
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Set freq 2437 (ht_enabled=0, vht_enabled=0, he_enabled=0, bandwidth=0 MHz, cf1=0 MHz, cf2=0 MHz)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * freq=2437
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * he_enabled=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * vht_enabled=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * ht_enabled=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * channel_type=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Determining HT/VHT options based on driver capabilities (freq=2437 chan=6)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Enable HT support (p2p_group=1 11a=0 ht40_hw_capab=1 ssid->ht40=0)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: * Capability dev=25 group=49
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: * Device Info
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: Update GO Probe Response P2P IE - hexdump(len=57): dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8 dd 28 50 6f 9a 09 02 02 00 25 49 0d 1c 00 b2 35 85 a9 54 3d 11 88 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: * P2P IE header
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: * Capability dev=25 group=49
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: * Device ID: b2:35:85:a9:54:3d
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: Update GO Beacon P2P IE - hexdump(len=35): dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8 dd 12 50 6f 9a 09 02 02 00 25 49 03 06 00 b2 35 85 a9 54 3d
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: GO - group idle
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: phy: phy0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Regulatory information - country=00
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 2402-2472 @ 40 MHz 20 mBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 2457-2482 @ 20 MHz 20 mBm (no IR)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 2474-2494 @ 20 MHz 20 mBm (no OFDM) (no IR)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 5170-5250 @ 80 MHz 20 mBm (no IR)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 5250-5330 @ 80 MHz 20 mBm (DFS) (no IR)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 5490-5730 @ 160 MHz 20 mBm (DFS) (no IR)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 5735-5835 @ 80 MHz 20 mBm (no IR)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: 57240-63720 @ 2160 MHz 0 mBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Added 802.11b mode based on 802.11g information
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Mode IEEE 802.11g: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484[DISABLED]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Mode IEEE 802.11a: 4920[DISABLED] 4940[DISABLED] 4960[DISABLED] 4980[DISABLED] 5040[DISABLED] 5060[DISABLED] 5080[DISABLED] 5180 5200 5220 5240 5260[NO_IR][RADAR] 5280[NO_IR][RADAR] 5300[NO_IR][RADAR]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Mode IEEE 802.11b: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484[DISABLED]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=12 freq=2467 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=1 chan=13 freq=2472 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=36 freq=5180 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=40 freq=5200 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=44 freq=5220 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=48 freq=5240 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=149 freq=5745 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=153 freq=5765 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=157 freq=5785 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=161 freq=5805 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=165 freq=5825 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=34 freq=5170 MHz max_tx_power=0 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=38 freq=5190 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=42 freq=5210 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=2 chan=46 freq=5230 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=12 freq=2467 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Allowed channel: mode=0 chan=13 freq=2472 MHz max_tx_power=20 dBm
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Completing interface initialization
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Mode: IEEE 802.11g  Channel: 6  Frequency: 2437 MHz
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: DFS 0 channels required radar detection
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Set freq 2437 (ht_enabled=1, vht_enabled=0, he_enabled=0, bandwidth=20 MHz, cf1=2437 MHz, cf2=0 MHz)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * freq=2437
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * he_enabled=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * vht_enabled=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * ht_enabled=1
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * sec_channel_offset=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * channel_type=1
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[0] rate=60 flags=0x1
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[1] rate=90 flags=0x0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[2] rate=120 flags=0x1
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[3] rate=180 flags=0x0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[4] rate=240 flags=0x1
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[5] rate=360 flags=0x0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[6] rate=480 flags=0x0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: RATE[7] rate=540 flags=0x0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: hostapd_setup_bss(hapd=0x1aa35e0 (wlp0s2f1u8), first=1)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: Flushing old station entries
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: flush -> DEL_STATION wlp0s2f1u8 (all)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: Deauthenticate all stations
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: 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=9
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: send_mlme -> send_frame
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: send_frame - Use bss->freq=2437
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: send_frame -> send_frame_cmd
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: CMD_FRAME freq=2437 wait=0 no_cck=0 no_ack=0 offchanok=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: CMD_FRAME - hexdump(len=26): c0 00 00 00 ff ff ff ff ff ff b2 35 85 a9 54 3d b2 35 85 a9 54 3d 00 00 02 00
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Frame command failed: ret=-16 (Device or resource busy) (freq=2437 wait=0)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wpa_driver_nl80211_set_key: ifindex=3 (wlp0s2f1u8) alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wpa_driver_nl80211_set_key: ifindex=3 (wlp0s2f1u8) alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wpa_driver_nl80211_set_key: ifindex=3 (wlp0s2f1u8) alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wpa_driver_nl80211_set_key: ifindex=3 (wlp0s2f1u8) alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Using interface wlp0s2f1u8 with hwaddr b2:35:85:a9:54:3d and ssid "DIRECT-LG"
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS: Use configured UUID - hexdump(len=16): 31 02 66 ea fd 1c 5a 04 b1 d6 a1 67 04 f7 b8 7c
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS: Build Beacon IEs
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Version (hardcoded 0x10)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Wi-Fi Protected Setup State (2)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Version2 (0x20)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Device Name
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Primary Device Type
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS: Build Probe Response IEs
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Version (hardcoded 0x10)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Wi-Fi Protected Setup State (2)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Response Type (3)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * UUID-E
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Manufacturer
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Model Name
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Model Number
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Serial Number
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Primary Device Type
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Device Name
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Config Methods (0)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Version2 (0x20)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: GMK - hexdump(len=32): [REMOVED]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: Key Counter - hexdump(len=32): [REMOVED]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPA: Delay group state machine start until Beacon frames have been configured
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS: Building WPS IE for (Re)Association Response
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Version (hardcoded 0x10)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Response Type (3)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPS:  * Version2 (0x20)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: * P2P IE header
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Set beacon (beacon_set=0)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Beacon head - hexdump(len=60): 80 00 00 00 ff ff ff ff ff ff b2 35 85 a9 54 3d b2 35 85 a9 54 3d 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 09 44 49 52 45 43 54 2d 4c 47 01 08 8c 12 98 24 b0 48 60 6c 03 01 06
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Beacon tail - hexdump(len=197): 2a 01 04 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00 2d 1a 4c 08 1b ff ff 00 00 01 00 00 00 00 00 2c 01 01 00 00 00 00 00 00 00 00 00 00 3d 16 06 00 00 00 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 18 00 50 f2 02 01 01 01 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00 dd 2f 00 50 f2 04 10 4a 00 01 10 10 44 00 01 02 10 49 00 06 00 37 2a 00 01 20 10 11 00 07 4d 69 72 61 63 6c 65 10 54 00 08 00 01 00 50 f2 04 00 01 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8 dd 12 50 6f 9a 09 02 02 00 25 49 03 06 00 b2 35 85 a9 54 3d
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: ifindex=3
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: beacon_int=100
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: beacon_rate=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: rate_type=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: dtim_period=2
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: ssid=DIRECT-LG
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * beacon_int=100
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:   * dtim_period=2
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: hidden SSID not in use
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: privacy=1
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: auth_algs=0x1
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: wpa_version=0x2
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: key_mgmt_suites=0x2
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: pairwise_ciphers=0x10
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: group_cipher=0x10
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: SMPS mode - off
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: beacon_ies - hexdump(len=94): 7f 08 00 00 00 00 00 00 00 40 dd 2f 00 50 f2 04 10 4a 00 01 10 10 44 00 01 02 10 49 00 06 00 37 2a 00 01 20 10 11 00 07 4d 69 72 61 63 6c 65 10 54 00 08 00 01 00 50 f2 04 00 01 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8 dd 12 50 6f 9a 09 02 02 00 25 49 03 06 00 b2 35 85 a9 54 3d
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: proberesp_ies - hexdump(len=167): 7f 08 00 00 00 00 00 00 00 40 dd 62 00 50 f2 04 10 4a 00 01 10 10 44 00 01 02 10 3b 00 01 03 10 47 00 10 31 02 66 ea fd 1c 5a 04 b1 d6 a1 67 04 f7 b8 7c 10 21 00 01 20 10 23 00 01 20 10 24 00 01 20 10 42 00 01 20 10 54 00 08 00 01 00 50 f2 04 00 01 10 11 00 07 4d 69 72 61 63 6c 65 10 08 00 02 00 00 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8 dd 28 50 6f 9a 09 02 02 00 25 49 0d 1c 00 b2 35 85 a9 54 3d 11 88 00 01 00 50 f2 04 00 01 00 10 11 00 07 4d 69 72 61 63 6c 65
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: assocresp_ies - hexdump(len=57): 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 04 10 4a 00 01 10 10 3b 00 01 03 10 49 00 06 00 37 2a 00 01 20 dd 0d 50 6f 9a 0a 00 00 06 00 11 1c 44 00 c8 dd 04 50 6f 9a 09
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: multicast to unicast disabled on interface wlp0s2f1u8
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPA: Start group state machine to set initial keys
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPA: group state machine entering state GTK_INIT (VLAN-ID 0)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: GTK - hexdump(len=16): [REMOVED]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wpa_driver_nl80211_set_key: ifindex=3 (wlp0s2f1u8) alg=3 addr=0x590b39 key_idx=1 set_tx=1 seq_len=0 key_len=16
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: KEY_DATA - hexdump(len=16): [REMOVED]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]:    broadcast key
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Set wlp0s2f1u8 operstate 0->1 (UP)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: netlink: Operstate: ifindex=3 linkmode=-1 (no change), operstate=6 (IF_OPER_UP)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: TX queue param set: queue=0 aifs=1 cw_min=3 cw_max=7 burst_time=15 --> res=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: TX queue param set: queue=1 aifs=1 cw_min=7 cw_max=15 burst_time=30 --> res=0
Apr 27 12:05:24 enp0s7 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp0s2f1u8: link becomes ready
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: TX queue param set: queue=2 aifs=3 cw_min=15 cw_max=63 burst_time=0 --> res=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: TX queue param set: queue=3 aifs=7 cw_min=15 cw_max=1023 burst_time=0 --> res=0
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: interface state UNINITIALIZED->ENABLED
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: AP-ENABLED
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=3 sndbuf=180224 outq=0 send_len=11
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: CTRL_IFACE monitor sent successfully to /tmp/.miracle-wpas-2488-1\x00
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: AP interface setup completed - state ENABLED
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: State: DISCONNECTED -> COMPLETED
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: CTRL-EVENT-CONNECTED - Connection to b2:35:85:a9:54:3d completed [id=0 id_str=]
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=3 sndbuf=180224 outq=0 send_len=79
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: CTRL_IFACE monitor sent successfully to /tmp/.miracle-wpas-2488-1\x00
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: nl80211: Set wlp0s2f1u8 operstate 1->1 (UP)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: netlink: Operstate: ifindex=3 linkmode=-1 (no change), operstate=6 (IF_OPER_UP)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: Determining shared radio frequencies (max len 1)
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: Shared frequencies (len=0): completed iteration
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: Add operating class 81
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: Channels - hexdump(len=13): 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: Update channel list
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: channels: 81:1,2,3,4,5,6,7,8,9,10,11,12,13
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: cli_channels:
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: wlp0s2f1u8: P2P: Common group frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: P2P: Setting up WPS for GO provisioning
Apr 27 12:05:24 enp0s7 miracle-wifid-wlp0s2f1u8-3[2501]: AP: Set MAC address filter: 8e:79:67:11:22:33
...

^ permalink raw reply	[flat|nested] 6+ messages in thread

* Re: [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces
  2020-04-27 16:53 ` Frank Schäfer
@ 2020-04-29 16:22   ` Frank Schäfer
  2020-05-05 15:04     ` Frank Schäfer
  0 siblings, 1 reply; 6+ messages in thread
From: Frank Schäfer @ 2020-04-29 16:22 UTC (permalink / raw)
  To: chunkeey, j; +Cc: linux-wireless


Am 27.04.20 um 18:53 schrieb Frank Schäfer:
>
> Am 18.04.20 um 13:20 schrieb Frank Schäfer:
>> Hi,
>>
>> I'm currently testing Miraclecast 
>> (https://github.com/albfan/miraclecast), an open source 
>> Wifi-Display/Miracast implementation.
>> In one of my setups, I'm using a carl9170 device as sink, which fails 
>> with the following debugging output:
>>
>> ...
>> TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
>> TRACE: wpa: raw message: <3>P2P-PROV-DISC-PBC-REQ 8e:79:67:11:22:33 
>> p2p_dev_addr=8e:79:67:11:22:33 pri_dev_type=10-0050F204-5 name='MY 
>> DEVICE' config_methods=0x188 dev_capab=0x25 group_capab=0x0
>> TRACE: wpa: raw message: <3>P2P-GO-NEG-REQUEST 8e:79:67:11:22:33 
>> dev_passwd_id=4 go_intent=0
>> DEBUG: supplicant: GO Negotiation Request from 8e:79:67:11:22:33 
>> (supplicant_event_p2p_go_neg_request() in 
>> ../../../src/wifi/wifid-supplicant.c:1065)
>> DEBUG: supplicant: connect to 8e:79:67:11:22:33 via pbc/(null) 
>> (supplicant_peer_connect() in ../../../src/wifi/wifid-supplicant.c:739)
>> TRACE: wpa: raw message: <3>P2P-FIND-STOPPED
>> DEBUG: supplicant: p2p-scanning stopped on wlp0s2f1u8 
>> (supplicant_event_p2p_find_stopped() in 
>> ../../../src/wifi/wifid-supplicant.c:904)
>> TRACE: wpa: raw message: OK
>>
>> TRACE: wpa: raw message: <3>P2P-GO-NEG-SUCCESS role=GO freq=2462 
>> ht40=0 peer_dev=8e:79:67:11:22:33 peer_iface=8e:79:67:11:22:33 
>> wps_method=PBC
>> DEBUG: supplicant: set STA-MAC for 8e:79:67:11:22:33 from <none> to 
>> 8e:79:67:11:22:33 (via GO-NEG-SUCCESS) 
>> (supplicant_event_p2p_go_neg_success() in 
>> ../../../src/wifi/wifid-supplicant.c:1194)
>> TRACE: wpa: raw message: IFNAME=p2p-0 <3>Failed to start AP 
>> functionality
>> DEBUG: supplicant: unhandled wpas-event: IFNAME=p2p-0 <3>Failed to 
>> start AP functionality (supplicant_event() in 
>> ../../../src/wifi/wifid-supplicant.c:1498)
>> TRACE: wpa: raw message: <3>P2P-GROUP-FORMATION-FAILURE
>> DEBUG: supplicant: peer MY DEVICE connection failed 
>> (supplicant_event_p2p_group_formation_failure() in 
>> ../../../src/wifi/wifid-supplicant.c:1318)
>> TRACE: wpa: raw message: <3>P2P-GROUP-REMOVED p2p-0 GO 
>> reason=FORMATION_FAILED
>> DEBUG: supplicant: stray P2P-GROUP-REMOVED: <3>P2P-GROUP-REMOVED 
>> p2p-0 GO reason=FORMATION_FAILED 
>> (supplicant_event_p2p_group_removed() in 
>> ../../../src/wifi/wifid-supplicant.c:1286)
>> DEBUG: supplicant: sent P2P_FIND to wpas on wlp0s2f1u8 
>> (supplicant_p2p_start_scan() in 
>> ../../../src/wifi/wifid-supplicant.c:2036)
>> TRACE: wpa: raw message: OK
>>
>> DEBUG: supplicant: p2p-scanning now active on wlp0s2f1u8 
>> (supplicant_p2p_find_fn() in ../../../src/wifi/wifid-supplicant.c:1917)
>> TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
>> ...
>>
>>
>> Searching the web for similar problems leads to the following thread:
>> https://marc.info/?l=hostap&m=135336063022534
>>
>> As suggested there, I have patched the Miraclecast sources to use the 
>> wpa_supplicant configuration parameter p2p_no_group_iface=1.
>> So far, this works fine.
>>
>>
>> Regards,
>> Frank Schäfer
>>
>
> I finally managed to create extended logs and to compare the working 
> ("GOOD") and non-working ("BAD") case.
> See attachment.
> If anything is missing, please let me know.
>
> Hth,
> Frank
>

Ok, this is what I can see happening in carl9170 with regards to the 
interfaces:

1) 1 interface is added+initialized: NL80211_IFTYPE_STATION, p2p=false 
(when miracle-wifid is started)

Non-working case (p2p_no_group_iface=0=default):
2a) 1 interface is added: NL80211_IFTYPE_STATION, p2p=true (apparently 
the p2p group interface)
2b) 1 interface is removed: NL80211_IFTYPE_STATION, p2p=true (apparently 
because setting of the interface flags failed with -EBUSY)
3) 1 interface is added+initialized: NL80211_IFTYPE_AP, p2p=true
    => fails with -EBUSY, because main_vif->p2p is false (main.c, line 656)

Working case (p2p_no_group_iface=1):
2) 1 interface is removed: NL80211_IFTYPE_STATION, p2p=false
3) 1 interface is added+initialized: NL80211_IFTYPE_AP, p2p=true

So if p2p_no_group_iface=1 is used, the initial STA non-p2p interface is 
removed before a new AP+p2p interface is added.
Maybe the same needs to be done in case of p2p_no_group_iface=0 ?

Regards,
Frank



^ permalink raw reply	[flat|nested] 6+ messages in thread

* Re: [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces
  2020-04-29 16:22   ` Frank Schäfer
@ 2020-05-05 15:04     ` Frank Schäfer
  0 siblings, 0 replies; 6+ messages in thread
From: Frank Schäfer @ 2020-05-05 15:04 UTC (permalink / raw)
  To: chunkeey, j; +Cc: linux-wireless


Christian, Jouni,

I have tracked this down as far as I can get without some help.
It would be nice if you could take a closer look at the results - see 
questions below.


Am 29.04.20 um 18:22 schrieb Frank Schäfer:
>
> Am 27.04.20 um 18:53 schrieb Frank Schäfer:
>>
>> Am 18.04.20 um 13:20 schrieb Frank Schäfer:
>>> Hi,
>>>
>>> I'm currently testing Miraclecast 
>>> (https://github.com/albfan/miraclecast), an open source 
>>> Wifi-Display/Miracast implementation.
>>> In one of my setups, I'm using a carl9170 device as sink, which 
>>> fails with the following debugging output:
>>>
>>> ...
>>> TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
>>> TRACE: wpa: raw message: <3>P2P-PROV-DISC-PBC-REQ 8e:79:67:11:22:33 
>>> p2p_dev_addr=8e:79:67:11:22:33 pri_dev_type=10-0050F204-5 name='MY 
>>> DEVICE' config_methods=0x188 dev_capab=0x25 group_capab=0x0
>>> TRACE: wpa: raw message: <3>P2P-GO-NEG-REQUEST 8e:79:67:11:22:33 
>>> dev_passwd_id=4 go_intent=0
>>> DEBUG: supplicant: GO Negotiation Request from 8e:79:67:11:22:33 
>>> (supplicant_event_p2p_go_neg_request() in 
>>> ../../../src/wifi/wifid-supplicant.c:1065)
>>> DEBUG: supplicant: connect to 8e:79:67:11:22:33 via pbc/(null) 
>>> (supplicant_peer_connect() in ../../../src/wifi/wifid-supplicant.c:739)
>>> TRACE: wpa: raw message: <3>P2P-FIND-STOPPED
>>> DEBUG: supplicant: p2p-scanning stopped on wlp0s2f1u8 
>>> (supplicant_event_p2p_find_stopped() in 
>>> ../../../src/wifi/wifid-supplicant.c:904)
>>> TRACE: wpa: raw message: OK
>>>
>>> TRACE: wpa: raw message: <3>P2P-GO-NEG-SUCCESS role=GO freq=2462 
>>> ht40=0 peer_dev=8e:79:67:11:22:33 peer_iface=8e:79:67:11:22:33 
>>> wps_method=PBC
>>> DEBUG: supplicant: set STA-MAC for 8e:79:67:11:22:33 from <none> to 
>>> 8e:79:67:11:22:33 (via GO-NEG-SUCCESS) 
>>> (supplicant_event_p2p_go_neg_success() in 
>>> ../../../src/wifi/wifid-supplicant.c:1194)
>>> TRACE: wpa: raw message: IFNAME=p2p-0 <3>Failed to start AP 
>>> functionality
>>> DEBUG: supplicant: unhandled wpas-event: IFNAME=p2p-0 <3>Failed to 
>>> start AP functionality (supplicant_event() in 
>>> ../../../src/wifi/wifid-supplicant.c:1498)
>>> TRACE: wpa: raw message: <3>P2P-GROUP-FORMATION-FAILURE
>>> DEBUG: supplicant: peer MY DEVICE connection failed 
>>> (supplicant_event_p2p_group_formation_failure() in 
>>> ../../../src/wifi/wifid-supplicant.c:1318)
>>> TRACE: wpa: raw message: <3>P2P-GROUP-REMOVED p2p-0 GO 
>>> reason=FORMATION_FAILED
>>> DEBUG: supplicant: stray P2P-GROUP-REMOVED: <3>P2P-GROUP-REMOVED 
>>> p2p-0 GO reason=FORMATION_FAILED 
>>> (supplicant_event_p2p_group_removed() in 
>>> ../../../src/wifi/wifid-supplicant.c:1286)
>>> DEBUG: supplicant: sent P2P_FIND to wpas on wlp0s2f1u8 
>>> (supplicant_p2p_start_scan() in 
>>> ../../../src/wifi/wifid-supplicant.c:2036)
>>> TRACE: wpa: raw message: OK
>>>
>>> DEBUG: supplicant: p2p-scanning now active on wlp0s2f1u8 
>>> (supplicant_p2p_find_fn() in ../../../src/wifi/wifid-supplicant.c:1917)
>>> TRACE: wpa: raw message: IFNAME=wlp0s2f1u8 <3>CTRL-EVENT-SCAN-STARTED
>>> ...
>>>
>>>
>>> Searching the web for similar problems leads to the following thread:
>>> https://marc.info/?l=hostap&m=135336063022534
>>>
>>> As suggested there, I have patched the Miraclecast sources to use 
>>> the wpa_supplicant configuration parameter p2p_no_group_iface=1.
>>> So far, this works fine.
>>>
>>>
>>> Regards,
>>> Frank Schäfer
>>>
>>
>> I finally managed to create extended logs and to compare the working 
>> ("GOOD") and non-working ("BAD") case.
>> See attachment.
>> If anything is missing, please let me know.
>>
>> Hth,
>> Frank
>>
>
> Ok, this is what I can see happening in carl9170 with regards to the 
> interfaces:
>
> 1) 1 interface is added+initialized: NL80211_IFTYPE_STATION, p2p=false 
> (when miracle-wifid is started)
>
> Non-working case (p2p_no_group_iface=0=default):
> 2a) 1 interface is added: NL80211_IFTYPE_STATION, p2p=true (apparently 
> the p2p group interface)
> 2b) 1 interface is removed: NL80211_IFTYPE_STATION, p2p=true 
> (apparently because setting of the interface flags failed with -EBUSY)
> 3) 1 interface is added+initialized: NL80211_IFTYPE_AP, p2p=true
>    => fails with -EBUSY, because main_vif->p2p is false (main.c, line 
> 656)
>
> Working case (p2p_no_group_iface=1):
> 2) 1 interface is removed: NL80211_IFTYPE_STATION, p2p=false
> 3) 1 interface is added+initialized: NL80211_IFTYPE_AP, p2p=true

UPDATE (removed some confusion due to async output to the log, added 
disconnection/exit):

1) STA non-p2p interface is added+initialized (miracle-wifid start)

Connection attempt:

BAD case (p2p_no_group_iface=0=default):
2a) STA p2p interface is added (p2p group interface)
2b) STA p2p interface is removed (p2p group interface)
3) AP p2p interface is added: fails with -EBUSY (carl9170 main.c, line 
654), because main_vif->p2p is false in line 648
=> connection fails

GOOD case (p2p_no_group_iface=1):
2) STA non-p2p interface is removed
3) AP p2p interface is added+initialized
=> connection established, streaming

Disconnection (GOOD case (p2p_no_group_iface=1)):
4) AP p2p interface is removed
5) STA non-p2p interface is added+initialized

miracle-wifid exit:
6) STA non-p2p is interface is removed


So there are two things to investigate further: BAD 3) and GOOD 2)

@Christian:
Can you please check if the behavior of carl9170 (BAD 3)) is correct ?
Reading the comments and the rest of the code in the switch 
(main_vif->type) {} section, I wonder if the main_vif->p2p check in line 
654 is correct/required.
Isn't all we have to make sure, that the AP interface always becomes the 
main interface ? Why does main_vif->p2p matter here ?
A few lines below (660/661), STA interfaces are always added if the main 
interface is a AP interface, no matter if any of them is p2p or not.

@Jouni:
Why does wpa_supplicant remove the STA non-p2p interface before creating 
the AP p2p interface if p2p_no_group_iface=1, but not if 
p2p_no_group_iface=0 ?
That seems to be inconsistent.
In case the driver doesn't support concurrent interfaces, the behavior 
of p2p_no_group_iface=1 seems to make things work.
But I don't know if there might be any disadvantages.
In my case, the removed interface is added again later (5)) and 
everything works fine.

Regards,
Frank


^ permalink raw reply	[flat|nested] 6+ messages in thread

end of thread, other threads:[~2020-05-05 15:03 UTC | newest]

Thread overview: 6+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2020-04-18 11:20 [Bug] carl9170 + wpa_supplicant: P2P mode doesn't work with separate interfaces Frank Schäfer
2020-04-18 16:30 ` Christian Lamparter
2020-04-19 14:08   ` Frank Schäfer
2020-04-27 16:53 ` Frank Schäfer
2020-04-29 16:22   ` Frank Schäfer
2020-05-05 15:04     ` Frank Schäfer

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).