Package: connman
Version: 1.41-2+b1
Followup-For: Bug #977780
Dear Maintainer,
I have been able to reproduce this issue in a more restricted manner and get an
output from syslog.
I am able to reproduce this issue with the error described in other emails and
subject to this bug report (as seen in connman-gtk). This error in my case
happens in case I attempt to connect to a hotspot created from my phone. This
did
not happen in my case with earlier versions of the connman package.
Attached is a snippet taken from syslog when I attempt to connect to the
hotspot enabled
from my phone, and conman fails. After the error, connman deflects to the SSID
for which has valid configs (SSID 'shrimp' in the below snippets).
Cheers,
-Manuel
-- System Information:
Debian Release: bookworm/sid
APT prefers testing
APT policy: (500, 'testing')
Architecture: amd64 (x86_64)
Kernel: Linux 6.1.0-1-amd64 (SMP w/8 CPU threads; PREEMPT)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8),
LANGUAGE=en_US:en
Shell: /bin/sh linked to /usr/bin/dash
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled
Versions of packages connman depends on:
ii dbus 1.14.4-1
ii init-system-helpers 1.65.2
ii iptables 1.8.9-2
ii libc6 2.36-8
ii libdbus-1-3 1.14.4-1
ii libglib2.0-0 2.74.4-1
ii libgnutls30 3.7.8-4
ii libreadline8 8.2-1.3
ii libxtables12 1.8.9-2
ii lsb-base 11.5
ii sysvinit-utils [lsb-base] 3.06-2
Versions of packages connman recommends:
ii bluez 5.66-1
pn ofono <none>
ii wpasupplicant 2:2.10-10
Versions of packages connman suggests:
pn connman-vpn <none>
-- no debconf information
2023-01-24T22:16:37.894249+02:00 hecate avahi-daemon[966]: Withdrawing address
record for 192.168.8.198 on wlp0s20f3.
2023-01-24T22:16:37.896665+02:00 hecate kernel: [ 1491.810923] wlp0s20f3:
deauthenticating from 94:83:c4:12:df:b1 by local choice (Reason:
3=DEAUTH_LEAVING)
2023-01-24T22:16:37.896712+02:00 hecate avahi-daemon[966]: Withdrawing address
record for fe80::2641:8cff:fe33:ab17 on wlp0s20f3.
2023-01-24T22:16:37.897248+02:00 hecate avahi-daemon[966]: Leaving mDNS
multicast group on interface wlp0s20f3.IPv6 with address
fe80::2641:8cff:fe33:ab17.
2023-01-24T22:16:37.897476+02:00 hecate avahi-daemon[966]: Interface
wlp0s20f3.IPv6 no longer relevant for mDNS.
2023-01-24T22:16:37.898464+02:00 hecate connmand[991]: wlp0s20f3 {del} address
192.168.8.198/24 label wlp0s20f3
2023-01-24T22:16:37.898766+02:00 hecate connmand[991]: wlp0s20f3 {RX} 11991
packets 8841500 bytes
2023-01-24T22:16:37.898980+02:00 hecate connmand[991]: wlp0s20f3 {TX} 8337
packets 2800036 bytes
2023-01-24T22:16:37.899189+02:00 hecate connmand[991]: wlp0s20f3 {update} flags
36867 <UP>
2023-01-24T22:16:37.899445+02:00 hecate connmand[991]: wlp0s20f3 {newlink}
index 4 address 24:41:8C:33:AB:17 mtu 1500
2023-01-24T22:16:37.899685+02:00 hecate connmand[991]: wlp0s20f3 {newlink}
index 4 operstate 2 <DOWN>
2023-01-24T22:16:37.899901+02:00 hecate connmand[991]: wlp0s20f3 {del} route
0.0.0.0 gw 192.168.8.1 scope 0 <UNIVERSE>
2023-01-24T22:16:37.900091+02:00 hecate connmand[991]: wlp0s20f3 {del} route
192.168.8.1 gw 0.0.0.0 scope 253 <LINK>
2023-01-24T22:16:37.900303+02:00 hecate connmand[991]: wlp0s20f3 {del} route
fe80:: gw :: scope 0 <UNIVERSE>
2023-01-24T22:16:38.000854+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
CTRL-EVENT-DISCONNECTED bssid=94:83:c4:12:df:b1 reason=3 locally_generated=1
2023-01-24T22:16:38.000944+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
CTRL-EVENT-DSCP-POLICY clear_all
2023-01-24T22:16:38.001156+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
PMKSA-CACHE-REMOVED 94:83:c4:12:df:b1 0
2023-01-24T22:16:38.864464+02:00 hecate wpa_supplicant[995]: wlp0s20f3: SME:
Trying to authenticate with 94:83:c4:12:df:b1 (SSID='shrimp' freq=5745 MHz)
2023-01-24T22:16:38.864613+02:00 hecate kernel: [ 1492.780429] wlp0s20f3:
authenticate with 94:83:c4:12:df:b1
2023-01-24T22:16:38.876668+02:00 hecate kernel: [ 1492.791050] wlp0s20f3: send
auth to 94:83:c4:12:df:b1 (try 1/3)
2023-01-24T22:16:38.934056+02:00 hecate wpa_supplicant[995]: wlp0s20f3: SME:
Trying to authenticate with 94:83:c4:12:df:b1 (SSID='shrimp' freq=5745 MHz)
2023-01-24T22:16:38.935959+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
PMKSA-CACHE-ADDED 94:83:c4:12:df:b1 0
2023-01-24T22:16:38.936172+02:00 hecate wpa_supplicant[995]: wlp0s20f3: Trying
to associate with 94:83:c4:12:df:b1 (SSID='shrimp' freq=5745 MHz)
2023-01-24T22:16:38.936668+02:00 hecate kernel: [ 1492.849939] wlp0s20f3:
authenticate with 94:83:c4:12:df:b1
2023-01-24T22:16:38.936684+02:00 hecate kernel: [ 1492.849956] wlp0s20f3: send
auth to 94:83:c4:12:df:b1 (try 1/3)
2023-01-24T22:16:38.936687+02:00 hecate kernel: [ 1492.851506] wlp0s20f3:
authenticated
2023-01-24T22:16:38.936699+02:00 hecate kernel: [ 1492.852679] wlp0s20f3:
associate with 94:83:c4:12:df:b1 (try 1/3)
2023-01-24T22:16:38.940685+02:00 hecate kernel: [ 1492.855069] wlp0s20f3: RX
AssocResp from 94:83:c4:12:df:b1 (capab=0x111 status=0 aid=3)
2023-01-24T22:16:38.944705+02:00 hecate kernel: [ 1492.859463] wlp0s20f3:
associated
2023-01-24T22:16:38.944589+02:00 hecate connmand[991]: wlp0s20f3 {RX} 11992
packets 8841635 bytes
2023-01-24T22:16:38.945013+02:00 hecate connmand[991]: wlp0s20f3 {TX} 8337
packets 2800036 bytes
2023-01-24T22:16:38.945141+02:00 hecate connmand[991]: wlp0s20f3 {update} flags
102403 <UP,LOWER_UP>
2023-01-24T22:16:38.945303+02:00 hecate connmand[991]: wlp0s20f3 {newlink}
index 4 address 24:41:8C:33:AB:17 mtu 1500
2023-01-24T22:16:38.945454+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
Associated with 94:83:c4:12:df:b1
2023-01-24T22:16:38.945590+02:00 hecate connmand[991]: wlp0s20f3 {newlink}
index 4 operstate 5 <DORMANT>
2023-01-24T22:16:38.945713+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
2023-01-24T22:16:38.980712+02:00 hecate kernel: [ 1492.896163] wlp0s20f3:
Limiting TX power to 30 (30 - 0) dBm as advertised by 94:83:c4:12:df:b1
2023-01-24T22:16:39.069496+02:00 hecate wpa_supplicant[995]: wlp0s20f3: WPA:
Key negotiation completed with 94:83:c4:12:df:b1 [PTK=CCMP GTK=CCMP]
2023-01-24T22:16:39.069767+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
CTRL-EVENT-CONNECTED - Connection to 94:83:c4:12:df:b1 completed [id=0 id_str=]
2023-01-24T22:16:39.069941+02:00 hecate connmand[991]: wlp0s20f3 {add} route
fe80:: gw :: scope 0 <UNIVERSE>
2023-01-24T22:16:39.070136+02:00 hecate connmand[991]: wlp0s20f3 {RX} 11993
packets 8841836 bytes
2023-01-24T22:16:39.070282+02:00 hecate connmand[991]: wlp0s20f3 {TX} 8339
packets 2800330 bytes
2023-01-24T22:16:39.070450+02:00 hecate connmand[991]: wlp0s20f3 {update} flags
102467 <UP,RUNNING,LOWER_UP>
2023-01-24T22:16:39.070610+02:00 hecate connmand[991]: wlp0s20f3 {newlink}
index 4 address 24:41:8C:33:AB:17 mtu 1500
2023-01-24T22:16:39.070759+02:00 hecate connmand[991]: wlp0s20f3 {newlink}
index 4 operstate 6 <UP>
2023-01-24T22:16:39.072654+02:00 hecate kernel: [ 1492.984986] IPv6:
ADDRCONF(NETDEV_CHANGE): wlp0s20f3: link becomes ready
2023-01-24T22:16:39.098242+02:00 hecate avahi-daemon[966]: Registering new
address record for 192.168.8.198 on wlp0s20f3.IPv4.
2023-01-24T22:16:39.098594+02:00 hecate connmand[991]: Setting hostname to
hecate
2023-01-24T22:16:39.098798+02:00 hecate connmand[991]: Setting domainname to lan
2023-01-24T22:16:39.145372+02:00 hecate connmand[991]: wlp0s20f3 {add} address
192.168.8.198/24 label wlp0s20f3 family 2
2023-01-24T22:16:39.146082+02:00 hecate connmand[991]: wlp0s20f3 {add} route
192.168.8.1 gw 0.0.0.0 scope 253 <LINK>
2023-01-24T22:16:39.146305+02:00 hecate connmand[991]: wlp0s20f3 {add} route
0.0.0.0 gw 192.168.8.1 scope 0 <UNIVERSE>
2023-01-24T22:16:40.097445+02:00 hecate connmand[991]: wlp0s20f3 {add} route
82.165.8.211 gw 192.168.8.1 scope 0 <UNIVERSE>
2023-01-24T22:16:40.885597+02:00 hecate avahi-daemon[966]: Joining mDNS
multicast group on interface wlp0s20f3.IPv6 with address
fe80::2641:8cff:fe33:ab17.
2023-01-24T22:16:40.885854+02:00 hecate avahi-daemon[966]: New relevant
interface wlp0s20f3.IPv6 for mDNS.
2023-01-24T22:16:40.885996+02:00 hecate avahi-daemon[966]: Registering new
address record for fe80::2641:8cff:fe33:ab17 on wlp0s20f3.*.
2023-01-24T22:16:44.304011+02:00 hecate connmand[991]: wlp0s20f3 {del} route
82.165.8.211 gw 192.168.8.1 scope 0 <UNIVERSE>
2023-01-24T22:17:01.261858+02:00 hecate CRON[20386]: (root) CMD (cd / &&
run-parts --report /etc/cron.hourly)
2023-01-24T22:17:10.109930+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-52 noise=9999 txrate=866700
2023-01-24T22:17:11.443302+02:00 hecate wpa_supplicant[995]: wlp0s20f3:
CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-43 noise=9999 txrate=866700
