Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

ipset kernel error causing to OTBR docker container crash #3497

Closed
ygfcl opened this issue Mar 1, 2024 · 6 comments
Closed

ipset kernel error causing to OTBR docker container crash #3497

ygfcl opened this issue Mar 1, 2024 · 6 comments

Comments

@ygfcl
Copy link

ygfcl commented Mar 1, 2024

Describe the issue you are experiencing

I installed the official addon OpenThread Border Router. But it always failed to start when running to the step named ipset (according to the logs I saw).

My system information:

root@Debian:~# cat /etc/os-release
PRETTY_NAME="Debian GNU/Linux 12 (bookworm)"
NAME="Debian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
root@Debian:~# uname -a
Linux Debian 5.10.100-g304c70ad206a-dirty #82 SMP Thu Nov 16 13:13:14 CST 2023 aarch64 GNU/Linux

the container exit code is 5

root@Debian:~# docker ps -a
CONTAINER ID   IMAGE                                                       COMMAND               CREATED        STATUS                   PORTS                                   NAMES
8e0dbb20e260   homeassistant/aarch64-addon-otbr:2.5.0                      "/init"               2 hours ago    Exited (5) 2 hours ago                                           addon_core_openthread_border_router
1a5944fbb98e   ghcr.io/home-assistant/aarch64-hassio-multicast:2023.06.2   "/init"               2 hours ago    Up 2 hours                                                       hassio_multicast
954f3475804b   ghcr.io/home-assistant/aarch64-hassio-audio:2023.12.0       "/init"               2 hours ago    Up 2 hours                                                       hassio_audio
dc4089276716   ghcr.io/home-assistant/aarch64-hassio-dns:2023.06.2         "/init"               2 hours ago    Up 2 hours                                                       hassio_dns
b4dac16017e6   ghcr.io/home-assistant/aarch64-hassio-cli:2023.11.0         "/init"               2 hours ago    Up 2 hours                                                       hassio_cli
2caacbb48037   ghcr.io/home-assistant/qemuarm-64-homeassistant:2024.2.5    "/init"               26 hours ago   Up 2 hours                                                       homeassistant
234d9ff7139f   ghcr.io/home-assistant/aarch64-hassio-supervisor:latest     "/init"               46 hours ago   Up 2 hours                                                       hassio_supervisor
63d69e1342a4   ghcr.io/home-assistant/aarch64-hassio-observer:2023.06.0    "/usr/bin/observer"   4 months ago   Up 2 hours               0.0.0.0:4357->80/tcp, :::4357->80/tcp   hassio_observer

The log of addon

