W dniu 23.01.2024 o 09:25, Julian Sikorski pisze:
Am 17.01.24 um 10:37 schrieb Benjamin Berg:
Hi,
On Sun, 2024-01-14 at 19:28 +0100, Julian Sikorski wrote:
Am 14.01.24 um 19:00 schrieb Jouni Malinen:
On Fri, Jan 05, 2024 at 10:46:57AM +0100, Julian Sikorski wrote:
I am trying to figure out why gnome-network-displays is unable to
establish
a P2P connection with my Fire TV stick. I originally reported it to
gnome-network displays, then to NetworkManager before being
directed here.
In brief, the p2p connection never goes up so I can never attempt
to capture
packets using wireshark or tcpdump. Same laptop and same Fire TV
Stick work
fine from Windows 11.
I am attaching the wpa_supplicant debug log. Thank you for your
help in
advance.
Which WLAN driver are you using on this device (wlp1s0)?
I am using iwlwifi/iwlmvm. The hardware is Intel Wi-Fi 6 AX200.
Hmm, OK, I would have expected that to work fine.
It looks like the peer device might be using power saving mechanism
(Notice of Absence) that would require the local driver to be able to
synchronize the transmissions appropriately to be able to get them
through. The debug log here indicated that none of the numerous
attempts
of sending a GO Negotiation Request to the peer resulted in the frame
being acknowledged which could imply that it was not listening at the
time the request went out. That said, it is difficult to say for
certain
since the peer discovery part did succeed. In any case, this type of an
issue might require looking in more detail to a sniffer capture of the
IEEE 802.11 frames and the exact timing of them and/or figuring out
what
kind of capabilities the local driver has for P2P.
Sniffer would be a special hardware, correct? Like rtl-sdr? I have a 2.4
GHz Spectrum Analyzer but this is probably not what we need. Can a
sniffer be fashioned out of a second laptop?
A while back miracast used to work intermittently, which is when I filed
https://gitlab.gnome.org/GNOME/gnome-network-displays/-/issues/285,
suspecting the issue is caused by IPv6 vs IPv4. Now I can no longer get
the connection to work under linux at all.
No need for special hardware. You can e.g. use a second (Intel) card,
change it into monitor mode "iw dev X set type monitor". Then put the
interface into the UP state ("ip link set X up"), configure the channel
and bandwidth using "iw" and grab a packet dump using tcpdump or
wireshark.
Where do I get the channel and the bandwidth from? Running iw dev
p2p-wlp1s0-2 info during the connection attempt does not reveal it:
Interface p2p-wlp1s0-2
ifindex 14
wdev 0x100000005
addr a4:b1:c1:dd:5a:28
type P2P-client
wiphy 1
multicast TXQ:
qsz-byt qsz-pkt flows drops marks overlmt
hashcol tx-bytes tx-packets
0 0 0 0 0 0 0 0 0
Now, if the peer device is using NoA's, that could be because the
FireTV stick is associated to another network at the same time. You
could try disconnecting it (and possibly the laptop) just to see if
that makes a difference.
There could be something to it. I disconnected Wi-Fi on both the stick
and the laptop and initially it did not help. I then went on to set up
another laptop in monitor mode, and as I was trying to test the iw dev
command above, connection miraculously worked:
Interface p2p-wlp1s0-4
ifindex 8
wdev 0x7
addr a4:b1:c1:dd:5a:28
ssid DIRECT-p1
type P2P-GO
wiphy 0
channel 157 (5785 MHz), width: 20 MHz, center1: 5785 MHz
txpower 22.00 dBm
multicast TXQ:
qsz-byt qsz-pkt flows drops marks overlmt
hashcol tx-bytes tx-packets
0 0 23 0 0 0 0 3585 23
Jan 23 08:57:33 snowball3 wpa_supplicant[1131]: P2P-DEVICE-FOUND
6a:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
pri_dev_type=10-0050F204-5 name='Yasmin's Fire TV' config_methods=0x80
dev_capab=0x25 group_capab=0x2a wfd_dev_info=0x00011c440032
vendor_elems=1 new=0
Jan 23 08:57:33 snowball3 wpa_supplicant[1131]: P2P-GO-NEG-SUCCESS
role=GO freq=5785 ht40=0 peer_dev=6a:9a:87:86:b0:0e
peer_iface=62:9a:87:86:b0:0e wps_method=PBC
Jan 23 08:57:33 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4: interface
state UNINITIALIZED->ENABLED
Jan 23 08:57:33 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4: AP-ENABLED
Jan 23 08:57:33 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-CONNECTED - Connection to a4:b1:c1:dd:5a:28 completed [id=0
id_str=]
Jan 23 08:57:33 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
WPS-PBC-ACTIVE
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-EAP-STARTED 62:9a:87:86:b0:0e
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=1
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-EAP-PROPOSED-METHOD vendor=14122 method=254
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
WPS-REG-SUCCESS 62:9a:87:86:b0:0e 12345678-9abc-def0-1234-56789abcdef0
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: P2P-GROUP-FORMATION-SUCCESS
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: P2P-GROUP-STARTED
p2p-wlp1s0-4 GO ssid="DIRECT-p1" freq=5785 go_dev_addr=a4:b1:c1:dd:5a:27
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: dbus: Register group
object '/fi/w1/wpa_supplicant1/Interfaces/2/Groups/p1'
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
WPS-PBC-DISABLE
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4: WPS-SUCCESS
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-EAP-FAILURE 62:9a:87:86:b0:0e
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
AP-STA-CONNECTED 62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: AP-STA-CONNECTED
62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:57:34 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
EAPOL-4WAY-HS-COMPLETED 62:9a:87:86:b0:0e
Jan 23 08:57:43 snowball3 wpa_supplicant[1131]: wlp1s0: Reject scan
trigger since one is already pending
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: P2P-FIND-STOPPED
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: P2P-GROUP-REMOVED
p2p-wlp1s0-4 GO reason=REQUESTED
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4: interface
state ENABLED->DISABLED
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
AP-STA-DISCONNECTED 62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: AP-STA-DISCONNECTED
62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4: AP-DISABLED
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-DISCONNECTED bssid=a4:b1:c1:dd:5a:28 reason=3
locally_generated=1
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-DSCP-POLICY clear_all
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-4:
CTRL-EVENT-DSCP-POLICY clear_all
Jan 23 08:58:10 snowball3 wpa_supplicant[1131]: nl80211: deinit
ifname=p2p-wlp1s0-4 disabled_11b_rates=0
Colours were all messed up with everything covered in magenta and green
but this is a problem to deal with later. I then re-connected the laptop
to Wi-Fi, and I was still able to establish a connection:
Jan 23 08:58:16 snowball3 wpa_supplicant[1131]: wlp1s0: WPS-PBC-ACTIVE
Jan 23 08:58:24 snowball3 wpa_supplicant[1131]: wlp1s0: WPS-CANCEL
Jan 23 08:58:24 snowball3 wpa_supplicant[1131]: wlp1s0: SME: Trying to
authenticate with ec:a9:40:79:fb:ad (SSID='UPC949397B' freq=5320 MHz)
Jan 23 08:58:24 snowball3 wpa_supplicant[1131]: wlp1s0: Trying to
associate with ec:a9:40:79:fb:ad (SSID='UPC949397B' freq=5320 MHz)
Jan 23 08:58:25 snowball3 wpa_supplicant[1131]: wlp1s0: Associated with
ec:a9:40:79:fb:ad
Jan 23 08:58:25 snowball3 wpa_supplicant[1131]: wlp1s0:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 23 08:58:25 snowball3 wpa_supplicant[1131]: wlp1s0: WPA: Key
negotiation completed with ec:a9:40:79:fb:ad [PTK=CCMP GTK=CCMP]
Jan 23 08:58:25 snowball3 wpa_supplicant[1131]: wlp1s0:
CTRL-EVENT-CONNECTED - Connection to ec:a9:40:79:fb:ad completed [id=0
id_str=]
Jan 23 08:58:25 snowball3 wpa_supplicant[1131]: wlp1s0:
CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-63 noise=9999 txrate=26000
Jan 23 08:58:25 snowball3 wpa_supplicant[1131]: wlp1s0: Reject scan
trigger since one is already pending
Jan 23 08:58:37 snowball3 wpa_supplicant[1131]: P2P-DEVICE-FOUND
6a:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
pri_dev_type=10-0050F204-5 name='Yasmin's Fire TV' config_methods=0x80
dev_capab=0x25 group_capab=0x2a wfd_dev_info=0x00011c440032
vendor_elems=1 new=0
Jan 23 08:58:37 snowball3 wpa_supplicant[1131]: P2P-GO-NEG-SUCCESS
role=GO freq=5745 ht40=0 peer_dev=6a:9a:87:86:b0:0e
peer_iface=62:9a:87:86:b0:0e wps_method=PBC
Jan 23 08:58:37 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5: interface
state UNINITIALIZED->ENABLED
Jan 23 08:58:37 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5: AP-ENABLED
Jan 23 08:58:37 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-CONNECTED - Connection to a4:b1:c1:dd:5a:28 completed [id=0
id_str=]
Jan 23 08:58:37 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
WPS-PBC-ACTIVE
Jan 23 08:58:39 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-EAP-STARTED 62:9a:87:86:b0:0e
Jan 23 08:58:39 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=1
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-EAP-PROPOSED-METHOD vendor=14122 method=254
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
WPS-REG-SUCCESS 62:9a:87:86:b0:0e 12345678-9abc-def0-1234-56789abcdef0
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: P2P-GROUP-FORMATION-SUCCESS
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: P2P-GROUP-STARTED
p2p-wlp1s0-5 GO ssid="DIRECT-aJ" freq=5745 go_dev_addr=a4:b1:c1:dd:5a:27
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: dbus: Register group
object '/fi/w1/wpa_supplicant1/Interfaces/3/Groups/aJ'
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
WPS-PBC-DISABLE
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5: WPS-SUCCESS
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-EAP-FAILURE 62:9a:87:86:b0:0e
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
AP-STA-CONNECTED 62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: AP-STA-CONNECTED
62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:58:40 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
EAPOL-4WAY-HS-COMPLETED 62:9a:87:86:b0:0e
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
AP-STA-DISCONNECTED 62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: AP-STA-DISCONNECTED
62:9a:87:86:b0:0e p2p_dev_addr=6a:9a:87:86:b0:0e
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: P2P-FIND-STOPPED
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: P2P-GROUP-REMOVED
p2p-wlp1s0-5 GO reason=REQUESTED
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5: interface
state ENABLED->DISABLED
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5: AP-DISABLED
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-DISCONNECTED bssid=a4:b1:c1:dd:5a:28 reason=3
locally_generated=1
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-DSCP-POLICY clear_all
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: p2p-wlp1s0-5:
CTRL-EVENT-DSCP-POLICY clear_all
Jan 23 08:59:44 snowball3 wpa_supplicant[1131]: nl80211: deinit
ifname=p2p-wlp1s0-5 disabled_11b_rates=0
After re-connecting the Fire TV Stick, however, I was no longer able to
establish a connection. Disconnecting it did not help immediately
either. Neither did restarting the stick or disconnecting the wi-fi from
the laptop. I was not able to reproduce the successful connection since.
Benjamin
Out of curiosity I tried connecting to the Fire TV stick from the old
laptop sporting Intel Wireless 7260 card, the one I originally planned
to use as a monitor. It did not work either, this is the log:
sty 30 08:29:36 wpa_supplicant[1350]: P2P-GO-NEG-FAILURE status=-1
sty 30 08:29:38 NetworkManager[1252]: <warn> [1706599778.9720] device
(p2p-dev-wlp4s0): Activation: (wifi-p2p) connecting took too long,
failing activation
sty 30 08:29:38 NetworkManager[1252]: <info> [1706599778.9721] device
(p2p-dev-wlp4s0): state change: config -> failed (reason
'supplicant-timeout', sys-iface-state: 'managed')
sty 30 08:29:38 NetworkManager[1252]: <warn> [1706599778.9733] device
(p2p-dev-wlp4s0): Activation: failed for connection 'Wi-Fi P2P Peer
6A:9A:87:86:B0:0E'
sty 30 08:29:38 NetworkManager[1252]: <info> [1706599778.9743] device
(p2p-dev-wlp4s0): state change: failed -> disconnected (reason 'none',
sys-iface-state: 'managed')
sty 30 08:29:41 wpa_supplicant[1350]: wlp4s0: CTRL-EVENT-SIGNAL-CHANGE
above=1 signal=-48 noise=9999 txrate=585100
sty 30 08:29:56 wpa_supplicant[1350]: P2P-FIND-STOPPED
sty 30 08:29:56 NetworkManager[1252]: <info> [1706599796.7108] device
(p2p-dev-wlp4s0): Activation: starting connection 'Wi-Fi P2P Peer
6A:9A:87:86:B0:0E' (e3ef19b2-2c77-49e0-b4bf-dd033726d0ca)
sty 30 08:29:56 NetworkManager[1252]: <info> [1706599796.7110] audit:
op="connection-add-activate" uuid="e3ef19b2-2c77-49e0-b4bf-dd033726d0ca"
name="Wi-Fi P2P Peer 6A:9A:87:86:B0:0E" pid=10257 uid=500 result="success"
sty 30 08:29:56 NetworkManager[1252]: <info> [1706599796.7139] device
(p2p-dev-wlp4s0): state change: disconnected -> prepare (reason 'none',
sys-iface-state: 'managed')
sty 30 08:29:56 NetworkManager[1252]: <info> [1706599796.7144] device
(p2p-dev-wlp4s0): state change: prepare -> config (reason 'none',
sys-iface-state: 'managed')
sty 30 08:30:38 wpa_supplicant[1350]: P2P-GO-NEG-FAILURE status=-1
sty 30 08:30:38 audit: BPF prog-id=245 op=LOAD
sty 30 08:30:41 wpa_supplicant[1350]: P2P-DEVICE-LOST
p2p_dev_addr=6a:9a:87:86:b0:0e
sty 30 08:30:41 wpa_supplicant[1350]: dbus: Unregister peer object
'/fi/w1/wpa_supplicant1/Interfaces/0/Peers/6a9a8786b00e'
sty 30 08:30:41 NetworkManager[1252]: <warn> [1706599841.9728] device
(p2p-dev-wlp4s0): Activation: (wifi-p2p) connecting took too long,
failing activation
sty 30 08:30:41 NetworkManager[1252]: <info> [1706599841.9730] device
(p2p-dev-wlp4s0): state change: config -> failed (reason
'supplicant-timeout', sys-iface-state: 'managed')
sty 30 08:30:41 NetworkManager[1252]: <warn> [1706599841.9736] device
(p2p-dev-wlp4s0): Activation: failed for connection 'Wi-Fi P2P Peer
6A:9A:87:86:B0:0E'
sty 30 08:30:41 NetworkManager[1252]: <info> [1706599841.9745] device
(p2p-dev-wlp4s0): state change: failed -> disconnected (reason 'none',
sys-iface-state: 'managed')
I have then tried connecting to the sink built into my Panasonic Viera
TV and it worked 3 out of 3 times, indicating that the old laptop is
capable of sending miracast video:
sty 30 08:40:28 wpa_supplicant[1350]: P2P-FIND-STOPPED
sty 30 08:40:28 NetworkManager[1252]: <info> [1706600428.9286] device
(p2p-dev-wlp4s0): Activation: starting connection 'Wi-Fi P2P Peer
C6:D9:62:8A:DD:7C' (93f400e4-ba91-4c8c-92e3-43d22083ddd8)
sty 30 08:40:28 NetworkManager[1252]: <info> [1706600428.9287] audit:
op="connection-add-activate" uuid="93f400e4-ba91-4c8c-92e3-43d22083ddd8"
name="Wi-Fi P2P Peer C6:D9:62:8A:DD:7C" pid=10257 uid=500 result="success"
sty 30 08:40:28 NetworkManager[1252]: <info> [1706600428.9313] device
(p2p-dev-wlp4s0): state change: disconnected -> prepare (reason 'none',
sys-iface-state: 'managed')
sty 30 08:40:28 NetworkManager[1252]: <info> [1706600428.9318] device
(p2p-dev-wlp4s0): state change: prepare -> config (reason 'none',
sys-iface-state: 'managed')
sty 30 08:40:40 wpa_supplicant[1350]: P2P-DEVICE-FOUND c6:d9:62:8a:dd:7c
p2p_dev_addr=c6:d9:62:8a:dd:7c pri_dev_type=7-0050F204-1
name='VIERA_txpvt60e_eef4' config_methods=0x80 dev_capab=0x5
group_capab=0x8 wfd_dev_info=0x011100000028 new=0
sty 30 08:40:40 wpa_supplicant[1350]: P2P-GO-NEG-SUCCESS role=client
freq=2437 ht40=0 peer_dev=c6:d9:62:8a:dd:7c peer_iface=c2:d9:62:8a:dd:7c
wps_method=PBC
sty 30 08:40:40 wpa_supplicant[1350]: p2p-wlp4s0-4: WPS-PBC-ACTIVE
sty 30 08:40:41 wpa_supplicant[1350]: p2p-wlp4s0-4: SME: Trying to
authenticate with c2:d9:62:8a:dd:7c (SSID='DIRECT-ka' freq=2437 MHz)
sty 30 08:40:41 kernel: p2p-wlp4s0-4: authenticate with c2:d9:62:8a:dd:7c
sty 30 08:40:41 kernel: p2p-wlp4s0-4: No basic rates, using min rate instead
sty 30 08:40:41 kernel: p2p-wlp4s0-4: 80 MHz not supported, disabling VHT
sty 30 08:40:41 kernel: p2p-wlp4s0-4: send auth to c2:d9:62:8a:dd:7c
(try 1/3)
sty 30 08:40:41 wpa_supplicant[1350]: p2p-wlp4s0-4: Trying to associate
with c2:d9:62:8a:dd:7c (SSID='DIRECT-ka' freq=2437 MHz)
sty 30 08:40:41 kernel: p2p-wlp4s0-4: authenticated
sty 30 08:40:41 kernel: p2p-wlp4s0-4: associate with c2:d9:62:8a:dd:7c
(try 1/3)
sty 30 08:40:41 kernel: p2p-wlp4s0-4: RX AssocResp from
c2:d9:62:8a:dd:7c (capab=0x431 status=0 aid=1)
sty 30 08:40:41 kernel: p2p-wlp4s0-4: No basic rates, using min rate instead
sty 30 08:40:41 kernel: p2p-wlp4s0-4: associated
sty 30 08:40:41 wpa_supplicant[1350]: p2p-wlp4s0-4: Associated with
c2:d9:62:8a:dd:7c
sty 30 08:40:41 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-EAP-STARTED EAP authentication started
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-EAP-PROPOSED-METHOD vendor=14122 method=1
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-EAP-METHOD EAP vendor 14122 method 1 (WSC) selected
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4: WPS-CRED-RECEIVED
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4: WPS-SUCCESS
sty 30 08:40:45 wpa_supplicant[1350]: P2P-GROUP-FORMATION-SUCCESS
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-EAP-FAILURE EAP authentication failed
sty 30 08:40:45 kernel: p2p-wlp4s0-4: deauthenticating from
c2:d9:62:8a:dd:7c by local choice (Reason: 3=DEAUTH_LEAVING)
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-DISCONNECTED bssid=c2:d9:62:8a:dd:7c reason=3 locally_generated=1
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-DSCP-POLICY clear_all
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4: SME: Trying to
authenticate with c2:d9:62:8a:dd:7c (SSID='DIRECT-ka' freq=2437 MHz)
sty 30 08:40:45 kernel: p2p-wlp4s0-4: authenticate with c2:d9:62:8a:dd:7c
sty 30 08:40:45 kernel: p2p-wlp4s0-4: No basic rates, using min rate instead
sty 30 08:40:45 kernel: p2p-wlp4s0-4: 80 MHz not supported, disabling VHT
sty 30 08:40:45 kernel: p2p-wlp4s0-4: send auth to c2:d9:62:8a:dd:7c
(try 1/3)
sty 30 08:40:45 kernel: p2p-wlp4s0-4: send auth to c2:d9:62:8a:dd:7c
(try 2/3)
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4: Trying to associate
with c2:d9:62:8a:dd:7c (SSID='DIRECT-ka' freq=2437 MHz)
sty 30 08:40:45 kernel: p2p-wlp4s0-4: authenticated
sty 30 08:40:45 kernel: p2p-wlp4s0-4: associate with c2:d9:62:8a:dd:7c
(try 1/3)
sty 30 08:40:45 kernel: p2p-wlp4s0-4: RX AssocResp from
c2:d9:62:8a:dd:7c (capab=0x431 status=0 aid=1)
sty 30 08:40:45 kernel: p2p-wlp4s0-4: No basic rates, using min rate instead
sty 30 08:40:45 kernel: p2p-wlp4s0-4: associated
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4: Associated with
c2:d9:62:8a:dd:7c
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4: WPA: Key negotiation
completed with c2:d9:62:8a:dd:7c [PTK=CCMP GTK=CCMP]
sty 30 08:40:45 wpa_supplicant[1350]: p2p-wlp4s0-4: CTRL-EVENT-CONNECTED
- Connection to c2:d9:62:8a:dd:7c completed [id=0 id_str=]
sty 30 08:40:45 wpa_supplicant[1350]: P2P-GROUP-STARTED p2p-wlp4s0-4
client ssid="DIRECT-ka" freq=2437 go_dev_addr=c6:d9:62:8a:dd:7c
sty 30 08:40:45 wpa_supplicant[1350]: dbus: Register group object
'/fi/w1/wpa_supplicant1/Interfaces/3/Groups/ka'
sty 30 08:40:46 NetworkManager[1252]: <info> [1706600446.0070] device
(p2p-dev-wlp4s0): P2P Group supplicant interface state:
internal-starting -> completed
sty 30 08:40:46 audit[1052]: NETFILTER_CFG table=firewalld:16 family=1
entries=33 op=nft_register_rule pid=1052
subj=system_u:system_r:firewalld_t:s0 comm="firewalld"
sty 30 08:40:46 kernel: audit: type=1325 audit(1706600446.065:727):
table=firewalld:16 family=1 entries=33 op=nft_register_rule pid=1052
subj=system_u:system_r:firewalld_t:s0 comm="firewalld"
sty 30 08:40:46 NetworkManager[1252]: <info> [1706600446.0748] device
(p2p-dev-wlp4s0): state change: config -> ip-config (reason 'none',
sys-iface-state: 'managed')
sty 30 08:40:46 NetworkManager[1252]: <info> [1706600446.0760] dhcp4
(p2p-wlp4s0-4): activation: beginning transaction (timeout in 45 seconds)
sty 30 08:40:46 avahi-daemon[1042]: Joining mDNS multicast group on
interface p2p-wlp4s0-4.IPv6 with address fe80::9360:6df:2a8e:14ae.
sty 30 08:40:46 avahi-daemon[1042]: New relevant interface
p2p-wlp4s0-4.IPv6 for mDNS.
sty 30 08:40:46 avahi-daemon[1042]: Registering new address record for
fe80::9360:6df:2a8e:14ae on p2p-wlp4s0-4.*.
sty 30 08:40:53 NetworkManager[1252]: <info> [1706600453.2826] dhcp4
(p2p-wlp4s0-4): state changed new lease, address=192.168.55.2
sty 30 08:40:53 avahi-daemon[1042]: Joining mDNS multicast group on
interface p2p-wlp4s0-4.IPv4 with address 192.168.55.2.
sty 30 08:40:53 avahi-daemon[1042]: New relevant interface
p2p-wlp4s0-4.IPv4 for mDNS.
sty 30 08:40:53 avahi-daemon[1042]: Registering new address record for
192.168.55.2 on p2p-wlp4s0-4.IPv4.
sty 30 08:40:53 NetworkManager[1252]: <info> [1706600453.2863] device
(p2p-dev-wlp4s0): state change: ip-config -> ip-check (reason 'none',
sys-iface-state: 'managed')
sty 30 08:40:53 systemd[1]: Starting NetworkManager-dispatcher.service -
Network Manager Script Dispatcher Service...
sty 30 08:40:53 systemd[1]: Started NetworkManager-dispatcher.service -
Network Manager Script Dispatcher Service.
sty 30 08:40:53 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0
msg='unit=NetworkManager-dispatcher comm="systemd"
exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
sty 30 08:40:53 kernel: audit: type=1130 audit(1706600453.314:728):
pid=1 uid=0 auid=4294967295 ses=4294967295
subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher
comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=?
terminal=? res=success'
sty 30 08:40:53 NetworkManager[1252]: <info> [1706600453.3170] device
(p2p-dev-wlp4s0): state change: ip-check -> secondaries (reason 'none',
sys-iface-state: 'managed')
sty 30 08:40:53 NetworkManager[1252]: <info> [1706600453.3173] device
(p2p-dev-wlp4s0): state change: secondaries -> activated (reason 'none',
sys-iface-state: 'managed')
sty 30 08:40:53 NetworkManager[1252]: <info> [1706600453.3185] device
(p2p-dev-wlp4s0): Activation: successful, device activated.
sty 30 08:40:54 gnome-network-d[10257]: WfdClient: No resolution found,
falling back to standard FullHD resolution.
sty 30 08:40:54 wireplumber[2348]:
<WpPortalPermissionStorePlugin:0x55a1572d0a10> Failed to call Lookup:
GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for camera
sty 30 08:41:03 systemd[1]: NetworkManager-dispatcher.service:
Deactivated successfully.
sty 30 08:41:03 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0
msg='unit=NetworkManager-dispatcher comm="systemd"
exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
sty 30 08:41:03 kernel: audit: type=1131 audit(1706600463.501:729):
pid=1 uid=0 auid=4294967295 ses=4294967295
subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher
comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=?
terminal=? res=success'
Intrigued by this, I tried reproducing with my new laptop, and to my
surprise, it worked too (albeit with messed up colours as mentioned
earlier):
Jan 30 08:58:44 wpa_supplicant[1161]: P2P-FIND-STOPPED
Jan 30 08:58:44 NetworkManager[1081]: <info> [1706601524.1326] device
(p2p-dev-wlp1s0): Activation: starting connection 'Wi-Fi P2P Peer
C6:D9:62:8A:DD:7C' (f5b32b41-29a1-4bcf-8f31-6a655128f3dd)
Jan 30 08:58:44 NetworkManager[1081]: <info> [1706601524.1327] audit:
op="connection-add-activate" uuid="f5b32b41-29a1-4bcf-8f31-6a655128f3dd"
name="Wi-Fi P2P Peer C6:D9:62:8A:DD:7C" pid=60474 uid=1000 result="success"
Jan 30 08:58:44 NetworkManager[1081]: <info> [1706601524.1329] device
(p2p-dev-wlp1s0): state change: disconnected -> prepare (reason 'none',
sys-iface-state: 'managed')
Jan 30 08:58:44 NetworkManager[1081]: <info> [1706601524.1339] device
(p2p-dev-wlp1s0): state change: prepare -> config (reason 'none',
sys-iface-state: 'managed')
Jan 30 08:58:46 ModemManager[1070]: <info> [base-manager] couldn't
check support for device
'/sys/devices/pci0000:00/0000:00:01.1/0000:01:00.0': not supported by
any plugin
Jan 30 08:58:55 wpa_supplicant[1161]: P2P-DEVICE-FOUND c6:d9:62:8a:dd:7c
p2p_dev_addr=c6:d9:62:8a:dd:7c pri_dev_type=7-0050F204-1
name='VIERA_txpvt60e_eef4' config_methods=0x80 dev_capab=0x5
group_capab=0x8 wfd_dev_info=0x011100000028 new=0
Jan 30 08:58:55 wpa_supplicant[1161]: P2P-GO-NEG-SUCCESS role=client
freq=2437 ht40=0 peer_dev=c6:d9:62:8a:dd:7c peer_iface=c2:d9:62:8a:dd:7c
wps_method=PBC
Jan 30 08:58:55 wpa_supplicant[1161]: p2p-wlp1s0-2: WPS-PBC-ACTIVE
Jan 30 08:58:56 wpa_supplicant[1161]: p2p-wlp1s0-2: SME: Trying to
authenticate with c2:d9:62:8a:dd:7c (SSID='DIRECT-sR' freq=2437 MHz)
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: No basic rates, using min rate instead
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: 80 MHz not supported, disabling VHT
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: authenticate with
c2:d9:62:8a:dd:7c (local address=a4:b1:c1:dd:5a:28)
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: send auth to c2:d9:62:8a:dd:7c
(try 1/3)
Jan 30 08:58:56 wpa_supplicant[1161]: p2p-wlp1s0-2: Trying to associate
with c2:d9:62:8a:dd:7c (SSID='DIRECT-sR' freq=2437 MHz)
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: authenticated
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: associate with c2:d9:62:8a:dd:7c
(try 1/3)
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: RX AssocResp from
c2:d9:62:8a:dd:7c (capab=0x431 status=0 aid=1)
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: No basic rates, using min rate instead
Jan 30 08:58:56 kernel: p2p-wlp1s0-2: associated
Jan 30 08:58:56 wpa_supplicant[1161]: p2p-wlp1s0-2: Associated with
c2:d9:62:8a:dd:7c
Jan 30 08:58:56 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-EAP-STARTED EAP authentication started
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-EAP-PROPOSED-METHOD vendor=14122 method=1
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-EAP-METHOD EAP vendor 14122 method 1 (WSC) selected
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2: WPS-CRED-RECEIVED
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2: WPS-SUCCESS
Jan 30 08:59:00 wpa_supplicant[1161]: P2P-GROUP-FORMATION-SUCCESS
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-EAP-FAILURE EAP authentication failed
Jan 30 08:59:00 kernel: p2p-wlp1s0-2: deauthenticating from
c2:d9:62:8a:dd:7c by local choice (Reason: 3=DEAUTH_LEAVING)
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-DISCONNECTED bssid=c2:d9:62:8a:dd:7c reason=3 locally_generated=1
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-DSCP-POLICY clear_all
Jan 30 08:59:00 wpa_supplicant[1161]: p2p-wlp1s0-2: SME: Trying to
authenticate with c2:d9:62:8a:dd:7c (SSID='DIRECT-sR' freq=2437 MHz)
Jan 30 08:59:00 kernel: p2p-wlp1s0-2: No basic rates, using min rate instead
Jan 30 08:59:00 kernel: p2p-wlp1s0-2: 80 MHz not supported, disabling VHT
Jan 30 08:59:00 kernel: p2p-wlp1s0-2: authenticate with
c2:d9:62:8a:dd:7c (local address=a4:b1:c1:dd:5a:28)
Jan 30 08:59:00 kernel: p2p-wlp1s0-2: send auth to c2:d9:62:8a:dd:7c
(try 1/3)
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: Trying to associate
with c2:d9:62:8a:dd:7c (SSID='DIRECT-sR' freq=2437 MHz)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: authenticated
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: associate with c2:d9:62:8a:dd:7c
(try 1/3)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: RX AssocResp from
c2:d9:62:8a:dd:7c (capab=0x431 status=0 aid=1)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: No basic rates, using min rate instead
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: Associated with
c2:d9:62:8a:dd:7c
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: associated
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: disassociated from
c2:d9:62:8a:dd:7c (Reason: 2=PREV_AUTH_NOT_VALID)
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-DISCONNECTED bssid=c2:d9:62:8a:dd:7c reason=2
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: WPA: 4-Way Handshake
failed - pre-shared key may be incorrect
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: SME: Trying to
authenticate with c2:d9:62:8a:dd:7c (SSID='DIRECT-sR' freq=2437 MHz)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: No basic rates, using min rate instead
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: 80 MHz not supported, disabling VHT
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: authenticate with
c2:d9:62:8a:dd:7c (local address=a4:b1:c1:dd:5a:28)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: send auth to c2:d9:62:8a:dd:7c
(try 1/3)
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: Trying to associate
with c2:d9:62:8a:dd:7c (SSID='DIRECT-sR' freq=2437 MHz)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: authenticated
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: associate with c2:d9:62:8a:dd:7c
(try 1/3)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: RX AssocResp from
c2:d9:62:8a:dd:7c (capab=0x431 status=0 aid=1)
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: No basic rates, using min rate instead
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: Associated with
c2:d9:62:8a:dd:7c
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 30 08:59:01 kernel: p2p-wlp1s0-2: associated
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: WPA: Key negotiation
completed with c2:d9:62:8a:dd:7c [PTK=CCMP GTK=CCMP]
Jan 30 08:59:01 wpa_supplicant[1161]: p2p-wlp1s0-2: CTRL-EVENT-CONNECTED
- Connection to c2:d9:62:8a:dd:7c completed [id=0 id_str=]
Jan 30 08:59:01 wpa_supplicant[1161]: P2P-GROUP-STARTED p2p-wlp1s0-2
client ssid="DIRECT-sR" freq=2437 go_dev_addr=c6:d9:62:8a:dd:7c
Jan 30 08:59:01 wpa_supplicant[1161]: dbus: Register group object
'/fi/w1/wpa_supplicant1/Interfaces/21/Groups/sR'
Jan 30 08:59:01 NetworkManager[1081]: <info> [1706601541.4421] device
(p2p-dev-wlp1s0): P2P Group supplicant interface state:
internal-starting -> completed
Jan 30 08:59:01 audit[1072]: NETFILTER_CFG table=firewalld:67 family=1
entries=33 op=nft_register_rule pid=1072
subj=system_u:system_r:firewalld_t:s0 comm="firewalld"
Jan 30 08:59:01 NetworkManager[1081]: <info> [1706601541.4652] device
(p2p-dev-wlp1s0): state change: config -> ip-config (reason 'none',
sys-iface-state: 'managed')
Jan 30 08:59:01 NetworkManager[1081]: <info> [1706601541.4672] dhcp4
(p2p-wlp1s0-2): activation: beginning transaction (timeout in 45 seconds)
Jan 30 08:59:01 avahi-daemon[951]: Joining mDNS multicast group on
interface p2p-wlp1s0-2.IPv6 with address fe80::461b:1c1a:1024:8e20.
Jan 30 08:59:01 avahi-daemon[951]: New relevant interface
p2p-wlp1s0-2.IPv6 for mDNS.
Jan 30 08:59:01 avahi-daemon[951]: Registering new address record for
fe80::461b:1c1a:1024:8e20 on p2p-wlp1s0-2.*.
Jan 30 08:59:04 NetworkManager[1081]: <info> [1706601544.4772] dhcp4
(p2p-wlp1s0-2): state changed new lease, address=192.168.55.2
Jan 30 08:59:04 avahi-daemon[951]: Joining mDNS multicast group on
interface p2p-wlp1s0-2.IPv4 with address 192.168.55.2.
Jan 30 08:59:04 avahi-daemon[951]: New relevant interface
p2p-wlp1s0-2.IPv4 for mDNS.
Jan 30 08:59:04 avahi-daemon[951]: Registering new address record for
192.168.55.2 on p2p-wlp1s0-2.IPv4.
Jan 30 08:59:04 NetworkManager[1081]: <info> [1706601544.4831] device
(p2p-dev-wlp1s0): state change: ip-config -> ip-check (reason 'none',
sys-iface-state: 'managed')
Jan 30 08:59:04 systemd[1]: Starting NetworkManager-dispatcher.service -
Network Manager Script Dispatcher Service...
Jan 30 08:59:04 systemd[1]: Started NetworkManager-dispatcher.service -
Network Manager Script Dispatcher Service.
Jan 30 08:59:04 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0
msg='unit=NetworkManager-dispatcher comm="systemd"
exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 30 08:59:04 NetworkManager[1081]: <info> [1706601544.5111] device
(p2p-dev-wlp1s0): state change: ip-check -> secondaries (reason 'none',
sys-iface-state: 'managed')
Jan 30 08:59:04 NetworkManager[1081]: <info> [1706601544.5114] device
(p2p-dev-wlp1s0): state change: secondaries -> activated (reason 'none',
sys-iface-state: 'managed')
Jan 30 08:59:04 NetworkManager[1081]: <info> [1706601544.5117] device
(p2p-dev-wlp1s0): Activation: successful, device activated.
Jan 30 08:59:04 systemd[1]: iscsi.service: Unit cannot be reloaded
because it is inactive.
Jan 30 08:59:06 gnome-network-d[60474]: WfdClient: No resolution found,
falling back to standard FullHD resolution.
Jan 30 08:59:06 wireplumber[2374]:
<WpPortalPermissionStorePlugin:0x55b39904fa70> Failed to call Lookup:
GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for camera
Jan 30 08:59:14 systemd[1]: NetworkManager-dispatcher.service:
Deactivated successfully.
Jan 30 08:59:14 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0
msg='unit=NetworkManager-dispatcher comm="systemd"
exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
What I noticed is that when my TV asks to confirm the connection, it
says '' request connection, suggesting gnome-network-displays does not
send a name. Could this be a reason for issues connecting to Fire TV?
But if so, why would the connection work every now and again for no
apparent reason?
Best regards,
Julian
_______________________________________________
Hostap mailing list
Hostap@xxxxxxxxxxxxxxxxxxx
http://lists.infradead.org/mailman/listinfo/hostap
