Package: connman
Version: 1.41-3
Severity: critical
Hello. I encountered with a problem: Connman randomly is getting inadequate.
Symptoms (simultaneously):
1. All existing connections stop working, cannot establish new.
2. Connman uses 1 CPU core.
3. Creates temporary file /var/lib/connman/stats.<6 random characters>.tmp
4. Connman don't responding to CLI or GUI, and signal SIGTERM.
I have a guess, that bug occurs when Connman tries to reconnect to Wi-Fi
network.
$ top
PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
3281 root 20 0 42988 32504 31352 R 99,3 0,9 2:38.19 connmand
Temporary files:
$ ls -l /var/lib/connman | grep "tmp"
-rw------- 1 root root 23592960 окт 22 10:38 stats.0W91C2.tmp
-rw------- 1 root root 21585920 окт 22 11:46 stats.25A7C2.tmp
-rw------- 1 root root 67358720 окт 22 10:35 stats.UD8ED2.tmp
Connman writes to a temporary file until free disk space is end.
If free space is end, then systemd kills Connman and restart it.
"stats.<>.tmp" contains 'header' and 'record', that infinity repeated.
'header' contains:
position 0x8-0xB for every file differs.
00000000 16 B9 00 FA 14 00 00 00 94 85 41 01 FF FF FF FF
00000010 FF FF FF FF 00 00 00 00 00 00 00 00 00 00 00 00
00000020 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00000030 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
repeated 'record' contains:
00000040 00 00 00 00 D4 5B 05 00 32 F4 35 63 00 00 00 00
00000050 4B 30 25 00 3E DB 18 00 C9 A0 C0 6E 6B E0 59 31
00000060 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00000070 BE 0A 06 00 00 00 00 00 2C DB 27 00 FF 4E 1A 00
00000080 35 70 EC 7C CC 64 0E 32 00 00 00 00 00 00 00 00
00000090 00 00 00 00 00 00 00 00 8A 5C 07 00 08 55 39 63
syslog (when bug occured and I try to stop Connman, MAC adress removed):
Oct 23 11:46:44 debian-gateway connman-vpnd[9104]: wlan0 {update} flags 36867
<UP>
Oct 23 11:46:44 debian-gateway connman-vpnd[9104]: wlan0 {newlink} index 3
address #WLAN_MAC# mtu 1500
Oct 23 11:46:44 debian-gateway connman-vpnd[9104]: wlan0 {newlink} index 3
operstate 2 <DOWN>
Oct 23 11:46:44 debian-gateway connmand[9092]: wlan0 {RX} 289974 packets
415132327 bytes
Oct 23 11:46:44 debian-gateway connmand[9092]: wlan0 {TX} 139436 packets
15087832 bytes
Oct 23 11:46:44 debian-gateway kernel: rtlwifi: AP off, try to reconnect now
Oct 23 11:46:44 debian-gateway kernel: wlan0: Connection to AP #AP_MAC# lost
Oct 23 11:46:44 debian-gateway wpa_supplicant[532]: wlan0:
CTRL-EVENT-DISCONNECTED bssid=#AP_MAC# reason=4 locally_generated=1
Oct 23 11:46:44 debian-gateway wpa_supplicant[532]: BSSID #AP_MAC# ignore list
count incremented to 2, ignoring for 10 seconds
Oct 23 11:46:44 debian-gateway wpa_supplicant[532]: wlan0:
CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Oct 23 11:46:50 debian-gateway kernel: wlan0: authenticate with #AP_MAC#
Oct 23 11:46:50 debian-gateway kernel: wlan0: 80 MHz not supported, disabling
VHT
Oct 23 11:46:50 debian-gateway wpa_supplicant[532]: wlan0: SME: Trying to
authenticate with #AP_MAC# (SSID=#SSID_NAME# freq=2462 MHz)
Oct 23 11:46:50 debian-gateway kernel: wlan0: send auth to #AP_MAC# (try 1/3)
Oct 23 11:46:50 debian-gateway wpa_supplicant[532]: wlan0: Trying to associate
with #AP_MAC# (SSID=#SSID_NAME# freq=2462 MHz)
Oct 23 11:46:50 debian-gateway kernel: wlan0: authenticated
Oct 23 11:46:50 debian-gateway kernel: wlan0: associate with #AP_MAC# (try 1/3)
Oct 23 11:46:50 debian-gateway kernel: wlan0: associate with #AP_MAC# (try 2/3)
Oct 23 11:46:50 debian-gateway connman-vpnd[9104]: wlan0 {update} flags 102403
<UP,LOWER_UP>
Oct 23 11:46:50 debian-gateway connman-vpnd[9104]: wlan0 {newlink} index 3
address #WLAN_MAC# mtu 1500
Oct 23 11:46:50 debian-gateway connman-vpnd[9104]: wlan0 {newlink} index 3
operstate 5 <DORMANT>
Oct 23 11:46:50 debian-gateway kernel: wlan0: RX AssocResp from #AP_MAC#
(capab=0x431 status=0 aid=48)
Oct 23 11:46:50 debian-gateway kernel: wlan0: associated
Oct 23 11:46:50 debian-gateway wpa_supplicant[532]: wlan0: Associated with
#AP_MAC#
Oct 23 11:46:50 debian-gateway wpa_supplicant[532]: wlan0:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 23 11:46:50 debian-gateway wpa_supplicant[532]: wlan0: WPA: Key negotiation
completed with #AP_MAC# [PTK=CCMP GTK=CCMP]
Oct 23 11:46:50 debian-gateway wpa_supplicant[532]: wlan0: CTRL-EVENT-CONNECTED
- Connection to #AP_MAC# completed [id=2 id_str=]
Oct 23 11:46:50 debian-gateway connman-vpnd[9104]: wlan0 {update} flags 102467
<UP,RUNNING,LOWER_UP>
Oct 23 11:46:50 debian-gateway connman-vpnd[9104]: wlan0 {newlink} index 3
address #WLAN_MAC# mtu 1500
Oct 23 11:46:50 debian-gateway connman-vpnd[9104]: wlan0 {newlink} index 3
operstate 6 <UP>
Oct 23 11:46:50 debian-gateway wpa_supplicant[532]: bgscan simple: Failed to
enable signal strength monitoring
Oct 23 11:47:37 debian-gateway dbus-daemon[757]: [session uid=1001 pid=755]
Activating service name='org.xfce.Xfconf' requested by ':1.9' (uid=1001 pid=839
comm="Thunar --daemon")
Oct 23 11:47:37 debian-gateway dbus-daemon[757]: [session uid=1001 pid=755]
Successfully activated service 'org.xfce.Xfconf'
Oct 23 11:47:56 debian-gateway connman-vpnd[9104]: Terminating
Oct 23 11:47:56 debian-gateway systemd[1]: Stopping connman-vpn.service -
ConnMan VPN service...
Oct 23 11:47:56 debian-gateway connman-vpnd[9104]: eth0 {remove} index 2
Oct 23 11:47:56 debian-gateway connman-vpnd[9104]: tether {remove} index 4
Oct 23 11:47:56 debian-gateway connman-vpnd[9104]: wlan0 {remove} index 3
Oct 23 11:47:56 debian-gateway connman-vpnd[9104]: Exit
Oct 23 11:47:56 debian-gateway systemd[1]: connman-vpn.service: Deactivated
successfully.
Oct 23 11:47:56 debian-gateway systemd[1]: Stopped connman-vpn.service -
ConnMan VPN service.
Oct 23 11:48:04 debian-gateway systemd[1]: connman-wait-online.service:
Deactivated successfully.
Oct 23 11:48:04 debian-gateway systemd[1]: Stopped connman-wait-online.service
- Wait for network to be configured by ConnMan.
Oct 23 11:48:04 debian-gateway systemd[1]: Stopping connman.service -
Connection service...
Oct 23 11:49:34 debian-gateway systemd[1]: connman.service: State
'stop-sigterm' timed out. Killing.
Oct 23 11:49:34 debian-gateway systemd[1]: connman.service: Killing process
9092 (connmand) with signal SIGKILL.
Oct 23 11:49:34 debian-gateway systemd[1]: connman.service: Main process
exited, code=killed, status=9/KILL
Oct 23 11:49:34 debian-gateway systemd[1]: connman.service: Failed with result
'timeout'.
Oct 23 11:49:34 debian-gateway systemd[1]: Stopped connman.service - Connection
service.
Oct 23 11:49:34 debian-gateway systemd[1]: connman.service: Consumed 2min
46.813s CPU time.
Command "sudo service connman restart" has no effect - after restart bug
immediately repeats.
I uses combination "sudo service connman-vpn stop" and "sudo service connman
restart".
When bug occurs, Connman VPN has no active VPN connections. More, Connman VPN
don't have configured VPN connections at all.
Architecture: amd64
library versions:
libc6: 2.36-9+deb12u3
libglib2.0-0: 2.78.0-2
libdbus-1-3: 1.14.10-1~deb12u1
libxtables12: 1.8.9-2
I try to use various kernels, and found that bug may occurs on kernels 5.16,
6.1, 6.5.
I have used 1.41-3 and kernel 6.1 without this problem for a long time.