otbr-agent[165]: 00:00:34.069 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.071 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.071 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:1:3 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.072 [I] Platform------: [netif] ADD [M] ff33:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.073 [I] Platform------: [netif] ADD [M] ff32:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.074 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[165]: 00:00:34.074 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.075 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.076 [W] Platform------: [netif] ADD [U] fd0b:b700:7a1d:49e3:0:ff:fe00:fc00 failed (InvalidArgs)
otbr-agent[165]: 00:00:34.077 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[165]: 00:00:34.082 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:1:3 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.082 [I] Platform------: [netif] ADD [M] ff33:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.083 [I] Platform------: [netif] ADD [M] ff32:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.084 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[165]: 00:00:34.085 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.085 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.087 [I] Platform------: [netif] Succeeded to process request#10
otbr-agent[165]: 00:00:34.092 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.093 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.094 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:1:3 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.095 [I] Platform------: [netif] ADD [M] ff33:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.095 [I] Platform------: [netif] ADD [M] ff32:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.096 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[165]: 00:00:34.097 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.098 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.106 [I] MeshForwarder-: Sent IPv6 UDP msg, len:96, chksum:c15e, ecn:no, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[165]: 00:00:34.106 [I] MeshForwarder-:     src:[fe80:0:0:0:68dd:4672:82bb:335c]:19788
otbr-agent[165]: 00:00:34.107 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[165]: 00:00:34.435 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[165]: 00:00:34.438 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.439 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.440 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:1:3 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.440 [I] Platform------: [netif] ADD [M] ff33:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.441 [I] Platform------: [netif] ADD [M] ff32:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.441 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[165]: 00:00:34.442 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.443 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.446 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.447 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.448 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:1:3 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.449 [I] Platform------: [netif] ADD [M] ff33:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.449 [I] Platform------: [netif] ADD [M] ff32:40:fd0b:b700:7a1d:49e3:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.450 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[165]: 00:00:34.451 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.451 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[165]: 00:00:34.660 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[165]: 00:00:34.679 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:9533, ecn:no, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[165]: 00:00:34.680 [I] MeshForwarder-:     src:[fe80:0:0:0:68dd:4672:82bb:335c]:19788
otbr-agent[165]: 00:00:34.680 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[165]: [INFO]-MDNS----: Successfully registered service Home Assistant OpenThread Border Router #335C._meshcop._udp
otbr-agent[165]: [INFO]-BA------: Result of publish meshcop service Home Assistant OpenThread Border Router #335C._meshcop._udp.local: OK
otbr-agent[165]: 00:00:34.871 [I] BbrLocal------: Add BBR Service: seqno (35), delay (5s), timeout (3600s), OK
otbr-agent[165]: 00:00:34.873 [I] NetworkData---: Sent ServerData
otbr-agent[165]: 00:00:34.874 [I] NetworkData---: Received ServerData
otbr-agent[165]: 00:00:34.875 [I] NetworkData---: Allocated Service ID = 0
otbr-agent[165]: 00:00:34.875 [I] NetworkData---: Sent ServerData ack
otbr-agent[165]: 00:00:34.877 [I] Notifier------: StateChanged (0x00000200) [NetData]
otbr-agent[165]: 00:00:34.878 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[165]: 00:00:34.878 [I] BbrLeader-----: PBBR state: Added
otbr-agent[165]: 00:00:34.879 [I] BbrLeader-----: Rloc16:0xe000, seqno:35, delay:5, timeout:3600
otbr-agent[165]: 00:00:34.883 [I] Platform------: [netif] Sent request#11 to add fd0b:b700:7a1d:49e3:0:ff:fe00:fc38/64
otbr-agent[165]: 00:00:34.884 [I] Platform------: [netif] Sent request#12 to add fd0b:b700:7a1d:49e3:0:ff:fe00:fc10/64
otbr-agent[165]: 00:00:34.885 [I] RoutingManager: Will evaluate routing policy in 00:02.069 (2069 msec)
ipset v7.17: Kernel error received: Invalid argument
otbr-agent[165]: 00:00:34.926 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 256
otbr-agent[165]: 00:00:34.926 [I] Platform------: Got an error when executing command `ipset flush otbr-ingress-allow-dst-swap`: `Resource temporarily unavailable`
otbr-agent[165]: 00:00:34.927 [W] Platform------: Failed to update ipsets: Failed
otbr-agent[165]: [INFO]-BA------: Publish meshcop service Home Assistant OpenThread Border Router #335C._meshcop._udp.local.
otbr-agent[165]: [INFO]-MDNS----: Removing existing service Home Assistant OpenThread Border Router #335C._meshcop._udp: outdated
otbr-agent[165]: [INFO]-MDNS----: Removing service Home Assistant OpenThread Border Router #335C._meshcop._udp
otbr-agent[165]: [INFO]-MDNS----: Registering service Home Assistant OpenThread Border Router #335C._meshcop._udp
otbr-agent[165]: 00:00:34.975 [I] Notifier------: StateChanged (0x02000001) [Ip6+ BbrState]
otbr-agent[165]: 00:00:34.976 [I] Bbr-----------: Start listening on port 61631
otbr-agent[165]: 00:00:34.976 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fd0b:b700:7a1d:49e3:0:3: OK
otbr-agent[165]: 00:00:34.977 [I] BbrManager----: Start Backbone TMF agent: OK
otbr-agent[165]: 00:00:34.978 [C] Platform------: InitMulticastRouterSock() at multicast_routing.cpp:225: Protocol not available
[11:44:55] WARNING: otbr-agent exited with code 5 (by signal 0).
[11:44:55] INFO: OTBR firewall teardown completed.
s6-rc: info: service legacy-services: stopping
s6-rc: info: service legacy-services successfully stopped
s6-rc: info: service otbr-agent-rest-discovery: stopping
s6-rc: info: service otbr-agent-configure: stopping
s6-rc: info: service otbr-agent-rest-discovery successfully stopped
s6-rc: info: service otbr-agent-configure successfully stopped
s6-rc: info: service otbr-agent: stopping
s6-rc: info: service otbr-agent successfully stopped
s6-rc: info: service legacy-cont-init: stopping
s6-rc: info: service universal-silabs-flasher: stopping
s6-rc: info: service mdns: stopping
Default: mDNSResponder (Engineering Build) (Feb 29 2024 14:39:08) stopping
s6-rc: info: service universal-silabs-flasher successfully stopped
s6-rc: info: service banner: stopping
s6-rc: info: service banner successfully stopped
s6-rc: info: service legacy-cont-init successfully stopped
s6-rc: info: service fix-attrs: stopping
s6-rc: info: service fix-attrs successfully stopped
s6-rc: info: service s6rc-oneshot-runner: stopping
s6-rc: info: service s6rc-oneshot-runner successfully stopped
[11:44:55] INFO: mDNS ended with exit code 4 (signal 0)...
s6-rc: info: service mdns successfully stopped

