Bug 1947166

Summary: dbus: wpa_dbus_property_changed: no property SessionLength in object
Product: [Fedora] Fedora Reporter: Rares Vernica <rvernica>
Component: wpa_supplicantAssignee: Lubomir Rintel <lkundrak>
Status: CLOSED EOL QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: high Docs Contact:
Priority: unspecified    
Version: 33CC: bgalvani, blueowl, dcaratti, dcbw, lkundrak, prabesh432
Target Milestone: ---   
Target Release: ---   
Hardware: x86_64   
OS: Linux   
Whiteboard:
Fixed In Version: Doc Type: ---
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2021-11-30 16:12:25 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Attachments:
Description Flags
Screenshot of bug taken from GNOME Logs about Dbus logs none

Description Rares Vernica 2021-04-07 19:53:15 UTC 
Description of problem:

WiFi connection disconnects randomly. If I put the computer to sleep, on wake-up the WiFi connection is restored. Alternatively, if I wait for about 10 minutes the connection is restored. I started observing this behavior since upgrading to  Fedora 32 and it still happens in Fedora 33.

Version-Release number of selected component (if applicable):

Fedora 33


How reproducible:

Multiple times a day. 


Steps to Reproduce:
1. No steps required. It will eventually happen.
2. Waiting for 10 min fixes it
3. Putting the computer to sleep fixes it as well on wake-up


Additional info:

Here are some of the log messages I captured during the event, from the time it happened (12:32) until it recovered (12:42).

Apr 07 11:29:00 baron wpa_supplicant[1080]: wlo1: WPA: Group rekeying completed with 5a:a3:a3:2f:24:98 [GTK=CCMP]
Apr 07 12:29:00 baron wpa_supplicant[1080]: wlo1: WPA: Group rekeying completed with 5a:a3:a3:2f:24:98 [GTK=CCMP]
Apr 07 12:32:38 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-DISCONNECTED bssid=5a:a3:a3:2f:24:98 reason=4 locally_generated=1
Apr 07 12:32:38 baron wpa_supplicant[1080]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/25
Apr 07 12:32:38 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Apr 07 12:32:38 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US
Apr 07 12:32:38 baron NetworkManager[953]: <info>  [1617823958.9057] device (wlo1): supplicant interface state: completed -> disconnected
Apr 07 12:32:39 baron NetworkManager[953]: <info>  [1617823959.0017] device (wlo1): supplicant interface state: disconnected -> scanning
Apr 07 12:32:42 baron wpa_supplicant[1080]: wlo1: SME: Trying to authenticate with 5a:a3:a3:2f:24:98 (SSID='slick_nomap' freq=2432 MHz)
Apr 07 12:32:42 baron NetworkManager[953]: <info>  [1617823962.0880] device (wlo1): supplicant interface state: scanning -> authenticating
Apr 07 12:32:42 baron wpa_supplicant[1080]: wlo1: Trying to associate with 5a:a3:a3:2f:24:98 (SSID='slick_nomap' freq=2432 MHz)
Apr 07 12:32:42 baron NetworkManager[953]: <info>  [1617823962.0959] device (wlo1): supplicant interface state: authenticating -> associating
Apr 07 12:32:42 baron wpa_supplicant[1080]: wlo1: Associated with 5a:a3:a3:2f:24:98
Apr 07 12:32:42 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 07 12:32:42 baron NetworkManager[953]: <info>  [1617823962.1180] device (wlo1): supplicant interface state: associating -> associated
Apr 07 12:32:42 baron NetworkManager[953]: <info>  [1617823962.2109] device (wlo1): supplicant interface state: associated -> 4way_handshake
Apr 07 12:32:42 baron wpa_supplicant[1080]: wlo1: WPA: Key negotiation completed with 5a:a3:a3:2f:24:98 [PTK=CCMP GTK=CCMP]
Apr 07 12:32:42 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-CONNECTED - Connection to 5a:a3:a3:2f:24:98 completed [id=0 id_str=]
Apr 07 12:32:42 baron NetworkManager[953]: <info>  [1617823962.2189] device (wlo1): supplicant interface state: 4way_handshake -> completed
Apr 07 12:32:42 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-48 noise=9999 txrate=1000
Apr 07 12:34:00 baron NetworkManager[953]: <info>  [1617824040.7075] manager: NetworkManager state is now CONNECTED_SITE

