Connection issues on Fedora 33
This is a follow up to #153 (closed). I initially thought this was my issue, but log evidence suggests otherwise. When trying to connect to my display I get the following messages:
(gnome-network-displays:53868): GLib-GIO-DEBUG: 16:33:40.987: _g_io_module_get_default: Found default implementation gvfs (GDaemonVfs) for ‘gio-vfs’
(gnome-network-displays:53868): GLib-DEBUG: 16:33:41.006: unsetenv() is not thread-safe and should not be used after threads are created
(gnome-network-displays:53868): Gtk-DEBUG: 16:33:41.006: Connecting to session manager
** (gnome-network-displays:53868): DEBUG: 16:33:41.052: NdScreencastPortal: Aquired Portal proxy
** (gnome-network-displays:53868): DEBUG: 16:33:41.052: task is: 0xc508f0
** (gnome-network-displays:53868): DEBUG: 16:33:41.053: NdPulseaudio: Querying sink info by name
** (gnome-network-displays:53868): DEBUG: 16:33:41.053: NdPulseaudio: Error querying sink info
** (gnome-network-displays:53868): DEBUG: 16:33:41.053: NdPulseaudio: Got a sink info for the expected name
** (gnome-network-displays:53868): DEBUG: 16:33:41.055: NdScreencastPortal: Received CreateSession response
** (gnome-network-displays:53868): DEBUG: 16:33:41.055: simple variant lookup: /org/freedesktop/portal/desktop/session/1_1311/gnd23315335
** (gnome-network-displays:53868): DEBUG: 16:33:41.063: WFDP2PRegistry: Found a new device, creating provider
** (gnome-network-displays:53868): DEBUG: 16:33:41.063: WFDP2PProvider: Found a new sink with peer 0xd0ebe0 on device 0xd281e0
** (gnome-network-displays:53868): DEBUG: 16:33:41.063: WFDP2PProvider: Ignoring peer "MFC-J6935DW_BR0a07" (FE:01:7C:4A:8A:07) as it has no WFDIEs set
** (gnome-network-displays:53868): DEBUG: 16:33:41.063: WFDP2PProvider: Found a new sink with peer 0xd0ee20 on device 0xd281e0
** (gnome-network-displays:53868): DEBUG: 16:33:41.063: WfdP2PProvider: Discover is now set to 1
** (gnome-network-displays:53868): DEBUG: 16:33:41.063: SinkList: Adding a sink
** (gnome-network-displays:53868): DEBUG: 16:33:41.064: SinkList: Adding a sink
** (gnome-network-displays:53868): DEBUG: 16:33:41.066: WFDP2PProvider: Found a new sink with peer 0xd0ee20 on device 0xd281e0
** (gnome-network-displays:53868): DEBUG: 16:33:41.066: WFDP2PProvider: Ignoring peer "MFC-J6935DW_BR0a07" (FE:01:7C:4A:8A:07) as it has no WFDIEs set
** (gnome-network-displays:53868): DEBUG: 16:33:41.066: WFDP2PProvider: Found a new sink with peer 0xd0ebe0 on device 0xd281e0
** (gnome-network-displays:53868): DEBUG: 16:33:41.067: NdScreencastPortal: Received SelectSource response
** (gnome-network-displays:53868): DEBUG: 16:33:41.076: WFDP2PRegistry: Got NMClient
** (gnome-network-displays:53868): DEBUG: 16:33:44.689: NdScreencastPortal: Received Start response
** (gnome-network-displays:53868): DEBUG: 16:33:44.689: Got a stream with node ID: 55
** (gnome-network-displays:53868): DEBUG: 16:33:45.994: Found openh264enc for video encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:45.994: Found x264enc for video encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:45.994: Found vaapih264enc for video encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:45.994: Found fdkaacenc for audio encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:45.994: Found avenc_aac for audio encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:45.994: Got state change notification from streaming sink to state ND_SINK_STATE_ENSURE_FIREWALL
** (gnome-network-displays:53868): DEBUG: 16:33:45.996: WfdP2PProvider: Discover is now set to 0
** (gnome-network-displays:53868): DEBUG: 16:33:46.027: NdWfdP2PSink: Got firewall information
** (gnome-network-displays:53868): DEBUG: 16:33:46.027: Got state change notification from streaming sink to state ND_SINK_STATE_WAIT_P2P
** (gnome-network-displays:53868): DEBUG: 16:33:46.071: NdWfdP2PSink: Got P2P connection
** (gnome-network-displays:53868): DEBUG: 16:33:46.071: Found openh264enc for video encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:46.071: Found x264enc for video encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:46.071: Found vaapih264enc for video encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:46.071: Found fdkaacenc for audio encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:46.072: Found avenc_aac for audio encoding.
** (gnome-network-displays:53868): DEBUG: 16:33:46.072: Got state change notification from streaming sink to state ND_SINK_STATE_WAIT_SOCKET
** (gnome-network-displays:53868): DEBUG: 16:33:52.532: WfdServer: Finalize
** (gnome-network-displays:53868): DEBUG: 16:33:52.532: WfdMediaFactory: Finalize
** (gnome-network-displays:53868): DEBUG: 16:33:52.532: Got state change notification from streaming sink to state ND_SINK_STATE_ERROR
and the network manager logs:
Okt 01 16:33:44 apollo13 wpa_supplicant[1752]: P2P-DEVICE-FOUND d0:37:45:0c:f8:cc p2p_dev_addr=d2:37:45:0c:f8:cc pri_dev_type=7-0050F200-0 name='PS-MONITOR1' config_methods=0x88 dev_capab=0x25 group_capab=0xab wfd_dev_info=0x00111c440036 vendor_elems=1 new=0
Okt 01 16:33:44 apollo13 wpa_supplicant[1752]: P2P-DEVICE-FOUND 44:ef:bf:8d:3f:64 p2p_dev_addr=44:ef:bf:8d:3f:64 pri_dev_type=7-0050F204-1 name='Smart TV(5ff7)' config_methods=0x188 dev_capab=0x25 group_capab=0x0 wfd_dev_info=0x011100000000 new=0
Okt 01 16:33:44 apollo13 wpa_supplicant[1752]: P2P-DEVICE-FOUND fe:01:7c:4a:8a:07 p2p_dev_addr=fe:01:7c:4a:8a:07 pri_dev_type=3-0050F204-5 name='MFC-J6935DW_BR0a07' config_methods=0x188 dev_capab=0x24 group_capab=0x2 vendor_elems=1 new=0
Okt 01 16:33:44 apollo13 pipewire[16831]: [E][000025959.689636][module-access.c:101 check_flatpak()] failed to open "/proc/2443/root": Permission denied
Okt 01 16:33:44 apollo13 pipewire[16831]: [W][000025959.689694][module-access.c:194 context_check_access()] access 0x55ec74e7fd30: client 0x55ec74fe72a0 sandbox check failed: Permission denied
Okt 01 16:33:45 apollo13 wpa_supplicant[1752]: P2P-FIND-STOPPED
Okt 01 16:33:46 apollo13 NetworkManager[1558]: <info> [1601562826.0659] device (p2p-dev-wlp0s20f3): Activation: starting connection 'Wi-Fi P2P Peer 44:EF:BF:8D:3F:64' (34320ed8-7863-41a6-aaa6-fbf63f2cc37f)
Okt 01 16:33:46 apollo13 NetworkManager[1558]: <info> [1601562826.0661] audit: op="connection-add-activate" uuid="34320ed8-7863-41a6-aaa6-fbf63f2cc37f" name="Wi-Fi P2P Peer 44:EF:BF:8D:3F:64" pid=53868 uid=1000 result="success"
Okt 01 16:33:46 apollo13 NetworkManager[1558]: <info> [1601562826.0705] device (p2p-dev-wlp0s20f3): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Okt 01 16:33:46 apollo13 NetworkManager[1558]: <info> [1601562826.0711] device (p2p-dev-wlp0s20f3): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Okt 01 16:33:46 apollo13 systemd-udevd[53915]: Using default interface naming scheme 'v245'.
Okt 01 16:33:46 apollo13 systemd-udevd[53915]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Okt 01 16:33:46 apollo13 systemd-udevd[53915]: Could not set AlternativeName= or apply AlternativeNamesPolicy= on p2p-wlp0s20-1: File exists
Okt 01 16:33:46 apollo13 systemd-udevd[53915]: p2p-wlp0s20-1: Could not apply link config, ignoring: File exists
Okt 01 16:33:46 apollo13 wpa_supplicant[1752]: P2P-GO-NEG-SUCCESS role=client freq=2462 ht40=0 peer_dev=44:ef:bf:8d:3f:64 peer_iface=46:ef:bf:0d:3f:64 wps_method=PBC
Okt 01 16:33:46 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: WPS-PBC-ACTIVE
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: SME: Trying to authenticate with 46:ef:bf:0d:3f:64 (SSID='DIRECT-6p' freq=2462 MHz)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: authenticate with 46:ef:bf:0d:3f:64
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: send auth to 46:ef:bf:0d:3f:64 (try 1/3)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: authenticated
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: Trying to associate with 46:ef:bf:0d:3f:64 (SSID='DIRECT-6p' freq=2462 MHz)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: associate with 46:ef:bf:0d:3f:64 (try 1/3)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: RX AssocResp from 46:ef:bf:0d:3f:64 (capab=0x11 status=0 aid=2)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: associated
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: Associated with 46:ef:bf:0d:3f:64
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-EAP-STARTED EAP authentication started
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-EAP-PROPOSED-METHOD vendor=14122 method=1
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-EAP-METHOD EAP vendor 14122 method 1 (WSC) selected
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: WPS-CRED-RECEIVED
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: WPS-SUCCESS
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: P2P-GROUP-FORMATION-SUCCESS
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-EAP-FAILURE EAP authentication failed
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: deauthenticating from 46:ef:bf:0d:3f:64 by local choice (Reason: 3=DEAUTH_LEAVING)
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-DISCONNECTED bssid=46:ef:bf:0d:3f:64 reason=3 locally_generated=1
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/3
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: SME: Trying to authenticate with 46:ef:bf:0d:3f:64 (SSID='DIRECT-6p' freq=2462 MHz)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: authenticate with 46:ef:bf:0d:3f:64
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: send auth to 46:ef:bf:0d:3f:64 (try 1/3)
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: Trying to associate with 46:ef:bf:0d:3f:64 (SSID='DIRECT-6p' freq=2462 MHz)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: authenticated
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: associate with 46:ef:bf:0d:3f:64 (try 1/3)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: RX AssocResp from 46:ef:bf:0d:3f:64 (capab=0x11 status=0 aid=2)
Okt 01 16:33:47 apollo13 kernel: p2p-wlp0s20-1: associated
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: Associated with 46:ef:bf:0d:3f:64
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: WPA: Key negotiation completed with 46:ef:bf:0d:3f:64 [PTK=CCMP GTK=CCMP]
Okt 01 16:33:47 apollo13 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): p2p-wlp0s20-1: link becomes ready
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-CONNECTED - Connection to 46:ef:bf:0d:3f:64 completed [id=0 id_str=]
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: P2P-GROUP-STARTED p2p-wlp0s20-1 client ssid="DIRECT-6p" freq=2462 go_dev_addr=44:ef:bf:8d:3f:64 [PERSISTENT]
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: dbus: Register group object '/fi/w1/wpa_supplicant1/Interfaces/3/Groups/6p'
Okt 01 16:33:47 apollo13 NetworkManager[1558]: <info> [1601562827.5355] device (p2p-dev-wlp0s20f3): P2P Group supplicant interface state: internal-starting -> completed
Okt 01 16:33:47 apollo13 wpa_supplicant[1752]: wlp0s20f3: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-57 noise=9999 txrate=400000
Okt 01 16:33:47 apollo13 NetworkManager[1558]: <info> [1601562827.5549] device (p2p-dev-wlp0s20f3): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Okt 01 16:33:47 apollo13 NetworkManager[1558]: <info> [1601562827.5559] dhcp4 (p2p-wlp0s20-1): activation: beginning transaction (timeout in 45 seconds)
Okt 01 16:33:47 apollo13 avahi-daemon[1414]: Joining mDNS multicast group on interface p2p-wlp0s20-1.IPv6 with address fe80::d48b:4b9e:cd64:cd88.
Okt 01 16:33:47 apollo13 avahi-daemon[1414]: New relevant interface p2p-wlp0s20-1.IPv6 for mDNS.
Okt 01 16:33:47 apollo13 avahi-daemon[1414]: Registering new address record for fe80::d48b:4b9e:cd64:cd88 on p2p-wlp0s20-1.*.
Okt 01 16:33:48 apollo13 ModemManager[1412]: <info> [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:14.3': not supported by any plugin
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <warn> [1601562832.5267] device (p2p-dev-wlp0s20f3): Peer requested in connection is missing for too long, failing connection.
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <info> [1601562832.5268] device (p2p-dev-wlp0s20f3): state change: ip-config -> failed (reason 'peer-not-found', sys-iface-state: 'managed')
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <warn> [1601562832.5307] device (p2p-dev-wlp0s20f3): Activation: failed for connection 'Wi-Fi P2P Peer 44:EF:BF:8D:3F:64'
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <info> [1601562832.5331] device (p2p-dev-wlp0s20f3): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Okt 01 16:33:52 apollo13 avahi-daemon[1414]: Withdrawing address record for fe80::d48b:4b9e:cd64:cd88 on p2p-wlp0s20-1.
Okt 01 16:33:52 apollo13 avahi-daemon[1414]: Leaving mDNS multicast group on interface p2p-wlp0s20-1.IPv6 with address fe80::d48b:4b9e:cd64:cd88.
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <info> [1601562832.5347] dhcp4 (p2p-wlp0s20-1): canceled DHCP transaction
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <info> [1601562832.5348] dhcp4 (p2p-wlp0s20-1): state changed unknown -> done
Okt 01 16:33:52 apollo13 avahi-daemon[1414]: Interface p2p-wlp0s20-1.IPv6 no longer relevant for mDNS.
Okt 01 16:33:52 apollo13 kernel: p2p-wlp0s20-1: deauthenticating from 46:ef:bf:0d:3f:64 by local choice (Reason: 3=DEAUTH_LEAVING)
Okt 01 16:33:52 apollo13 wpa_supplicant[1752]: p2p-wlp0s20-1: CTRL-EVENT-DISCONNECTED bssid=46:ef:bf:0d:3f:64 reason=3 locally_generated=1
Okt 01 16:33:52 apollo13 wpa_supplicant[1752]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/3
Okt 01 16:33:52 apollo13 wpa_supplicant[1752]: P2P-GROUP-REMOVED p2p-wlp0s20-1 client reason=REQUESTED
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <warn> [1601562832.5888] sup-iface[56f12a98ebcb8e2b,1,wlp0s20f3]: call-p2p-cancel: failed with P2P cancel failed
Okt 01 16:33:52 apollo13 wpa_supplicant[1752]: nl80211: deinit ifname=p2p-wlp0s20-1 disabled_11b_rates=1
Okt 01 16:33:52 apollo13 NetworkManager[1558]: <info> [1601562832.6218] device (p2p-dev-wlp0s20f3): P2P Group supplicant interface state: completed -> internal-down
Okt 01 16:33:52 apollo13 wpa_supplicant[1752]: wlp0s20f3: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-57 noise=9999 txrate=400000