Constant connectivity checks?

myxal · August 10, 2021 at 11:13 AM

Cheers, I'm using LE 9.2.6 on Raspberry Pi 3.

A recent rollout of IPv6 had me shuffle a few network settings:

Pi and other devices are still pointed to a local dnsmasq instance running on v4 (via DHCPv4) and v6 (via DHCPv6)
Upstream DNS was swapped to nextdns for filtering and analytics - this is where I noticed the issue.
Devices get self assigned GUA and ULA - the prefixes advertised have lifetimes I got from ISP router's defaults 10800/14400 and 3600/7200, respectively. The expected addresses are seen assigned on the pi (via SSH -> ip -6 addr sh, I couldn't find v6 info in Kodi/LE GUI)

After noticing the huge amount of queries to ipv6.connman.net I checked my local resolver's logs and saw that LE on the Pi is querying this every 3 minutes (all the time, since the pi isn't turned off) - and it checks both via IPv4, and IPv6 (the ULA) simultaneously.

dnsmasq logs

Code

Aug 10 11:34:35 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 11:37:31 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 11:37:31 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from 192.168.2.59
Aug 10 11:40:28 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 11:40:28 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from 192.168.2.59
Aug 10 11:43:24 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 11:43:24 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from 192.168.2.59
Aug 10 11:46:20 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from 192.168.2.59
Aug 10 11:46:20 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 11:49:16 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 11:49:16 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from 192.168.2.59
Aug 10 11:52:13 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 11:52:13 rpi1 dnsmasq[8836]: query[AAAA] ipv6.connman.net from 192.168.2.59

Display More

I checked connman logs (journalctl - u connman.service) and kodi logs (.../temp/kodi.log) and didn't see anything that would match the periodicity or occurrences.

Any idea where else to look?

Edit: Hmm, I enabled CONNMAN_WEB_DEBUG for the service and the loaded URL, http://ipv6.connman.net/online/status.html is returning HTTP 502 Bad Gateway on IPv4, and is completely silent on IPv6. I guess that's the problem.

Connman logs

Code

Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:g_resolv_cancel_lookup() resolv 0x12a5a00 lookup id 1
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:g_resolv_add_nameserver() resolv 0x12a5a00 setting nameserver 192.168.2.3
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:g_resolv_add_nameserver() resolv 0x12a5a00 setting nameserver fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:set_accept_option() web 0x129fd70 clearing accept option
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:set_user_agent() web 0x129fd70 setting user agent ConnMan/1.38 wispr
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:do_request() web 0x129fd70 request http://ipv6.connman.net/online/status.html
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:do_request() web 0x129fd70 proxy host (null)
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:do_request() web 0x129fd70 port 80
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:do_request() web 0x129fd70 host ipv6.connman.net
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:do_request() web 0x129fd70 flags 0
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:do_request() web 0x129fd70 request /online/status.html
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:g_resolv_lookup_hostname() resolv 0x12a5a00 hostname ipv6.connman.net
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:add_query() resolv 0x12a5a00 sending 34 bytes
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:add_query() resolv 0x12a5a00 lookup 0x12a32a8 id 1 query 0x129c068
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:g_resolv_lookup_hostname() resolv 0x12a5a00 lookup 0x12a32a8 id 1
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:parse_response() resolv 0x12a5a00 response from 192.168.2.3
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:parse_response() resolv 0x12a5a00 msg id: 0x833a rcode: 0 count: 1
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:destroy_query() resolv 0x12a5a00 query 0x129c068 timeout 152
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:sort_and_return_results() resolv 0x12a5a00 lookup 0x12a32a8 received 1 results
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:destroy_lookup() resolv 0x12a5a00 lookup 0x12a32a8 id 1 ipv4 (nil) ipv6 (nil)
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:handle_resolved_address() web 0x129fd70 address 2001:8d8:8b4:c861:5826:fa5f:6690:0
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:connect_session_transport() web 0x129fd70 no encryption
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gweb.c:create_transport() web 0x129fd70 creating session 2001:8d8:8b4:c861:5826:fa5f:6690:0:80
Aug 10 12:22:09 rpi3 connmand[3241]: WEB: ../gweb/gresolv.c:parse_response() resolv 0x12a5a00 response from fd0c:xxxx:xxxx::xxxx:xxxx:xxxx:xxxx

Display More