Apr 07 12:38:49 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-DISCONNECTED bssid=5a:a3:a3:2f:24:98 reason=4 locally_generated=1
Apr 07 12:38:49 baron wpa_supplicant[1080]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/25
Apr 07 12:38:49 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Apr 07 12:38:49 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US
Apr 07 12:38:49 baron NetworkManager[953]: <info>  [1617824329.1768] device (wlo1): supplicant interface state: completed -> disconnected
Apr 07 12:38:49 baron NetworkManager[953]: <info>  [1617824329.2724] device (wlo1): supplicant interface state: disconnected -> scanning
Apr 07 12:38:52 baron wpa_supplicant[1080]: wlo1: SME: Trying to authenticate with 5a:a3:a3:2f:24:98 (SSID='slick_nomap' freq=2432 MHz)
Apr 07 12:38:52 baron wpa_supplicant[1080]: wlo1: Trying to associate with 5a:a3:a3:2f:24:98 (SSID='slick_nomap' freq=2432 MHz)
Apr 07 12:38:52 baron NetworkManager[953]: <info>  [1617824332.3739] device (wlo1): supplicant interface state: scanning -> authenticating
Apr 07 12:38:52 baron NetworkManager[953]: <info>  [1617824332.3843] device (wlo1): supplicant interface state: authenticating -> associating
Apr 07 12:38:52 baron wpa_supplicant[1080]: wlo1: Associated with 5a:a3:a3:2f:24:98
Apr 07 12:38:52 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 07 12:38:52 baron NetworkManager[953]: <info>  [1617824332.4014] device (wlo1): supplicant interface state: associating -> associated
Apr 07 12:38:52 baron NetworkManager[953]: <info>  [1617824332.4926] device (wlo1): supplicant interface state: associated -> 4way_handshake
Apr 07 12:38:52 baron wpa_supplicant[1080]: wlo1: WPA: Key negotiation completed with 5a:a3:a3:2f:24:98 [PTK=CCMP GTK=CCMP]
Apr 07 12:38:52 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-CONNECTED - Connection to 5a:a3:a3:2f:24:98 completed [id=0 id_str=]
Apr 07 12:38:52 baron NetworkManager[953]: <info>  [1617824332.5031] device (wlo1): supplicant interface state: 4way_handshake -> completed
Apr 07 12:38:52 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-53 noise=9999 txrate=1000

Apr 07 12:39:17 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-DISCONNECTED bssid=5a:a3:a3:2f:24:98 reason=4 locally_generated=1
Apr 07 12:39:17 baron wpa_supplicant[1080]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/25
Apr 07 12:39:17 baron NetworkManager[953]: <info>  [1617824357.7362] device (wlo1): supplicant interface state: completed -> disconnected
Apr 07 12:39:17 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Apr 07 12:39:17 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US
Apr 07 12:39:17 baron wpa_supplicant[1080]: wlo1: Reject scan trigger since one is already pending
Apr 07 12:39:17 baron wpa_supplicant[1080]: wlo1: Failed to initiate AP scan
Apr 07 12:39:18 baron wpa_supplicant[1080]: wlo1: Reject scan trigger since one is already pending
Apr 07 12:39:18 baron wpa_supplicant[1080]: wlo1: Failed to initiate AP scan
Apr 07 12:39:19 baron wpa_supplicant[1080]: wlo1: Reject scan trigger since one is already pending
Apr 07 12:39:19 baron wpa_supplicant[1080]: wlo1: Failed to initiate AP scan
Apr 07 12:39:20 baron wpa_supplicant[1080]: wlo1: SME: Trying to authenticate with 5a:a3:a3:2f:24:98 (SSID='slick_nomap' freq=2432 MHz)
Apr 07 12:39:20 baron NetworkManager[953]: <info>  [1617824360.8269] device (wlo1): supplicant interface state: disconnected -> authenticating
Apr 07 12:39:20 baron wpa_supplicant[1080]: wlo1: Trying to associate with 5a:a3:a3:2f:24:98 (SSID='slick_nomap' freq=2432 MHz)
Apr 07 12:39:20 baron NetworkManager[953]: <info>  [1617824360.8331] device (wlo1): supplicant interface state: authenticating -> associating
Apr 07 12:39:20 baron wpa_supplicant[1080]: wlo1: Associated with 5a:a3:a3:2f:24:98
Apr 07 12:39:20 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 07 12:39:20 baron NetworkManager[953]: <info>  [1617824360.8580] device (wlo1): supplicant interface state: associating -> associated
Apr 07 12:39:20 baron NetworkManager[953]: <info>  [1617824360.9529] device (wlo1): supplicant interface state: associated -> 4way_handshake
Apr 07 12:39:20 baron wpa_supplicant[1080]: wlo1: WPA: Key negotiation completed with 5a:a3:a3:2f:24:98 [PTK=CCMP GTK=CCMP]
Apr 07 12:39:20 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-CONNECTED - Connection to 5a:a3:a3:2f:24:98 completed [id=0 id_str=]
Apr 07 12:39:20 baron NetworkManager[953]: <info>  [1617824360.9604] device (wlo1): supplicant interface state: 4way_handshake -> completed
Apr 07 12:39:21 baron wpa_supplicant[1080]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-47 noise=9999 txrate=1000

