home-assistant / addons

:heavy_plus_sign: Docker add-ons for Home Assistant
https://home-assistant.io/hassio/
Apache License 2.0
1.57k stars 1.52k forks source link

OTBR add-on do not want to properly start #3815

Open frabar55 opened 4 weeks ago

frabar55 commented 4 weeks ago

Describe the issue you are experiencing

Hello everybody, I'm triyng to install OTBR add on but I always receive the error documented in the attached file, i.e OTBR do not want to start!

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.11.1

Steps to reproduce the issue

1. 2. 3. ...

System Health information

System Information

version core-2024.10.4
installation_type Home Assistant Supervised
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.12.4
os_name Linux
os_version 6.1.0-26-amd64
arch x86_64
timezone Europe/Rome
config_dir /config
Home Assistant Community Store GitHub API | ok -- | -- GitHub Content | ok GitHub Web | ok HACS Data | ok GitHub API Calls Remaining | 5000 Installed Version | 2.0.1 Stage | running Available Repositories | 1453 Downloaded Repositories | 15
Home Assistant Cloud logged_in | true -- | -- subscription_expiration | 8 novembre 2024 alle ore 01:00 relayer_connected | true relayer_region | eu-central-1 remote_enabled | true remote_connected | true alexa_enabled | true google_enabled | false remote_server | eu-central-1-3.ui.nabu.casa certificate_status | ready instance_id | a83fc7a2262248c197e667b38dd28daf 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.10.3 agent_version | 1.6.0 docker_version | 27.3.1 disk_total | 250.9 GB disk_used | 89.7 GB healthy | true supported | true host_connectivity | true supervisor_connectivity | true ntp_synchronized | false virtualization | supervisor_api | ok version_api | ok installed_addons | Mosquitto broker (6.4.1), Get HACS (1.3.1), Advanced SSH & Web Terminal (19.0.0), Zigbee2MQTT (1.40.2-1), Samba share (12.3.2), Studio Code Server (5.17.2), Matter Server (6.6.0), OpenThread Border Router (2.11.1)
Dashboards dashboards | 2 -- | -- resources | 8 views | 10 mode | storage
Recorder oldest_recorder_run | 18 ottobre 2024 alle ore 12:49 -- | -- current_recorder_run | 28 ottobre 2024 alle ore 16:18 estimated_db_size | 966.70 MiB database_engine | sqlite database_version | 3.45.3

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