What type of installation are you running?

Home Assistant Supervised

Which operating system are you running on?

Debian

Which add-on are you reporting an issue with?

OpenThread Border Router

What is the version of the add-on?

2.5.0

Steps to reproduce the issue

no need to reproduce , it always fails in my environment.

System Health information

System Information

version core-2024.2.5
installation_type Home Assistant Supervised
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.12.1
os_name Linux
os_version 5.10.100-g304c70ad206a-dirty
arch aarch64
timezone Asia/Shanghai
config_dir /config
Home Assistant Cloud
logged_in false
can_reach_cert_server ok
can_reach_cloud_auth ok
can_reach_cloud ok
Home Assistant Supervisor
host_os Debian GNU/Linux 12 (bookworm)
update_channel stable
supervisor_version supervisor-2024.02.1
agent_version 1.6.0
docker_version 24.0.6
disk_total 28.4 GB
disk_used 9.2 GB
healthy true
supported true
supervisor_api ok
version_api ok
installed_addons OpenThread Border Router (2.5.0)
Dashboards
dashboards 1
resources 0
mode auto-gen
Recorder
oldest_recorder_run 28 February 2024 at 06:17
current_recorder_run 1 March 2024 at 11:39
estimated_db_size 2.87 MiB
database_engine sqlite
database_version 3.44.2

Anything in the Supervisor logs that might be useful for us?

