texecom2mqtt-hassio
texecom2mqtt-hassio copied to clipboard
Published
20 hours ago •
dchesterton
dchesterton
Home assistant add-in crashed
Describe the bug On version 1.3.1 of the Home Assistant add-in. This suddenly crashed.
Application version 1.3.1
Texecom alarm type Premier Elite 24
Home Assistant version 2025.2.2
Debug log
2025-02-27 16:03:46 - DEBUG: Updating system power...
2025-02-27 16:03:46 - DEBUG: Queuing GetSystemPower command (sequence: 123)
2025-02-27 16:03:46 - DEBUG: Executing GetSystemPower command (sequence: 123)
2025-02-27 16:03:47 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":81,"battery_voltage":13.56,"panel_current":513,"panel_voltage":13.63}' (retain: true)
2025-02-27 16:04:16 - DEBUG: Updating system power...
2025-02-27 16:04:16 - DEBUG: Queuing GetSystemPower command (sequence: 124)
2025-02-27 16:04:16 - DEBUG: Executing GetSystemPower command (sequence: 124)
2025-02-27 16:04:17 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":531,"panel_voltage":13.56}' (retain: true)
2025-02-27 16:04:46 - DEBUG: Updating system power...
2025-02-27 16:04:46 - DEBUG: Queuing GetSystemPower command (sequence: 125)
2025-02-27 16:04:46 - DEBUG: Executing GetSystemPower command (sequence: 125)
2025-02-27 16:04:47 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":81,"battery_voltage":13.56,"panel_current":504,"panel_voltage":13.63}' (retain: true)
2025-02-27 16:04:59 - INFO: Caught interrupt signal, exiting gracefully...
2025-02-27 16:04:59 - DEBUG: Queuing SetEventMessages command (sequence: 126)
2025-02-27 16:04:59 - DEBUG: Executing SetEventMessages command (sequence: 126)
2025-02-27 16:04:59 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true)
2025-02-27 16:04:59 - DEBUG: Disconnecting from panel
2025-02-27 16:04:59 - INFO: Closing connection to panel...
2025-02-27 16:05:01 - INFO: Closed connection to panel
2025-02-27 16:05:01 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true)
2025-02-27 16:05:01 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true)
2025-02-27 16:05:01 - INFO: Closing connection to MQTT broker...
2025-02-27 16:05:01 - INFO: Closed connection to MQTT broker
Mine has also crashed twice in the last week, I've enabled debug logs and will post back if it happens again.
Here's the info only logs for now.
'''
2025-03-03 16:52:08 - INFO: Caught interrupt signal, exiting gracefully... 2025-03-03 16:52:08 - INFO: Closing connection to panel... 2025-03-03 16:52:10 - INFO: Closed connection to panel 2025-03-03 16:52:10 - INFO: Closing connection to MQTT broker... 2025-03-03 16:52:10 - ERROR: MQTT broker error: write after end 2025-03-03 16:52:10 - INFO: Closed connection to MQTT broker '''
Just had another crash:
2025-03-03 17:58:07 - DEBUG: Updating system power...
2025-03-03 17:58:07 - DEBUG: Queuing GetSystemPower command (sequence: 93)
2025-03-03 17:58:07 - DEBUG: Updating system power...
2025-03-03 17:58:07 - DEBUG: Queuing GetSystemPower command (sequence: 94)
2025-03-03 17:58:07 - DEBUG: Executing GetSystemPower command (sequence: 93)
2025-03-03 17:58:07 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":81,"battery_voltage":13.56,"panel_current":504,"panel_voltage":13.56}' (retain: true)
2025-03-03 17:58:07 - DEBUG: Executing GetSystemPower command (sequence: 94)
2025-03-03 17:58:07 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":504,"panel_voltage":13.63}' (retain: true)
2025-03-03 17:58:12 - INFO: Caught interrupt signal, exiting gracefully...
2025-03-03 17:58:12 - DEBUG: Queuing SetEventMessages command (sequence: 95)
2025-03-03 17:58:12 - DEBUG: Executing SetEventMessages command (sequence: 95)
2025-03-03 17:58:12 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true)
2025-03-03 17:58:12 - DEBUG: Disconnecting from panel
2025-03-03 17:58:12 - INFO: Closing connection to panel...
2025-03-03 17:58:14 - INFO: Closed connection to panel
2025-03-03 17:58:14 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true)
2025-03-03 17:58:14 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true)
2025-03-03 17:58:14 - INFO: Closing connection to MQTT broker...
2025-03-03 17:58:14 - INFO: Closed connection to MQTT broker
In all of those examples the "caught interrupt signal" line means that something is closing the container, it's not crashing.
How are you running the container? Via HA or standalone?
Me too. Been stable until 1.31 (1.30 wasn't out long enough to find out). Running in HA as an addon (on a Home Assistant Yellow)
025-03-03 18:44:25 - INFO: Kitchen status changed to Secure 2025-03-03 18:44:25 - DEBUG: Publishing to texecom2mqtt/zone/kitchen: '{"name":"Kitchen","number":5,"areas":["A"],"status":0,"type":"Guard Access"}' (retain: true) 2025-03-03 18:44:28 - DEBUG: Command GetSystemPower timed out (attempt 1, sequence: 99). 2025-03-03 18:44:28 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":9,"battery_voltage":13.42,"panel_current":567,"panel_voltage":13.49}' (retain: true) 2025-03-03 18:44:36 - DEBUG: Updating system power... 2025-03-03 18:44:36 - DEBUG: Queuing GetSystemPower command (sequence: 100) 2025-03-03 18:44:36 - DEBUG: Executing GetSystemPower command (sequence: 100) 2025-03-03 18:44:36 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":9,"battery_voltage":13.42,"panel_current":576,"panel_voltage":13.49}' (retain: true) 2025-03-03 18:44:45 - INFO: Caught interrupt signal, exiting gracefully... 2025-03-03 18:44:45 - DEBUG: Queuing SetEventMessages command (sequence: 101) 2025-03-03 18:44:45 - DEBUG: Executing SetEventMessages command (sequence: 101) 2025-03-03 18:44:45 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true) 2025-03-03 18:44:45 - DEBUG: Disconnecting from panel 2025-03-03 18:44:45 - INFO: Closing connection to panel... 2025-03-03 18:44:47 - INFO: Closed connection to panel 2025-03-03 18:44:47 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true) 2025-03-03 18:44:47 - DEBUG: Publishing to texecom2mqtt/status: 'offline' (retain: true) 2025-03-03 18:44:47 - INFO: Closing connection to MQTT broker... 2025-03-03 18:44:47 - INFO: Closed connection to MQTT broker 2025-03-04 18:40:32 - INFO: Starting texecom2mqtt v1.3.1 (Node v23.7.0)... 2025-03-04 18:40:33 - INFO: Connected to MQTT broker: core-mosquitto:1883 (retain: true, clean: true, client_id: texecom2mqtt, qos: 0) 2025-03-04 18:40:33 - INFO: Connecting to panel... 2025-03-04 18:40:33 - DEBUG: Publishing to texecom2mqtt/status: 'online' (retain: true) 2025-03-04 18:40:33 - INFO: Connected to alarm, sleeping for 2 seconds... 2025-03-04 18:40:35 - INFO: Fetching serial number 2025-03-04 18:40:35 - DEBUG: Executing serial number command 2025-03-04 18:40:35 - INFO: Logging in to panel 2025-03-04 18:40:35 - DEBUG: Queuing Login command (sequence: 0) 2025-03-04 18:40:35 - DEBUG: Executing Login command (sequence: 0) 2025-03-04 18:40:35 - INFO: Successfully logged in to panel
@dchesterton I've managed to dig into this a bit more. In all three examples (the third being just now) the root cause is the HA Supervisor having an update - it downloads the update which looks like it stops and starts the docker container. At that point the add-in ends up in a stopped state.
Here are the supervisor logs:
[32m2025-03-06 13:43:38.403 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json[0m
[32m2025-03-06 13:43:38.501 INFO (MainThread) [supervisor.misc.tasks] Found new Supervisor version 2025.03.2, updating[0m
[32m2025-03-06 13:43:38.502 INFO (MainThread) [supervisor.supervisor] Fetching AppArmor profile https://version.home-assistant.io/apparmor_stable.txt[0m
[32m2025-03-06 13:43:38.546 INFO (MainThread) [supervisor.host.apparmor] Adding/updating AppArmor profile: hassio-supervisor[0m
[32m2025-03-06 13:43:38.881 INFO (MainThread) [supervisor.supervisor] Update Supervisor to version 2025.03.2[0m
[32m2025-03-06 13:43:38.883 INFO (MainThread) [supervisor.docker.interface] Downloading docker image ghcr.io/home-assistant/aarch64-hassio-supervisor with tag 2025.03.2.[0m
[32m2025-03-06 13:44:08.711 INFO (MainThread) [supervisor.misc.scheduler] Shutting down scheduled tasks[0m
[32m2025-03-06 13:44:08.715 INFO (MainThread) [supervisor.docker.monitor] Stopped docker events monitor[0m
[32m2025-03-06 13:44:08.720 INFO (MainThread) [supervisor.api] Stopping API on 172.30.32.2[0m
[32m2025-03-06 13:44:08.730 INFO (MainThread) [supervisor.hardware.monitor] Stopped Supervisor hardware monitor[0m
[32m2025-03-06 13:44:08.745 INFO (MainThread) [supervisor.dbus.manager] Closed conection to system D-Bus.[0m
[33m2025-03-06 13:44:08.757 WARNING (MainThread) [supervisor.homeassistant.websocket] Connection is closed[0m
[32m2025-03-06 13:44:08.760 INFO (MainThread) [supervisor.core] Supervisor is down - 0[0m
[32m2025-03-06 13:44:08.764 INFO (MainThread) [__main__] Closing Supervisor[0m
[13:44:08] WARNING: [33mMaybe found an issue on shutdown[0m
[13:44:09] WARNING: [33mHalt Supervisor (Wuff)[0m
[13:44:09] INFO: [32mWatchdog restart after closing[0m
s6-rc: info: service legacy-services: stopping
[13:44:09] INFO: [32mSupervisor restart after closing[0m
s6-rc: info: service legacy-services successfully stopped
s6-rc: info: service legacy-cont-init: stopping
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
s6-rc: info: service s6rc-oneshot-runner: starting
s6-rc: info: service s6rc-oneshot-runner successfully started
s6-rc: info: service fix-attrs: starting
s6-rc: info: service fix-attrs successfully started
s6-rc: info: service legacy-cont-init: starting
cont-init: info: running /etc/cont-init.d/udev.sh
[13:44:21] INFO: [32mUsing udev information from host[0m
cont-init: info: /etc/cont-init.d/udev.sh exited 0
s6-rc: info: service legacy-cont-init successfully started
s6-rc: info: service legacy-services: starting
services-up: info: copying legacy longrun supervisor (no readiness notification)
services-up: info: copying legacy longrun watchdog (no readiness notification)
[13:44:21] INFO: [32mStarting local supervisor watchdog...[0m
s6-rc: info: service legacy-services successfully started
[32m2025-03-06 13:44:24.388 INFO (MainThread) [__main__] Initializing Supervisor setup[0m
[32m2025-03-06 13:44:24.607 INFO (MainThread) [supervisor.coresys] Setting up coresys for machine: yellow[0m
[32m2025-03-06 13:44:24.621 INFO (MainThread) [supervisor.docker.supervisor] Attaching to Supervisor ghcr.io/home-assistant/aarch64-hassio-supervisor with version 2025.03.2[0m
[32m2025-03-06 13:44:24.621 INFO (MainThread) [supervisor.docker.supervisor] Connecting Supervisor to hassio-network[0m
[32m2025-03-06 13:44:24.917 INFO (SyncWorker_0) [supervisor.docker.manager] Cleanup images: ['homeassistant/aarch64-hassio-supervisor:2025.03.0', 'ghcr.io/home-assistant/aarch64-hassio-supervisor:2025.03.0'][0m
[32m2025-03-06 13:44:25.625 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state initialize[0m
[32m2025-03-06 13:44:25.630 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete[0m
[32m2025-03-06 13:44:25.632 INFO (MainThread) [__main__] Setting up Supervisor[0m
[32m2025-03-06 13:44:25.784 INFO (MainThread) [supervisor.api] Starting API on 172.30.32.2[0m
[32m2025-03-06 13:44:25.980 INFO (MainThread) [supervisor.hardware.monitor] Started Supervisor hardware monitor[0m
[32m2025-03-06 13:44:25.983 INFO (MainThread) [supervisor.dbus.manager] Connected to system D-Bus.[0m
[32m2025-03-06 13:44:25.984 INFO (MainThread) [supervisor.dbus.agent] Load dbus interface io.hass.os[0m
[32m2025-03-06 13:44:25.986 INFO (MainThread) [supervisor.dbus.hostname] Load dbus interface org.freedesktop.hostname1[0m
[32m2025-03-06 13:44:25.987 INFO (MainThread) [supervisor.dbus.logind] Load dbus interface org.freedesktop.login1[0m
[32m2025-03-06 13:44:25.988 INFO (MainThread) [supervisor.dbus.network] Load dbus interface org.freedesktop.NetworkManager[0m
[32m2025-03-06 13:44:25.991 INFO (MainThread) [supervisor.dbus.rauc] Load dbus interface de.pengutronix.rauc[0m
[32m2025-03-06 13:44:25.992 INFO (MainThread) [supervisor.dbus.resolved] Load dbus interface org.freedesktop.resolve1[0m
[32m2025-03-06 13:44:26.993 INFO (MainThread) [supervisor.dbus.systemd] Load dbus interface org.freedesktop.systemd1[0m
[32m2025-03-06 13:44:26.994 INFO (MainThread) [supervisor.dbus.timedate] Load dbus interface org.freedesktop.timedate1[0m
[32m2025-03-06 13:44:26.839 INFO (MainThread) [supervisor.host.services] Updating service information[0m
[32m2025-03-06 13:44:26.854 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information[0m
[32m2025-03-06 13:44:27.301 INFO (MainThread) [supervisor.host.network] Updating local network information[0m
[32m2025-03-06 13:44:27.489 INFO (MainThread) [supervisor.host.apparmor] Loading AppArmor Profiles: {'hassio-supervisor'}[0m
[32m2025-03-06 13:44:27.545 INFO (MainThread) [supervisor.docker.monitor] Started docker events monitor[0m
[32m2025-03-06 13:44:27.546 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json[0m
[32m2025-03-06 13:44:27.643 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-cli with version 2025.02.0[0m
[32m2025-03-06 13:44:27.718 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-dns with version 2025.02.0[0m
[32m2025-03-06 13:44:27.765 INFO (MainThread) [supervisor.plugins.dns] Updated /etc/resolv.conf[0m
[32m2025-03-06 13:44:27.783 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-audio with version 2023.12.0[0m
[32m2025-03-06 13:44:27.834 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-observer with version 2023.06.0[0m
[32m2025-03-06 13:44:27.885 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-multicast with version 2024.03.0[0m
[32m2025-03-06 13:44:27.919 INFO (MainThread) [supervisor.homeassistant.secrets] Loaded 2 Home Assistant secrets[0m
[32m2025-03-06 13:44:27.929 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/yellow-homeassistant with version 2025.2.5[0m
[32m2025-03-06 13:44:28.018 INFO (MainThread) [supervisor.os.manager] Detect Home Assistant Operating System 14.2 / BootSlot A[0m
[32m2025-03-06 13:44:28.020 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/core repository[0m
[32m2025-03-06 13:44:28.021 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/9074a9fa repository[0m
[32m2025-03-06 13:44:28.023 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/5c53de3b repository[0m
[32m2025-03-06 13:44:28.025 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/45df7312 repository[0m
[32m2025-03-06 13:44:28.027 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/3d360630 repository[0m
[32m2025-03-06 13:44:28.028 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/db21ed7f repository[0m
[32m2025-03-06 13:44:28.033 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/c15a2434 repository[0m
[32m2025-03-06 13:44:28.038 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/cebe7a76 repository[0m
[32m2025-03-06 13:44:28.041 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/d5369777 repository[0m
[32m2025-03-06 13:44:28.042 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a1b84aa7 repository[0m
[32m2025-03-06 13:44:28.043 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a0d7b954 repository[0m
[32m2025-03-06 13:44:30.613 INFO (MainThread) [supervisor.store] Loading add-ons from store: 192 all - 192 new - 0 remove[0m
[32m2025-03-06 13:44:30.973 INFO (MainThread) [supervisor.addons.manager] Found 12 installed add-ons[0m
[32m2025-03-06 13:44:31.172 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/nut/aarch64 with version 0.14.1[0m
[32m2025-03-06 13:44:31.183 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/log-viewer/aarch64 with version 0.17.1[0m
[32m2025-03-06 13:44:31.186 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/sabeechen/hassio-google-drive-backup-aarch64 with version 0.112.1[0m
[32m2025-03-06 13:44:31.197 INFO (MainThread) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-configurator with version 5.8.0[0m
[32m2025-03-06 13:44:31.241 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/esphome/esphome-hassio with version 2025.2.1[0m
[32m2025-03-06 13:44:31.242 INFO (MainThread) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-ssh with version 9.16.0[0m
[32m2025-03-06 13:44:31.244 INFO (MainThread) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-otbr with version 2.13.0[0m
[32m2025-03-06 13:44:31.252 INFO (MainThread) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-mosquitto with version 6.5.0[0m
[32m2025-03-06 13:44:31.255 INFO (MainThread) [supervisor.docker.interface] Attaching to dchesterton/texecom2mqtt with version 1.3.1[0m
[32m2025-03-06 13:44:31.302 INFO (MainThread) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/ssh/aarch64 with version 20.0.2[0m
[32m2025-03-06 13:44:31.316 INFO (MainThread) [supervisor.docker.interface] Attaching to local/aarch64-addon-bwalink with version 2024.8.0[0m
[32m2025-03-06 13:44:31.318 INFO (MainThread) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-matter-server with version 7.0.0[0m
[32m2025-03-06 13:44:31.496 INFO (SyncWorker_4) [supervisor.docker.manager] Stopping addon_c15a2434_texecom2mqtt application[0m
[32m2025-03-06 13:44:34.531 INFO (SyncWorker_4) [supervisor.docker.manager] Cleaning addon_c15a2434_texecom2mqtt application[0m
[32m2025-03-06 13:44:34.570 INFO (SyncWorker_3) [supervisor.docker.manager] Removing image dchesterton/texecom2mqtt with latest[0m
[32m2025-03-06 13:44:34.579 INFO (SyncWorker_3) [supervisor.docker.manager] Removing image dchesterton/texecom2mqtt with 1.3.1[0m
[32m2025-03-06 13:44:34.956 INFO (MainThread) [supervisor.docker.interface] Downloading docker image dchesterton/texecom2mqtt with tag 1.3.1.[0m
[32m2025-03-06 13:44:55.958 INFO (MainThread) [supervisor.backups.manager] Found 4 backup files[0m
[32m2025-03-06 13:44:56.997 INFO (MainThread) [supervisor.discovery] Loaded 4 messages[0m
[32m2025-03-06 13:44:56.998 INFO (MainThread) [supervisor.ingress] Loaded 0 ingress sessions[0m
[32m2025-03-06 13:44:56.998 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state setup[0m
[32m2025-03-06 13:44:56.998 INFO (MainThread) [supervisor.resolution.checks.base] Run check for detached_addon_removed/addon[0m
[32m2025-03-06 13:44:56.998 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system[0m
[32m2025-03-06 13:44:56.999 INFO (MainThread) [supervisor.resolution.checks.base] Run check for detached_addon_missing/addon[0m
[32m2025-03-06 13:44:56.999 INFO (MainThread) [supervisor.resolution.checks.base] Run check for disabled_data_disk/system[0m
[32m2025-03-06 13:44:56.999 INFO (MainThread) [supervisor.resolution.check] System checks complete[0m
[32m2025-03-06 13:44:56.999 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state setup[0m
[32m2025-03-06 13:44:56.000 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete[0m
[32m2025-03-06 13:44:56.000 INFO (MainThread) [supervisor.jobs] 'ResolutionFixup.run_autofix' blocked from execution, system is not running - setup[0m
[32m2025-03-06 13:44:56.001 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state setup[0m
[32m2025-03-06 13:44:56.001 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete[0m
[32m2025-03-06 13:44:56.002 INFO (MainThread) [__main__] Running Supervisor[0m
[32m2025-03-06 13:44:56.077 INFO (MainThread) [supervisor.os.manager] Rauc: slot A - activated slot kernel.0, marked slot kernel.0 as good[0m
[32m2025-03-06 13:44:56.109 INFO (MainThread) [supervisor.addons.manager] Phase 'initialize' starting 0 add-ons[0m
[32m2025-03-06 13:44:56.109 INFO (MainThread) [supervisor.core] Detected Supervisor restart[0m
[32m2025-03-06 13:44:56.110 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled[0m
[32m2025-03-06 13:44:56.111 INFO (MainThread) [supervisor.host.info] Updating local host information[0m
[32m2025-03-06 13:44:56.113 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running[0m
[32m2025-03-06 13:44:56.114 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core[0m
[32m2025-03-06 13:44:56.115 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system[0m
[32m2025-03-06 13:44:56.115 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor[0m
[32m2025-03-06 13:44:56.127 INFO (MainThread) [supervisor.core] Supervisor is up and running[0m
[32m2025-03-06 13:44:56.141 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server[0m
[32m2025-03-06 13:44:56.155 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token[0m
[32m2025-03-06 13:44:56.186 INFO (MainThread) [supervisor.resolution.checks.base] Run check for no_current_backup/system[0m
[32m2025-03-06 13:44:56.186 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system[0m
[32m2025-03-06 13:44:56.187 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system[0m
[32m2025-03-06 13:44:56.187 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server[0m
[32m2025-03-06 13:44:56.201 INFO (MainThread) [supervisor.host.services] Updating service information[0m
[32m2025-03-06 13:44:56.234 INFO (MainThread) [supervisor.host.network] Updating local network information[0m
[32m2025-03-06 13:44:56.243 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system[0m
[32m2025-03-06 13:44:56.256 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon[0m
[33m2025-03-06 13:44:56.464 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disarm' for texecom2mqtt (c15a2434_texecom2mqtt)[0m
[32m2025-03-06 13:44:56.479 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m2025-03-06 13:44:56.499 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[32m2025-03-06 13:44:56.527 INFO (MainThread) [supervisor.resolution.checks.base] Run check for disabled_data_disk/system[0m
[32m2025-03-06 13:44:56.527 INFO (MainThread) [supervisor.resolution.check] System checks complete[0m
[32m2025-03-06 13:44:56.527 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running[0m
[32m2025-03-06 13:44:56.785 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information[0m
[32m2025-03-06 13:44:56.864 INFO (MainThread) [supervisor.host.manager] Host information reload completed[0m
[32m2025-03-06 13:44:57.189 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete[0m
[32m2025-03-06 13:44:57.189 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running[0m
[32m2025-03-06 13:44:57.190 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete[0m
[32m2025-03-06 13:45:06.359 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup[0m
[32m2025-03-06 13:45:06.383 INFO (MainThread) [supervisor.api.middleware.security] /backups access from cebe7a76_hassio_google_drive_backup[0m
[33m2025-03-06 13:46:33.805 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disarm' for texecom2mqtt (c15a2434_texecom2mqtt)[0m
[33m2025-03-06 13:51:33.807 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disarm' for texecom2mqtt (c15a2434_texecom2mqtt)[0m
[33m2025-03-06 13:51:48.495 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disarm' for texecom2mqtt (c15a2434_texecom2mqtt)[0m
[33m2025-03-06 13:56:33.823 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disarm' for texecom2mqtt (c15a2434_texecom2mqtt)[0m
[33m2025-03-06 13:58:27.902 WARNING (MainThread) [supervisor.addons.options] Unknown option 'disarm' for texecom2mqtt (c15a2434_texecom2mqtt)[0m
And here are the add-in logs:
2025-03-06 13:42:50 - DEBUG: Updating system power...
2025-03-06 13:42:50 - DEBUG: Queuing GetSystemPower command (sequence: 1)
2025-03-06 13:42:50 - DEBUG: Updating system power...
2025-03-06 13:42:50 - DEBUG: Queuing GetSystemPower command (sequence: 2)
2025-03-06 13:42:50 - DEBUG: Executing GetSystemPower command (sequence: 1)
2025-03-06 13:42:50 - DEBUG: Updating system power...
2025-03-06 13:42:50 - DEBUG: Queuing GetSystemPower command (sequence: 3)
2025-03-06 13:42:50 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":81,"battery_voltage":13.56,"panel_current":504,"panel_voltage":13.63}' (retain: true)
2025-03-06 13:42:50 - DEBUG: Executing GetSystemPower command (sequence: 2)
2025-03-06 13:42:50 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":513,"panel_voltage":13.56}' (retain: true)
2025-03-06 13:42:50 - DEBUG: Executing GetSystemPower command (sequence: 3)
2025-03-06 13:42:51 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":522,"panel_voltage":13.63}' (retain: true)
2025-03-06 13:43:20 - DEBUG: Updating system power...
2025-03-06 13:43:20 - DEBUG: Queuing GetSystemPower command (sequence: 4)
2025-03-06 13:43:20 - DEBUG: Updating system power...
2025-03-06 13:43:20 - DEBUG: Queuing GetSystemPower command (sequence: 5)
2025-03-06 13:43:20 - DEBUG: Updating system power...
2025-03-06 13:43:20 - DEBUG: Queuing GetSystemPower command (sequence: 6)
2025-03-06 13:43:20 - DEBUG: Executing GetSystemPower command (sequence: 4)
2025-03-06 13:43:20 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":504,"panel_voltage":13.56}' (retain: true)
2025-03-06 13:43:20 - DEBUG: Executing GetSystemPower command (sequence: 5)
2025-03-06 13:43:20 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":513,"panel_voltage":13.63}' (retain: true)
2025-03-06 13:43:20 - DEBUG: Executing GetSystemPower command (sequence: 6)
2025-03-06 13:43:21 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":513,"panel_voltage":13.56}' (retain: true)
2025-03-06 13:43:50 - DEBUG: Updating system power...
2025-03-06 13:43:50 - DEBUG: Queuing GetSystemPower command (sequence: 7)
2025-03-06 13:43:50 - DEBUG: Updating system power...
2025-03-06 13:43:50 - DEBUG: Queuing GetSystemPower command (sequence: 8)
2025-03-06 13:43:50 - DEBUG: Updating system power...
2025-03-06 13:43:50 - DEBUG: Queuing GetSystemPower command (sequence: 9)
2025-03-06 13:43:50 - DEBUG: Executing GetSystemPower command (sequence: 7)
2025-03-06 13:43:50 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":81,"battery_voltage":13.56,"panel_current":513,"panel_voltage":13.63}' (retain: true)
2025-03-06 13:43:50 - DEBUG: Executing GetSystemPower command (sequence: 8)
2025-03-06 13:43:50 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":531,"panel_voltage":13.56}' (retain: true)
2025-03-06 13:43:50 - DEBUG: Executing GetSystemPower command (sequence: 9)
2025-03-06 13:43:51 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":81,"battery_voltage":13.56,"panel_current":495,"panel_voltage":13.63}' (retain: true)
2025-03-06 13:44:20 - DEBUG: Updating system power...
2025-03-06 13:44:20 - DEBUG: Queuing GetSystemPower command (sequence: 10)
2025-03-06 13:44:20 - DEBUG: Updating system power...
2025-03-06 13:44:20 - DEBUG: Queuing GetSystemPower command (sequence: 11)
2025-03-06 13:44:20 - DEBUG: Updating system power...
2025-03-06 13:44:20 - DEBUG: Queuing GetSystemPower command (sequence: 12)
2025-03-06 13:44:20 - DEBUG: Executing GetSystemPower command (sequence: 10)
2025-03-06 13:44:20 - DEBUG: Publishing to texecom2mqtt/power: '{"battery_charging_current":72,"battery_voltage":13.56,"panel_current":522,"panel_voltage":13.56}' (retain: true)
I'm assuming the "Unknown option 'disarm'" log is a red herring as it seems to log that even when everything is working.
So my logs from 27/2 correspond to supervisor updating to 25.02.4, the ones 3/3 supervisor updated to 2025.03.0, and above logs supervisor updated to 2025.03.2
Looking at the timestamps of both logs, it looks like the docker container is running, but the add-in is in a stopped state - as the supervisor logs show entries much later than the add-in logs (which are logging every 30 seconds).
Happened again to my system, Sunday evening 22:38. Supervisor update but Texecom2mqtt did not restart. I've reverted to 1.2.3 until this issue is hopefully fixed. I rely a lot on texecom2mqtt for node-red flows and some automations in HA.