[SOLVED] Wpa_supplicant error messages

[mm3100]

Hello,

I am using buster with KDE and I seem to be having some wifi issues. Internet connection seems stable to me, but looking in journal I keep getting these messages

Code: Select all

NetworkManager[974]: <warn>  [1603235136.9130] sup-iface[0x563fe53c78b0,wlo1]: connection disconnected (reason -4)
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/1
NetworkManager[974]: <warn>  [1603282864.9538] sup-iface[0x563fe53c78b0,wlo1]: connection disconnected (reason -3)
NetworkManager[974]: <warn>  [1603282872.4462] sup-iface[0x563fe53c7a10,wlo1]: connection disconnected (reason -4)
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/3

Those messages would appear sometimes randomly, but would always appear whenever I would put laptop in standby mode, or when it would go to sleep.
Looking in

Code: Select all

systemctl cat wpa_supplicant

I get

Code: Select all

[Unit]
Description=WPA supplicant
Before=network.target
After=dbus.service
Wants=network.target

[Service]
Type=dbus
BusName=fi.w1.wpa_supplicant1
ExecStart=/sbin/wpa_supplicant -u -s -O /run/wpa_supplicant

[Install]
WantedBy=multi-user.target
Alias=dbus-fi.w1.wpa_supplicant1.service

As time would pass, I would get up to Interfaces/80 at times.

All my networks are managed by network manager and I only use wifi, looking in /etc/network/interfaces shows

Code: Select all

# This file describes the network interfaces available on your system
# and how to activate them. For more information, see interfaces(5).

#source /etc/network/interfaces.d/*

# The loopback network interface
auto lo
iface lo inet loopback

#auto eno1
#iface eno1 inet dhcp

I haven't noticed any real issues with my internet connection, it only gets really slow from time to time, but I can't be sure it is because of my device.
Now for what network device I use

Code: Select all

sudo lshw -class network
  *-network                 
       description: Wireless interface
       product: QCA9565 / AR9565 Wireless Network Adapter
       vendor: Qualcomm Atheros
       physical id: 0
       bus info: pci@0000:07:00.0
       logical name: wlo1
       version: 01
       serial: a4:db:30:2b:14:5d
       width: 64 bits
       clock: 33MHz
       capabilities: pm msi pciexpress bus_master cap_list rom ethernet physical wireless
       configuration: broadcast=yes driver=ath9k driverversion=4.19.0-12-amd64 firmware=N/A ip=192.168.1.105 latency=0 link=yes multicast=yes wireless=IEEE 802.11
       resources: irq:16 memory:c3500000-c357ffff memory:c3580000-c358ffff

I had to install firmware-atheros as it was complaining about missing firmware. I have working connection just am slightly annoyed by those messages, and it worries it that could be causing me to lose performance.
Running on

Code: Select all

Linux HP2000 4.19.0-12-amd64 #1 SMP Debian 4.19.152-1 (2020-10-18) x86_64 GNU/Linux

Thanks for help.

[sunrat]

These are warnings, not errors, which are basically just informational. If your networking is working properly just ignore them.
To make journalctl actually useful, use priority filter to only show errors:

Code: Select all

journalctl -b -p3

[mm3100]

I am getting those with journalctl -b -p3. At least just half of it, showed it all in hope it shows more info.

Code: Select all

wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/1
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/3
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/3
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/5
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/5
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/7
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/7
wpa_supplicant[972]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/9

These are messages I was getting while device was on sleep during the night.

I am asking here for it, since it is odd to me that just a warning would be shown as error in the first place.

[Head_on_a_Stick]

Hmm, I've seen those before: https://bbs.archlinux.org/viewtopic.php?id=252455

Do you have any problems with the connection or are these log messages functionally irrelevant?

[mm3100]

Looking in dmesg it seems that I lose connection each time and it has to reconnect. But it appears it almost always happens during suspend/powerup/wakeup. So it doesn't seem to be functionally relevant.

I have been looking up on the internet and various other forums but haven't found anything relevant to me. I have tried with backported kernel and firmware but still same messages during same events.

So it made me think if it could be something with power control. Looking up for ath in dmesg I get this

Code: Select all

ath: phy0: WB335 1-ANT card detected
ath: phy0: Set BT/WLAN RX diversity capability
ath: phy0: Enable LNA combining
ath: phy0: ASPM enabled: 0x43
ath: EEPROM regdomain: 0x6a
ath: EEPROM indicates we should expect a direct regpair map
ath: Country alpha2 being used: 00
ath: Regpair used: 0x6a
ath9k 0000:07:00.0 wlo1: renamed from wlan0
usbcore: registered new interface driver ath3k

But looking up aspm it seems that my os doesn't have aspm control.

Code: Select all

ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
acpi PNP0A08:00: _OSC failed (AE_ERROR); disabling ASPM
pci 0000:00:1c.2: ASPM: current common clock configuration is broken, reconfiguring
r8169 0000:0e:00.0: can't disable ASPM; OS doesn't have ASPM control
ath: phy0: ASPM enabled: 0x43

Could it be caused by that, in a way that when it goes to sleep it tries to gracefully turn off wifi card, but fails as it has no aspm control?

[Head_on_a_Stick]

Perhaps try https://www.reddit.com/r/debian/comment ... ment_aspm/

But I think you'll need the backported kernel for that: https://www.phoronix.com/scan.php?page= ... -PCI-PCI-X

[mm3100]

Thanks for links. Looking devices it seems that most have L1/L0 controller active, except network controller, which only have L1. It does say that ASPM is active, as was seen in previous dmesg log, but OS still doesn't have ASPM control. I will try this something front those links in hope it gets some results.

But it might be completely unrelated to the issues to my original question. Googling up didn't help me much at all, as I am unsure what is the real cause behind it. So far it seem to be connected to power, as it occurs at every suspend, but then again could be caused by something called for sleep.target.

[mm3100]

Sorry for going on tangent about ASPM. I looked a bit more into it, should have done this first.

Seems that those messages don't have so much about suspend/wake up as much as lose of connection, and power off of network device.

I have tried with nmcli, wpa_cli and KDE network manager, all have same behaviour, when disconnecting it would always print same message.

Code: Select all

 wpa_supplicant[893]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/7
wpa_supplicant[893]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/7
wpa_supplicant[893]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/7

On connection it wouldn't say anything, but then, if I turn off network device, it would first print same message, but on next disconnect it would inceremtn interface number by 2, like

Code: Select all

wpa_supplicant[893]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/9

So to me it seems that it is just a message it lost connection. Now it interests me why it is shown as error? I have tried same thing on another device with pop os, but didn't get same result, tomorrow I will try to see if I get same messages while using usb wifi adapter.

[mm3100]

Messages ended after upgrading to Bullseye, never found out what exactly was wrong, but at least I am not getting those error messages any more.