24-03-01 11:38:42 INFO (MainThread) [supervisor.plugins.audio] Starting Audio plugin
24-03-01 11:38:42 INFO (SyncWorker_0) [supervisor.docker.manager] Cleaning hassio_audio application
24-03-01 11:38:45 INFO (MainThread) [supervisor.docker.audio] Starting Audio ghcr.io/home-assistant/aarch64-hassio-audio with version 2023.12.0 - 172.30.32.4
24-03-01 11:38:45 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-observer with version 2023.06.0
24-03-01 11:38:45 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-multicast with version 2023.06.2
24-03-01 11:38:45 INFO (MainThread) [supervisor.plugins.multicast] Starting Multicast plugin
24-03-01 11:38:45 INFO (SyncWorker_1) [supervisor.docker.manager] Cleaning hassio_multicast application
24-03-01 11:38:47 INFO (MainThread) [supervisor.docker.multicast] Starting Multicast ghcr.io/home-assistant/aarch64-hassio-multicast with version 2023.06.2 - Host
24-03-01 11:38:47 INFO (MainThread) [supervisor.homeassistant.secrets] Loaded 1 Home Assistant secrets
24-03-01 11:38:47 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/qemuarm-64-homeassistant with version 2024.2.5
24-03-01 11:38:47 INFO (MainThread) [supervisor.os.manager] No Home Assistant Operating System found
24-03-01 11:38:47 WARNING (MainThread) [supervisor.homeassistant.core] Watchdog found Home Assistant failed, restarting...
24-03-01 11:38:47 INFO (SyncWorker_0) [supervisor.docker.manager] Starting homeassistant
24-03-01 11:38:49 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
24-03-01 11:38:54 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a0d7b954 repository
24-03-01 11:38:54 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/5c53de3b repository
24-03-01 11:38:54 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/45df7312 repository
24-03-01 11:38:54 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/core repository
24-03-01 11:38:58 INFO (MainThread) [supervisor.store] Loading add-ons from store: 80 all - 80 new - 0 remove
24-03-01 11:38:58 INFO (MainThread) [supervisor.addons.manager] Found 1 installed add-ons
24-03-01 11:38:58 INFO (MainThread) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-otbr with version 2.4.7
24-03-01 11:38:59 INFO (MainThread) [supervisor.backups.manager] Found 3 backup files
24-03-01 11:38:59 INFO (MainThread) [supervisor.discovery] Loaded 1 messages
24-03-01 11:38:59 INFO (MainThread) [supervisor.ingress] Loaded 0 ingress sessions
24-03-01 11:38:59 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state setup
24-03-01 11:38:59 INFO (MainThread) [supervisor.resolution.check] System checks complete
24-03-01 11:38:59 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state setup
24-03-01 11:38:59 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-01 11:38:59 INFO (MainThread) [supervisor.jobs] 'ResolutionFixup.run_autofix' blocked from execution, system is not running - setup
24-03-01 11:38:59 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state setup
24-03-01 11:38:59 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-01 11:38:59 INFO (MainThread) [__main__] Running Supervisor
24-03-01 11:38:59 INFO (MainThread) [supervisor.jobs] 'OSManager.mark_healthy' blocked from execution, no Home Assistant OS available
24-03-01 11:38:59 INFO (MainThread) [supervisor.addons.manager] Phase 'initialize' starting 0 add-ons
24-03-01 11:38:59 INFO (MainThread) [supervisor.addons.manager] Phase 'system' starting 0 add-ons
24-03-01 11:38:59 INFO (MainThread) [supervisor.addons.manager] Phase 'services' starting 1 add-ons
24-03-01 11:38:59 INFO (SyncWorker_4) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
24-03-01 11:39:01 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-otbr with version 2.4.7
24-03-01 11:39:06 INFO (MainThread) [supervisor.core] Skipping start of Home Assistant
24-03-01 11:39:06 INFO (MainThread) [supervisor.addons.manager] Phase 'application' starting 0 add-ons
24-03-01 11:39:06 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled
24-03-01 11:39:06 INFO (MainThread) [supervisor.core] Supervisor is up and running
24-03-01 11:39:06 INFO (MainThread) [supervisor.host.info] Updating local host information
24-03-01 11:39:06 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for no_current_backup/system
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.module] Create new suggestion create_full_backup - system / None
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.module] Create new issue no_current_backup - system / None
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.check] System checks complete
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
24-03-01 11:39:06 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
24-03-01 11:39:09 INFO (MainThread) [supervisor.host.services] Updating service information
24-03-01 11:39:09 INFO (MainThread) [supervisor.host.network] Updating local network information
24-03-01 11:39:10 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
24-03-01 11:39:10 INFO (MainThread) [supervisor.host.manager] Host information reload completed
24-03-01 11:39:29 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
24-03-01 11:39:37 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
24-03-01 11:39:42 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to NOT_RUNNING
24-03-01 11:40:17 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
24-03-01 11:40:17 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
24-03-01 11:40:53 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to RUNNING
24-03-01 11:40:53 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance
24-03-01 11:41:36 INFO (MainThread) [supervisor.backups.manager] Backup e3f2b0f6 starting stage addon_repositories
24-03-01 11:41:36 INFO (MainThread) [supervisor.backups.manager] Backup e3f2b0f6 starting stage docker_config
24-03-01 11:41:36 INFO (MainThread) [supervisor.backups.manager] Creating new partial backup with slug e3f2b0f6
24-03-01 11:41:36 INFO (MainThread) [supervisor.backups.manager] Backup e3f2b0f6 starting stage addons
24-03-01 11:41:36 INFO (MainThread) [supervisor.addons.addon] Building backup for add-on core_openthread_border_router
24-03-01 11:41:37 INFO (MainThread) [supervisor.addons.addon] Finish backup for addon core_openthread_border_router
24-03-01 11:41:37 INFO (MainThread) [supervisor.backups.manager] Backup e3f2b0f6 starting stage finishing_file
24-03-01 11:41:37 INFO (MainThread) [supervisor.backups.manager] Creating partial backup with slug e3f2b0f6 completed
24-03-01 11:41:37 INFO (MainThread) [supervisor.docker.addon] Updating image homeassistant/aarch64-addon-otbr:2.4.7 to homeassistant/aarch64-addon-otbr:2.5.0
24-03-01 11:41:37 INFO (MainThread) [supervisor.docker.interface] Downloading docker image homeassistant/aarch64-addon-otbr with tag 2.5.0.
24-03-01 11:42:49 INFO (MainThread) [supervisor.addons.addon] Add-on 'core_openthread_border_router' successfully updated
24-03-01 11:42:49 INFO (SyncWorker_0) [supervisor.docker.manager] Cleanup images: ['homeassistant/aarch64-addon-otbr:2.4.7']
24-03-01 11:42:49 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
24-03-01 11:42:49 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/zigbee2mqtt/hassio-zigbee2mqtt repository
24-03-01 11:42:50 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
24-03-01 11:42:50 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
24-03-01 11:42:50 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
24-03-01 11:42:58 INFO (MainThread) [supervisor.store] Loading add-ons from store: 80 all - 0 new - 0 remove
24-03-01 11:42:58 INFO (MainThread) [supervisor.store] Loading add-ons from store: 80 all - 0 new - 0 remove
24-03-01 11:43:37 INFO (SyncWorker_2) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
24-03-01 11:43:40 WARNING (SyncWorker_5) [supervisor.docker.manager] Can't grep logs from addon_core_openthread_border_router: 409 Client Error for http+docker:https://localhost/v1.43/containers/4cef1f7e66e8de65e4164d3765c8d9192a19da73e85d6e629e263c09c71181cf/logs?stderr=1&stdout=1&timestamps=0&follow=0&tail=100: Conflict ("can not get logs from container which is dead or marked for removal")
24-03-01 11:43:41 WARNING (SyncWorker_1) [supervisor.docker.manager] Can't grep logs from addon_core_openthread_border_router: 409 Client Error for http+docker:https://localhost/v1.43/containers/4cef1f7e66e8de65e4164d3765c8d9192a19da73e85d6e629e263c09c71181cf/logs?stderr=1&stdout=1&timestamps=0&follow=0&tail=100: Conflict ("can not get logs from container which is dead or marked for removal")
24-03-01 11:43:43 WARNING (SyncWorker_4) [supervisor.docker.manager] Can't grep logs from addon_core_openthread_border_router: 409 Client Error for http+docker:https://localhost/v1.43/containers/4cef1f7e66e8de65e4164d3765c8d9192a19da73e85d6e629e263c09c71181cf/logs?stderr=1&stdout=1&timestamps=0&follow=0&tail=100: Conflict ("can not get logs from container which is dead or marked for removal")
24-03-01 11:43:44 WARNING (SyncWorker_5) [supervisor.docker.manager] Can't grep logs from addon_core_openthread_border_router: 409 Client Error for http+docker:https://localhost/v1.43/containers/4cef1f7e66e8de65e4164d3765c8d9192a19da73e85d6e629e263c09c71181cf/logs?stderr=1&stdout=1&timestamps=0&follow=0&tail=100: Conflict ("can not get logs from container which is dead or marked for removal")
24-03-01 11:43:44 WARNING (SyncWorker_1) [supervisor.docker.manager] Can't grep logs from addon_core_openthread_border_router: 409 Client Error for http+docker:https://localhost/v1.43/containers/4cef1f7e66e8de65e4164d3765c8d9192a19da73e85d6e629e263c09c71181cf/logs?stderr=1&stdout=1&timestamps=0&follow=0&tail=100: Conflict ("can not get logs from container which is dead or marked for removal")
24-03-01 11:43:46 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-otbr with version 2.5.0
24-03-01 11:44:07 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router