2024-10-28 16:18:48.900 INFO (MainThread) [supervisor.resolution.check] System checks complete
2024-10-28 16:18:48.900 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
2024-10-28 16:18:48.976 INFO (MainThread) [supervisor.jobs] 'OSManager.reload' blocked from execution, no Home Assistant OS available
2024-10-28 16:18:48.976 INFO (MainThread) [supervisor.host.services] Updating service information
2024-10-28 16:18:48.981 INFO (MainThread) [supervisor.host.network] Updating local network information
2024-10-28 16:18:49.086 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
2024-10-28 16:18:49.100 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
2024-10-28 16:18:49.100 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
2024-10-28 16:18:49.101 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
2024-10-28 16:18:49.106 INFO (MainThread) [supervisor.host.manager] Host information reload completed
2024-10-28 16:23:28.061 WARNING (MainThread) [supervisor.addons.options] Unknown option 'mqtt' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:23:28.061 WARNING (MainThread) [supervisor.addons.options] Unknown option 'homeassistant' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:23:28.061 WARNING (MainThread) [supervisor.addons.options] Unknown option 'permit_join' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:23:28.061 WARNING (MainThread) [supervisor.addons.options] Unknown option 'frontend' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:23:28.061 WARNING (MainThread) [supervisor.addons.options] Unknown option 'ota' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:23:28.061 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disable_automatic_update_check' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:23:28.061 WARNING (MainThread) [supervisor.addons.options] Unknown option 'advanced' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:25:49.819 INFO (MainThread) [supervisor.addons.addon] No core_openthread_border_router addon Docker image homeassistant/amd64-addon-otbr found
2024-10-28 16:25:49.819 INFO (MainThread) [supervisor.docker.interface] Downloading docker image homeassistant/amd64-addon-otbr with tag 2.11.1.
2024-10-28 16:25:58.962 INFO (MainThread) [supervisor.addons.addon] Creating Home Assistant add-on data folder /data/addons/data/core_openthread_border_router
2024-10-28 16:25:58.963 INFO (MainThread) [supervisor.docker.interface] Downloading docker image homeassistant/amd64-addon-otbr with tag 2.11.1.
2024-10-28 16:26:00.125 INFO (MainThread) [supervisor.addons.manager] Add-on 'core_openthread_border_router' successfully installed
2024-10-28 16:26:58.760 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:26:59.848 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:27:03.480 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:27:03.491 INFO (SyncWorker_3) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:27:03.988 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:27:05.171 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:27:47.084 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:27:47.089 INFO (SyncWorker_0) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:27:47.629 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:27:48.849 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:28:28.054 WARNING (MainThread) [supervisor.addons.options] Unknown option 'mqtt' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.055 WARNING (MainThread) [supervisor.addons.options] Unknown option 'homeassistant' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.055 WARNING (MainThread) [supervisor.addons.options] Unknown option 'permit_join' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.055 WARNING (MainThread) [supervisor.addons.options] Unknown option 'frontend' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.055 WARNING (MainThread) [supervisor.addons.options] Unknown option 'ota' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.055 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disable_automatic_update_check' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.055 WARNING (MainThread) [supervisor.addons.options] Unknown option 'advanced' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.078 WARNING (MainThread) [supervisor.addons.options] Unknown option 'mqtt' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.078 WARNING (MainThread) [supervisor.addons.options] Unknown option 'homeassistant' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.078 WARNING (MainThread) [supervisor.addons.options] Unknown option 'permit_join' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.078 WARNING (MainThread) [supervisor.addons.options] Unknown option 'frontend' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.078 WARNING (MainThread) [supervisor.addons.options] Unknown option 'ota' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.078 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disable_automatic_update_check' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:28.078 WARNING (MainThread) [supervisor.addons.options] Unknown option 'advanced' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:28:30.768 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:28:30.775 INFO (SyncWorker_3) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:28:31.315 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:28:32.392 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:29:14.345 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:29:14.349 INFO (SyncWorker_7) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:29:14.891 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:29:15.969 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:29:57.913 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:29:57.917 INFO (SyncWorker_1) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:29:58.439 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:29:59.636 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:30:41.593 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:30:41.597 INFO (SyncWorker_1) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:30:42.082 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:30:43.364 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:31:25.352 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:31:25.355 INFO (SyncWorker_3) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:31:25.873 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:31:26.998 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:32:08.956 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:32:08.968 INFO (SyncWorker_3) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:32:09.515 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:32:10.565 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:32:52.525 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:32:52.529 INFO (SyncWorker_0) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:32:53.076 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:32:54.153 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:33:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'mqtt' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:33:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'homeassistant' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:33:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'permit_join' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:33:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'frontend' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:33:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'ota' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:33:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disable_automatic_update_check' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:33:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'advanced' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:33:36.115 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon OpenThread Border Router is failed, restarting...
2024-10-28 16:33:36.119 INFO (SyncWorker_7) [supervisor.docker.manager] Cleaning addon_core_openthread_border_router application
2024-10-28 16:33:36.701 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-otbr with version 2.11.1
2024-10-28 16:33:37.836 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_openthread_border_router
2024-10-28 16:34:19.786 ERROR (MainThread) [asyncio] Task exception was never retrieved
future: <Task finished name='Task-2511' coro=<Addon.watchdog_container() done, defined at /usr/src/supervisor/supervisor/addons/addon.py:1468> exception=AddonsJobError('Rate limit exceeded, more than 10 calls in 0:30:00')>
Traceback (most recent call last):
  File "/usr/src/supervisor/supervisor/addons/addon.py", line 1482, in watchdog_container
    await self._restart_after_problem(event.state)
  File "/usr/src/supervisor/supervisor/jobs/decorator.py", line 291, in wrapper
    raise on_condition(
supervisor.exceptions.AddonsJobError: Rate limit exceeded, more than 10 calls in 0:30:00
2024-10-28 16:38:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'mqtt' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:38:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'homeassistant' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:38:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'permit_join' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:38:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'frontend' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:38:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'ota' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:38:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disable_automatic_update_check' for Zigbee2MQTT (45df7312_zigbee2mqtt)
2024-10-28 16:38:28.143 WARNING (MainThread) [supervisor.addons.options] Unknown option 'advanced' for Zigbee2MQTT (45df7312_zigbee2mqtt)

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

-----------------------------------------------------------
 Add-on: OpenThread Border Router
 OpenThread Border Router add-on
-----------------------------------------------------------
 Add-on version: 2.11.1
 You are running the latest version of this add-on.
 System: Debian GNU/Linux 12 (bookworm)  (amd64 / qemux86-64)
 Home Assistant Core: 2024.10.4
 Home Assistant Supervisor: 2024.10.3
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums or the Discord chat.
-----------------------------------------------------------
s6-rc: info: service banner successfully started
s6-rc: info: service universal-silabs-flasher: starting
[16:29:15] INFO: Flashing firmware is disabled
s6-rc: info: service universal-silabs-flasher successfully started
s6-rc: info: service otbr-agent: starting
[16:29:16] INFO: Setup OTBR firewall...
[16:29:16] INFO: Starting otbr-agent...
[NOTE]-AGENT---: Running 0.3.0-ff7227ea-dirty
[NOTE]-AGENT---: Thread version: 1.3.0
[NOTE]-AGENT---: Thread interface: wpan0
[NOTE]-AGENT---: Radio URL: spinel+hdlc+uart:///dev/ttyUSB0?uart-baudrate=460800&uart-flow-control
[NOTE]-AGENT---: Radio URL: trel://enp1s0
[NOTE]-ILS-----: Infra link selected: enp1s0
[INFO]-RCP_HOS-: OpenThread log level changed to 5
49d.17:14:52.002 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:0, cmd:RESET
49d.17:14:52.002 [D] P-SpinelDrive-: Waiting response: key=0
49d.17:14:54.005 [W] P-SpinelDrive-: Wait for response timeout
49d.17:14:54.005 [I] P-SpinelDrive-: co-processor self reset successfully
49d.17:14:54.005 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:PROTOCOL_VERSION
49d.17:14:54.005 [D] P-SpinelDrive-: Waiting response: key=1
49d.17:14:56.007 [W] P-SpinelDrive-: Wait for response timeout
49d.17:14:56.007 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:PROTOCOL_VERSION
49d.17:14:56.007 [D] P-SpinelDrive-: Waiting response: key=1
49d.17:14:58.009 [W] P-SpinelDrive-: Wait for response timeout
49d.17:14:58.009 [C] Platform------: Init() at spinel_driver.cpp:82: Failure
49d.17:14:58.009 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:PROTOCOL_VERSION
49d.17:14:58.009 [D] P-SpinelDrive-: Waiting response: key=1
49d.17:15:00.011 [W] P-SpinelDrive-: Wait for response timeout
[16:29:54] WARNING: otbr-agent exited with code 1 (by signal 0).
Chain OTBR_FORWARD_INGRESS (0 references)
target     prot opt source               destination         
DROP       all  --  anywhere             anywhere             PKTTYPE = unicast
DROP       all  --  anywhere             anywhere             match-set otbr-ingress-deny-src src
ACCEPT     all  --  anywhere             anywhere             match-set otbr-ingress-allow-dst dst
DROP       all  --  anywhere             anywhere             PKTTYPE = unicast
ACCEPT     all  --  anywhere             anywhere            
otbr-ingress-deny-src
otbr-ingress-deny-src-swap
otbr-ingress-allow-dst
otbr-ingress-allow-dst-swap
Chain OTBR_FORWARD_EGRESS (0 references)
target     prot opt source               destination         
ACCEPT     all  --  anywhere             anywhere            
[16:29:54] INFO: OTBR firewall teardown completed.
s6-svlisten1: fatal: /run/s6-rc/servicedirs/otbr-agent failed permanently or its supervisor died
s6-rc: warning: unable to start service otbr-agent: command exited 1
s6-rc: info: service legacy-cont-init: stopping
s6-rc: info: service universal-silabs-flasher: stopping
s6-rc: info: service mdns: stopping
/run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information.
/run/s6/basedir/scripts/rc.init: fatal: stopping the container.
s6-rc: info: service universal-silabs-flasher successfully stopped
s6-rc: info: service banner: stopping
Default: mDNSResponder (Engineering Build) (Oct  7 2024 16:49:24) 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
[16:29:54] INFO: mDNS ended with exit code 4 (signal 0)...
s6-rc: info: service mdns successfully stopped

Additional information

No response