Hi all,
I have an issue where my Raspberry Pi 4 fails to come online via a wired connection. The connection state remains "ready" and never gets itself to "online".
This occurs on the built-in network interface, but also on a USB3.0 network interface that I tested. I suspect for some reason, the 'online-check' of connman does not initiate. In my logs I can also see that the route described in https://wiki.archlinux.org/title/ConnMan#…e_on_connection is never made:
On eth0:
Feb 27 18:26:11.584990 LibreELEC connman-vpnd[454]: eth0 {update} flags 102467 <UP,RUNNING,LOWER_UP>
Feb 27 18:26:11.584991 LibreELEC connmand[747]: eth0 {add} route fe80:: gw :: scope 0 <UNIVERSE>
Feb 27 18:26:11.585006 LibreELEC connman-vpnd[454]: eth0 {newlink} index 2 address E4:5F:01:F5:ED:BF mtu 1500
Feb 27 18:26:11.585015 LibreELEC connman-vpnd[454]: eth0 {newlink} index 2 operstate 6 <UP>
Feb 27 18:26:11.585038 LibreELEC connmand[747]: eth0 {update} flags 102467 <UP,RUNNING,LOWER_UP>
Feb 27 18:26:11.585050 LibreELEC connmand[747]: eth0 {newlink} index 2 address E4:5F:01:F5:ED:BF mtu 1500
Feb 27 18:26:11.585059 LibreELEC connmand[747]: eth0 {newlink} index 2 operstate 6 <UP>
Feb 27 18:26:11.587914 LibreELEC kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Feb 27 18:26:11.601832 LibreELEC kernel: 8021q: 802.1Q VLAN Support v1.8
Feb 27 18:26:11.628427 LibreELEC avahi-daemon[769]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.128.69.
Feb 27 18:26:11.628501 LibreELEC avahi-daemon[769]: New relevant interface eth0.IPv4 for mDNS.
Feb 27 18:26:11.628572 LibreELEC avahi-daemon[769]: Registering new address record for 10.0.128.69 on eth0.IPv4.
Feb 27 18:26:11.629049 LibreELEC connmand[747]: Setting domainname to domumadinferos.nl
Feb 27 18:26:11.668026 LibreELEC connmand[747]: eth0 {add} address 10.0.128.69/16 label eth0 family 2
Feb 27 18:26:11.668827 LibreELEC connmand[747]: eth0 {add} route 10.0.0.0 gw 0.0.0.0 scope 253 <LINK>
Feb 27 18:26:11.668865 LibreELEC connmand[747]: eth0 {add} route 10.0.0.1 gw 0.0.0.0 scope 253 <LINK>
Feb 27 18:26:11.668903 LibreELEC connmand[747]: eth0 {add} route 9.9.9.9 gw 10.0.0.1 scope 0 <UNIVERSE>
Feb 27 18:26:11.668939 LibreELEC connmand[747]: eth0 {add} route 1.1.1.1 gw 10.0.0.1 scope 0 <UNIVERSE>
Feb 27 18:26:11.668977 LibreELEC connmand[747]: eth0 {add} route 0.0.0.0 gw 10.0.0.1 scope 0 <UNIVERSE>
.... end for eth0
Display More
When Kodi has booted, I go into the LibreELEC settings to disconnect to the wired network and connect to a wireless network, and the behaviour shows in the logs like this (wlan0):
Feb 27 18:28:16.889749 LibreELEC avahi-daemon[769]: Withdrawing address record for 10.0.128.69 on eth0.
Feb 27 18:28:16.889849 LibreELEC avahi-daemon[769]: Leaving mDNS multicast group on interface eth0.IPv4 with address 10.0.128.69.
Feb 27 18:28:16.889907 LibreELEC avahi-daemon[769]: Interface eth0.IPv4 no longer relevant for mDNS.
Feb 27 18:28:16.892001 LibreELEC avahi-daemon[769]: Withdrawing address record for fe80::e65f:1ff:fef5:edbf on eth0.
Feb 27 18:28:16.892083 LibreELEC avahi-daemon[769]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::e65f:1ff:fef5:edbf.
Feb 27 18:28:16.914563 LibreELEC kernel: bcmgenet fd580000.ethernet eth0: Link is Down
Feb 27 18:28:16.964784 LibreELEC connman-vpnd[454]: eth0 {update} flags 36866 <DOWN>
Feb 27 18:28:16.964811 LibreELEC connman-vpnd[454]: eth0 {newlink} index 2 address E4:5F:01:F5:ED:BF mtu 1500
Feb 27 18:28:16.964829 LibreELEC connman-vpnd[454]: eth0 {newlink} index 2 operstate 2 <DOWN>
Feb 27 18:28:16.966014 LibreELEC avahi-daemon[769]: Interface eth0.IPv6 no longer relevant for mDNS.
Feb 27 18:28:16.972738 LibreELEC connmand[747]: eth0 {del} address 10.0.128.69/16 label eth0
Feb 27 18:28:16.972829 LibreELEC connmand[747]: eth0 {del} route 10.0.0.0 gw 0.0.0.0 scope 253 <LINK>
Feb 27 18:28:16.972947 LibreELEC connmand[747]: eth0 {del} route fe80:: gw :: scope 0 <UNIVERSE>
Feb 27 18:28:16.973059 LibreELEC connmand[747]: eth0 {add} route fe80:: gw :: scope 0 <UNIVERSE>
Feb 27 18:28:16.973134 LibreELEC connmand[747]: eth0 {RX} 813 packets 162115 bytes
Feb 27 18:28:16.973151 LibreELEC connmand[747]: eth0 {TX} 324 packets 51924 bytes
Feb 27 18:28:16.973172 LibreELEC connmand[747]: eth0 {update} flags 36866 <DOWN>
Feb 27 18:28:16.973194 LibreELEC connmand[747]: eth0 {newlink} index 2 address E4:5F:01:F5:ED:BF mtu 1500
Feb 27 18:28:16.973211 LibreELEC connmand[747]: eth0 {newlink} index 2 operstate 2 <DOWN>
Feb 27 18:28:16.973400 LibreELEC connmand[747]: eth0 {del} route fe80:: gw :: scope 0 <UNIVERSE>
Feb 27 18:28:21.523575 LibreELEC connman-vpnd[454]: wlan0 {update} flags 4099 <UP>
Feb 27 18:28:21.523592 LibreELEC connman-vpnd[454]: wlan0 {newlink} index 3 address E4:5F:01:F5:ED:C1 mtu 1500
Feb 27 18:28:21.523601 LibreELEC connman-vpnd[454]: wlan0 {newlink} index 3 operstate 2 <DOWN>
Feb 27 18:28:21.524549 LibreELEC iwd[476]: event: state, old: disconnected, new: autoconnect_quick
Feb 27 18:28:21.524963 LibreELEC kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
Feb 27 18:28:21.526824 LibreELEC connmand[747]: wlan0 {update} flags 4099 <UP>
Feb 27 18:28:21.526839 LibreELEC connmand[747]: wlan0 {newlink} index 3 address E4:5F:01:F5:ED:C1 mtu 1500
Feb 27 18:28:21.526849 LibreELEC connmand[747]: wlan0 {newlink} index 3 operstate 2 <DOWN>
Feb 27 18:28:21.545542 LibreELEC iwd[476]: Could not register frame watch type 00d0: -114
Feb 27 18:28:25.958421 LibreELEC connmand[747]: ipconfig state 2 ipconfig method 1
Feb 27 18:28:26.024081 LibreELEC iwd[476]: event: connect-info, ssid: home-network-irrelevant-for-this-log, bss: 68:d7:9a:45:d8:53, signal: -52, load: 27/255
Feb 27 18:28:26.024081 LibreELEC iwd[476]: event: state, old: autoconnect_full, new: connecting
Feb 27 18:28:26.024734 LibreELEC iwd[476]: CMD_SET_CQM failed: Operation not supported
Feb 27 18:28:26.057961 LibreELEC connmand[747]: ipconfig state 3 ipconfig method 1
Feb 27 18:28:26.166345 LibreELEC connman-vpnd[454]: wlan0 {update} flags 69635 <UP,LOWER_UP>
Feb 27 18:28:26.166362 LibreELEC connman-vpnd[454]: wlan0 {newlink} index 3 address E4:5F:01:F5:ED:C1 mtu 1500
Feb 27 18:28:26.167153 LibreELEC iwd[476]: event: state, old: connecting, new: connected
Feb 27 18:28:26.166372 LibreELEC connman-vpnd[454]: wlan0 {newlink} index 3 operstate 5 <DORMANT>
Feb 27 18:28:26.166683 LibreELEC connmand[747]: wlan0 {update} flags 69635 <UP,LOWER_UP>
Feb 27 18:28:26.166698 LibreELEC connmand[747]: wlan0 {newlink} index 3 address E4:5F:01:F5:ED:C1 mtu 1500
Feb 27 18:28:26.166708 LibreELEC connmand[747]: wlan0 {newlink} index 3 operstate 5 <DORMANT>
Feb 27 18:28:26.193711 LibreELEC connman-vpnd[454]: wlan0 {update} flags 69699 <UP,RUNNING,LOWER_UP>
Feb 27 18:28:26.193732 LibreELEC connman-vpnd[454]: wlan0 {newlink} index 3 address E4:5F:01:F5:ED:C1 mtu 1500
Feb 27 18:28:26.193740 LibreELEC connmand[747]: wlan0 {update} flags 69699 <UP,RUNNING,LOWER_UP>
Feb 27 18:28:26.193741 LibreELEC connman-vpnd[454]: wlan0 {newlink} index 3 operstate 6 <UP>
Feb 27 18:28:26.193757 LibreELEC connmand[747]: wlan0 {newlink} index 3 address E4:5F:01:F5:ED:C1 mtu 1500
Feb 27 18:28:26.193767 LibreELEC connmand[747]: wlan0 {newlink} index 3 operstate 6 <UP>
Feb 27 18:28:26.194561 LibreELEC kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0x5, action 0x4
Feb 27 18:28:32.415153 LibreELEC avahi-daemon[769]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.128.68.
Feb 27 18:28:32.418327 LibreELEC avahi-daemon[769]: New relevant interface wlan0.IPv4 for mDNS.
Feb 27 18:28:32.418515 LibreELEC avahi-daemon[769]: Registering new address record for 10.0.128.68 on wlan0.IPv4.
Feb 27 18:28:32.419372 LibreELEC connmand[747]: Setting domainname to domumadinferos.nl
Feb 27 18:28:32.458275 LibreELEC connmand[747]: wlan0 {add} address 10.0.128.68/16 label wlan0 family 2
Feb 27 18:28:32.460470 LibreELEC connmand[747]: wlan0 {add} route 10.0.0.0 gw 0.0.0.0 scope 253 <LINK>
Feb 27 18:28:32.460546 LibreELEC connmand[747]: wlan0 {add} route 10.0.0.1 gw 0.0.0.0 scope 253 <LINK>
Feb 27 18:28:32.460616 LibreELEC connmand[747]: wlan0 {add} route 9.9.9.9 gw 10.0.0.1 scope 0 <UNIVERSE>
Feb 27 18:28:32.460682 LibreELEC connmand[747]: wlan0 {add} route 1.1.1.1 gw 10.0.0.1 scope 0 <UNIVERSE>
Feb 27 18:28:32.460860 LibreELEC connmand[747]: wlan0 {add} route 0.0.0.0 gw 10.0.0.1 scope 0 <UNIVERSE>
Feb 27 18:28:32.475356 LibreELEC connmand[747]: wlan0 {add} route 82.165.8.211 gw 10.0.0.1 scope 0 <UNIVERSE>
Feb 27 18:28:32.499161 LibreELEC connmand[747]: ntp: adjust (jump): +15797799.355068 sec
Aug 28 15:45:11.873514 LibreELEC connmand[747]: wlan0 {del} route 82.165.8.211 gw 10.0.0.1 scope 0 <UNIVERSE>
Display More
The DHCP server behind both these networks is the same, i.e. there is no difference in issued DNS-servers or time servers for that matter.
I seem to be reaching in the dark as to why the Pi fails to come online through a wired connection, as the documentation of connman and other online resources do not really help me. Anyone willing to take a guess on what kind of behavior is shown here, and maybe help me out? Might it be possible to disable connman's online check, or what might cause it to not be started at all?