Anything in the add-on logs that might be useful for us?

No response

Additional information

No response

@agners
Copy link
Member

agners commented Mar 1, 2024

root@Debian:~# uname -a
Linux Debian 5.10.100-g304c70ad206a-dirty #82 SMP Thu Nov 16 13:13:14 CST 2023 aarch64 GNU/Linux

This does not seem to be the vanilla Linux kernel of a Debian 12/Bookworm installation. What type of kernel are you running?

My guess is that ipset kernel module ip_set etc. is not there. Can you run the following command on your host?

sudo ipset create test hash:net

If so, can you then try to start the add-on again?

@ygfcl
Copy link
Author

ygfcl commented Mar 1, 2024

ipset create test hash:net

Actually ipset is not installed on the host machine, it is installed in the addon docker container.
But still the ipset command fails because of kernel error even in the container, I'm not sure if there's some incompatibility between ipset and my kernel version.

root@Debian:~# docker ps -a
CONTAINER ID   IMAGE                                                       COMMAND               CREATED        STATUS                   PORTS                                   NAMES
0996bbd1b3fa   homeassistant/aarch64-addon-otbr:2.5.0                      "/init"               3 hours ago    Exited (5) 3 hours ago                                           addon_core_openthread_border_router
ea6a820e5f95   ghcr.io/home-assistant/aarch64-hassio-multicast:2023.06.2   "/init"               3 hours ago    Up 3 hours                                                       hassio_multicast
84b17bbb0f40   ghcr.io/home-assistant/aarch64-hassio-audio:2023.12.0       "/init"               3 hours ago    Up 3 hours                                                       hassio_audio
4e37605ab365   ghcr.io/home-assistant/aarch64-hassio-dns:2023.06.2         "/init"               3 hours ago    Up 3 hours                                                       hassio_dns
553fc6e14f0e   ghcr.io/home-assistant/aarch64-hassio-cli:2023.11.0         "/init"               3 hours ago    Up 3 hours                                                       hassio_cli
2caacbb48037   ghcr.io/home-assistant/qemuarm-64-homeassistant:2024.2.5    "/init"               30 hours ago   Up 3 hours                                                       homeassistant
234d9ff7139f   ghcr.io/home-assistant/aarch64-hassio-supervisor:latest     "/init"               2 days ago     Up 3 hours                                                       hassio_supervisor
63d69e1342a4   ghcr.io/home-assistant/aarch64-hassio-observer:2023.06.0    "/usr/bin/observer"   4 months ago   Up 3 hours               0.0.0.0:4357->80/tcp, :::4357->80/tcp   hassio_observer
root@Debian:~# ipset create test hash:net
-bash: ipset: command not found
root@Debian:~# docker start 0996bbd1b3fa && docker exec -it 0996bbd1b3fa /bin/bash
0996bbd1b3fa
root@Debian:/usr/src# ipset create test hash:net
ipset v7.17: Kernel error received: Invalid argument
root@Debian:/usr/src# uname -a
Linux Debian 5.10.100-g304c70ad206a-dirty #82 SMP Thu Nov 16 13:13:14 CST 2023 aarch64 GNU/Linux
root@Debian:/usr/src# exit
exit
root@Debian:~#