Apr 07 12:42:31 baron NetworkManager[953]: <info>  [1617824551.9007] manager: NetworkManager state is now CONNECTED_GLOBAL
Comment 1 Pranav 2021-09-15 11:12:59 UTC 
Yes, this problem still persists here in Fedora 34 which I explained here: https://gitlab.freedesktop.org/dbus/dbus/-/issues/342#note_1061057

That Dbus issue was closed and someone named Phillip directed me here.

I see know attention has been given to this issue. I hope this issue really gets resolved soon.

pranav@fedora ~> journalctl -b --unit=wpa_supplicant
-- Journal begins at Mon 2021-08-02 14:44:55 +0545, ends at Wed 2021-09-15 16:00:35 +0545. --
Sep 15 09:37:39 fedora systemd[1]: Starting WPA supplicant...
Sep 15 09:37:39 fedora systemd[1]: Started WPA supplicant.
Sep 15 09:37:39 fedora wpa_supplicant[911]: Successfully initialized wpa_supplicant
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: WPA: Key negotiation completed with e8:d0:b9:ac:10:d9 [PTK=CCMP GTK=TKIP]
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to e8:d0:b9:ac:10:d9 completed [id=0 id_str=]
Sep 15 09:37:43 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-45 noise=9999 txrate=1000
Sep 15 11:16:25 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:16:32 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:16:34 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:16:37 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:16:38 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=4 locally_generated=1
Sep 15 11:16:38 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 11:16:38 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 11:16:38 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=4 locally_generated=1
Sep 15 11:16:40 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 11:16:40 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=4 locally_generated=1
Sep 15 11:16:42 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 11:16:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=4 locally_generated=1
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="WiFi Name_wlink" auth_failures=1 duration=>
Sep 15 11:16:45 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 11:16:45 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 11:16:54 fedora wpa_supplicant[911]: wlp3s0: Reject scan trigger since one is already pending
Sep 15 11:16:54 fedora wpa_supplicant[911]: wlp3s0: Reject scan trigger since one is already pending
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: WPA: Key negotiation completed with e8:d0:b9:ac:10:d9 [PTK=CCMP GTK=TKIP]
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to e8:d0:b9:ac:10:d9 completed [id=0 id_str=]
Sep 15 11:16:58 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-46 noise=9999 txrate=72200
Sep 15 11:17:03 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=4 locally_generated=1
Sep 15 11:17:03 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 11:17:03 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 11:17:03 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: WPA: Key negotiation completed with e8:d0:b9:ac:10:d9 [PTK=CCMP GTK=TKIP]
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to e8:d0:b9:ac:10:d9 completed [id=0 id_str=]
Sep 15 11:17:05 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-55 noise=9999 txrate=72200
Sep 15 11:17:10 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:17:13 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-47 noise=9999 txrate=72200
Sep 15 11:17:21 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:17:36 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:17:37 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:17:41 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-BEACON-LOSS
Sep 15 11:34:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=3 locally_generated=1
Sep 15 11:34:42 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 11:34:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 11:34:42 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 11:34:42 fedora wpa_supplicant[911]: nl80211: deinit ifname=p2p-dev-wlp3s0 disabled_11b_rates=0
Sep 15 11:34:42 fedora wpa_supplicant[911]: nl80211: deinit ifname=wlp3s0 disabled_11b_rates=0
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: WPA: Key negotiation completed with e8:d0:b9:ac:10:d9 [PTK=CCMP GTK=TKIP]
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to e8:d0:b9:ac:10:d9 completed [id=0 id_str=]
Sep 15 12:28:52 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-50 noise=9999 txrate=72200
Sep 15 12:28:56 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-44 noise=9999 txrate=72200
Sep 15 12:48:52 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=3 locally_generated=1
Sep 15 12:48:52 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 12:48:52 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 12:48:52 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 12:48:52 fedora wpa_supplicant[911]: nl80211: deinit ifname=p2p-dev-wlp3s0 disabled_11b_rates=0
Sep 15 12:48:52 fedora wpa_supplicant[911]: nl80211: deinit ifname=wlp3s0 disabled_11b_rates=0
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: WPA: Key negotiation completed with e8:d0:b9:ac:10:d9 [PTK=CCMP GTK=TKIP]
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to e8:d0:b9:ac:10:d9 completed [id=0 id_str=]
Sep 15 12:50:53 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-43 noise=9999 txrate=72200
Sep 15 15:16:57 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=e8:d0:b9:ac:10:d9 reason=3 locally_generated=1
Sep 15 15:16:57 fedora wpa_supplicant[911]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1>
Sep 15 15:16:57 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Sep 15 15:16:57 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=NP
Sep 15 15:16:57 fedora wpa_supplicant[911]: nl80211: deinit ifname=p2p-dev-wlp3s0 disabled_11b_rates=0
Sep 15 15:16:57 fedora wpa_supplicant[911]: nl80211: deinit ifname=wlp3s0 disabled_11b_rates=0
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: SME: Trying to authenticate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=246>
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: Trying to associate with e8:d0:b9:ac:10:d9 (SSID='WiFi Name_wlink' freq=2462 MHz)
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: Associated with e8:d0:b9:ac:10:d9
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: WPA: Key negotiation completed with e8:d0:b9:ac:10:d9 [PTK=CCMP GTK=TKIP]
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to e8:d0:b9:ac:10:d9 completed [id=0 id_str=]
Sep 15 15:22:06 fedora wpa_supplicant[911]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-46 noise=9999 txrate=72200
pranav@fedora ~>
```
Comment 2 Pranav 2021-09-15 11:16:11 UTC 
Created attachment 1823272 [details]
Screenshot of bug taken from GNOME Logs about Dbus logs
Comment 3 Ben Cotton 2021-11-04 13:43:54 UTC 
This message is a reminder that Fedora 33 is nearing its end of life.
Fedora will stop maintaining and issuing updates for Fedora 33 on 2021-11-30.
It is Fedora's policy to close all bug reports from releases that are no longer
maintained. At that time this bug will be closed as EOL if it remains open with a
Fedora 'version' of '33'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version' 
to a later Fedora version.

Thank you for reporting this issue and we are sorry that we were not 
able to fix it before Fedora 33 is end of life. If you would still like 
to see this bug fixed and are able to reproduce it against a later version 
of Fedora, you are encouraged  change the 'version' to a later Fedora 
version prior this bug is closed as described in the policy above.

Although we aim to fix as many bugs as possible during every release's 
lifetime, sometimes those efforts are overtaken by events. Often a 
more recent Fedora release includes newer upstream software that fixes 
bugs or makes them obsolete.
Comment 4 Ben Cotton 2021-11-04 14:13:25 UTC 
This message is a reminder that Fedora 33 is nearing its end of life.
Fedora will stop maintaining and issuing updates for Fedora 33 on 2021-11-30.
It is Fedora's policy to close all bug reports from releases that are no longer
maintained. At that time this bug will be closed as EOL if it remains open with a
Fedora 'version' of '33'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version' 
to a later Fedora version.

Thank you for reporting this issue and we are sorry that we were not 
able to fix it before Fedora 33 is end of life. If you would still like 
to see this bug fixed and are able to reproduce it against a later version 
of Fedora, you are encouraged  change the 'version' to a later Fedora 
version prior this bug is closed as described in the policy above.

Although we aim to fix as many bugs as possible during every release's 
lifetime, sometimes those efforts are overtaken by events. Often a 
more recent Fedora release includes newer upstream software that fixes 
bugs or makes them obsolete.
Comment 5 Ben Cotton 2021-11-04 15:11:01 UTC 
This message is a reminder that Fedora 33 is nearing its end of life.
Fedora will stop maintaining and issuing updates for Fedora 33 on 2021-11-30.
It is Fedora's policy to close all bug reports from releases that are no longer
maintained. At that time this bug will be closed as EOL if it remains open with a
Fedora 'version' of '33'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version' 
to a later Fedora version.

Thank you for reporting this issue and we are sorry that we were not 
able to fix it before Fedora 33 is end of life. If you would still like 
to see this bug fixed and are able to reproduce it against a later version 
of Fedora, you are encouraged  change the 'version' to a later Fedora 
version prior this bug is closed as described in the policy above.

Although we aim to fix as many bugs as possible during every release's 
lifetime, sometimes those efforts are overtaken by events. Often a 
more recent Fedora release includes newer upstream software that fixes 
bugs or makes them obsolete.
Comment 6 Pranav 2021-11-05 15:52:12 UTC 
Mr @ben, Commenting once was enough. But you commented thrice. 

Anyway, if you re-read my comment above, this problem was there for Fedora 34 and this problem is still there in Fedora 35.

Please don't close this bug report. What if we see the same problem in Fedora 36?! We never know.

uname -r
5.14.15-300.fc35.x86_64

wpa_supplicant
`dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0
Comment 7 Ben Cotton 2021-11-30 16:12:25 UTC 
Fedora 33 changed to end-of-life (EOL) status on 2021-11-30. Fedora 33 is
no longer maintained, which means that it will not receive any further
security or bug fix updates. As a result we are closing this bug.

If you can reproduce this bug against a currently maintained version of
Fedora please feel free to reopen this bug against that version. If you
are unable to reopen this bug, please file a new report against the
current release. If you experience problems, please add a comment to this
bug.

Thank you for reporting this bug and we are sorry it could not be fixed.