@agners
Copy link
Member

agners commented Mar 1, 2024

I'd recommend install it on the host system and make sure it is working there.

Not sure if you have the kernel config around, but CONFIG_IP_SET should be enabled, e.g.:

zcat /proc/config.gz | grep IP_SET

Should show output.

@ygfcl
Copy link
Author

ygfcl commented Mar 1, 2024

I'd recommend install it on the host system and make sure it is working there.

Not sure if you have the kernel config around, but CONFIG_IP_SET should be enabled, e.g.:

zcat /proc/config.gz | grep IP_SET

Should show output.

no such file

root@Debian:~# docker start 0996bbd1b3fa && docker exec -it 0996bbd1b3fa /bin/bash
0996bbd1b3fa
root@Debian:/usr/src# zcat /proc/config.gz | grep IP_SET
gzip: /proc/config.gz: No such file or directory
root@Debian:/usr/src# exity
bash: exity: command not found
root@Debian:/usr/src# exit
exit
root@Debian:~# docker start 0996bbd1b3fa && docker exec -it 0996bbd1b3fa /bin/bash
root@Debian:~#
root@Debian:~# zcat /proc/config.gz | grep IP_SET
gzip: /proc/config.gz: No such file or directory

@agners
Copy link
Member

agners commented Mar 1, 2024

Yeah not sure where the kernel config of your kernel is exactly, maybe somewhere in /boot.

But in any case, your system runs on a non-standard Debian kernel. This is some kernel config problem or so. Officially we only support vanilla Debian installation exactly for this reason (see https://github.com/home-assistant/architecture/blob/master/adr/0014-home-assistant-supervised.md#supported-operating-system-system-dependencies-and-versions, no derivatives). 🤷‍♂️

Copy link

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

@github-actions github-actions bot added the stale label Mar 31, 2024
@github-actions github-actions bot closed this as not planned Won't fix, can't repro, duplicate, stale Apr 7, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

2 participants