Home Assistant - Restarts and connection problems #2034

Sydney1101 · 2022-11-02T11:23:25Z

Sydney1101
Nov 2, 2022

Describe the issue you are experiencing

I use RaspberryMatic as an addon in Home Assistant. At the moment I can't perform any operation via the interface, because I have connection problems all the time. It also seems that RaspberryMatic is constantly restarting. As soon as I select something in the Rasp web interface, Bad Gateway appears and the addon restarts. In between I get connection problems to the interface etc. displayed.

Describe the behavior you expected

No restarts from the addon and constant connections.

Steps to reproduce the issue

Installation changed to HACS (Homematic(IP) Local) and the trouble starts with the latest update. Before that, I had the problems sporadically.

What is the version this bug report is based on?

3.65.11-20221005

Which base platform are you running?

rpi4 (RaspberryPi4)

Which HomeMatic/homematicIP radio module are you using?

HmIP-RFUSB

Anything in the logs that might be useful for us?

CORE:

reconnect: re-connected client RaspberryMatic-HmIP-RF
11:53:59 – (WARNUNG) runner.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:00:35 aufgetreten und erscheint 6217 mal
ping failed: NoConnection [(ConnectionRefusedError(111, 'Connection refused'),)]
11:53:59 – (FEHLER) /usr/local/lib/python3.10/site-packages/hahomematic/client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 00:59:28 aufgetreten und erscheint 6904 mal
mark_all_devices_availability: marked all devices unavailable for RaspberryMatic-BidCos-RF
11:53:42 – (WARNUNG) /usr/local/lib/python3.10/site-packages/hahomematic/client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:00:00 aufgetreten und erscheint 1246 mal
(111, 'Connection refused')
11:53:21 – (FEHLER) /usr/local/lib/python3.10/site-packages/hahomematic/xml_rpc_proxy.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 00:59:28 aufgetreten und erscheint 159699 mal
proxy_init failed: AuthFailure [(<ProtocolError for de838cd8-raspberrymatic:2001/RPC2: 401 Unauthorized>,)] Unable to initialize proxy for RaspberryMatic-BidCos-RF
11:53:06 – (FEHLER) /usr/local/lib/python3.10/site-packages/hahomematic/client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 00:59:27 aufgetreten und erscheint 31 mal
_post_script failed: Error while logging in via JSON-RPC.
11:49:57 – (WARNUNG) /usr/local/lib/python3.10/site-packages/hahomematic/json_rpc_client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:26:04 aufgetreten und erscheint 236 mal
_do_login failed: Unable to open session: Cannot connect to host de838cd8-raspberrymatic:80 ssl:default [Connect call failed ('172.30.33.2', 80)]
11:49:57 – (WARNUNG) /usr/local/lib/python3.10/site-packages/hahomematic/json_rpc_client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:26:04 aufgetreten und erscheint 236 mal
_do_post failed: ClientConnectorError
11:49:57 – (FEHLER) /usr/local/lib/python3.10/site-packages/hahomematic/json_rpc_client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 00:59:28 aufgetreten und erscheint 449 mal
get_all_device_data failed: HaHomematicException [('_post_script: error: 500',)]
11:42:52 – (WARNUNG) runner.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:09:24 aufgetreten und erscheint 634 mal
get_all_device_data failed: HaHomematicException [("_post_script: error: Cannot connect to host de838cd8-raspberrymatic:80 ssl:default [Connect call failed ('172.30.33.2', 80)]",)]
11:25:50 – (WARNUNG) /usr/local/lib/python3.10/site-packages/hahomematic/json_rpc_client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 00:59:28 aufgetreten und erscheint 49 mal
proxy_de_init failed: NoConnection [(ConnectionRefusedError(111, 'Connection refused'),)] Unable to de-initialize proxy for RaspberryMatic-HmIP-RF
11:22:36 – (FEHLER) /usr/local/lib/python3.10/site-packages/hahomematic/client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:47:11 aufgetreten und erscheint 158 mal
Update of update.terminal_ssh_update is taking over 10 seconds
10:17:17 – (WARNUNG) helpers/entity.py
Could not fetch stats for de838cd8_raspberrymatic: Can't read stats from addon_de838cd8_raspberrymatic: Expecting value: line 1 column 1 (char 0)
08:25:56 – (WARNUNG) Home Assistant Supervisor - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 07:11:22 aufgetreten und erscheint 7 mal
You need to call .set_last_commit to set this property
03:06:48 – (WARNUNG) HACS (benutzerdefinierte Integration)
_set_value failed with NoConnection [(ConnectionRefusedError(111, 'Connection refused'),)]: 00085D899C1D78:4, STATE, False
1. November 2022 um 21:37:32 – (WARNUNG) /usr/local/lib/python3.10/site-packages/hahomematic/client.py - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:16:07 aufgetreten und erscheint 18 mal
Error fetching ipp data: Invalid response from API: Timeout occurred while connecting to IPP server.
31. Oktober 2022 um 20:15:56 – (FEHLER) Internet Printing Protocol (IPP) - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 01:01:31 aufgetreten und erscheint 2 mal
Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x7f896dd300>: Failed to establish a new connection: [Errno -3] Try again')': /api/v3/accounts/124352/homescreen
31. Oktober 2022 um 20:03:32 – (WARNUNG) components/blink/binary_sensor.py
_do_post failed: Status: 500
31. Oktober 2022 um 00:59:26 – (WARNUNG) Homematic(IP) Local (benutzerdefinierte Integration) - Die Nachricht ist zum ersten Mal um 31. Oktober 2022 um 00:59:25 aufgetreten und erscheint 3 mal


Supervisor:

22-11-02 12:07:29 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/eq3loop - None
22-11-02 12:07:29 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:0 rwm' for device /dev/eq3loop to addon_de838cd8_raspberrymatic
22-11-02 12:07:32 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_hmip - None
22-11-02 12:07:32 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_bidcos - None
22-11-02 12:07:32 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:1 rwm' for device /dev/mmd_hmip to addon_de838cd8_raspberrymatic
22-11-02 12:07:32 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:2 rwm' for device /dev/mmd_bidcos to addon_de838cd8_raspberrymatic
22-11-02 12:10:21 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon RaspberryMatic CCU is unhealthy, restarting...
22-11-02 12:10:21 INFO (SyncWorker_1) [supervisor.docker.interface] Stopping addon_de838cd8_raspberrymatic application
22-11-02 12:10:31 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_bidcos - None
22-11-02 12:10:32 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_hmip - None
22-11-02 12:10:33 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/eq3loop - None
22-11-02 12:10:39 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:10:39 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5139: 
22-11-02 12:10:39 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:10:39 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5140: 
22-11-02 12:10:39 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:10:39 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5139: 
22-11-02 12:10:39 INFO (SyncWorker_1) [supervisor.docker.interface] Cleaning addon_de838cd8_raspberrymatic application
22-11-02 12:10:40 WARNING (SyncWorker_2) [supervisor.docker.addon] RaspberryMatic CCU running with disabled protected mode!
22-11-02 12:10:41 INFO (SyncWorker_2) [supervisor.docker.addon] Starting Docker add-on ghcr.io/jens-maus/raspberrymatic with version 3.65.11.20221005
22-11-02 12:10:42 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:42 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:42 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:43 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:44 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:45 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:45 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:47 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:47 ERROR (MainThread) [supervisor.api.ingress] Ingress error: Cannot connect to host 172.30.33.0:8099 ssl:default [Connect call failed ('172.30.33.0', 8099)]
22-11-02 12:10:50 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/eq3loop - None
22-11-02 12:10:50 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:0 rwm' for device /dev/eq3loop to addon_de838cd8_raspberrymatic
22-11-02 12:10:53 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_hmip - None
22-11-02 12:10:53 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_bidcos - None
22-11-02 12:10:53 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:1 rwm' for device /dev/mmd_hmip to addon_de838cd8_raspberrymatic
22-11-02 12:10:53 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:2 rwm' for device /dev/mmd_bidcos to addon_de838cd8_raspberrymatic
22-11-02 12:13:42 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon RaspberryMatic CCU is unhealthy, restarting...
22-11-02 12:13:42 INFO (SyncWorker_3) [supervisor.docker.interface] Stopping addon_de838cd8_raspberrymatic application
22-11-02 12:13:51 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_bidcos - None
22-11-02 12:13:52 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_hmip - None
22-11-02 12:13:53 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/eq3loop - None
22-11-02 12:13:58 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:13:58 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5142: 
22-11-02 12:13:58 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:13:58 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5143: 
22-11-02 12:13:58 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:13:58 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5142: 
22-11-02 12:13:59 INFO (SyncWorker_3) [supervisor.docker.interface] Cleaning addon_de838cd8_raspberrymatic application
22-11-02 12:13:59 WARNING (SyncWorker_7) [supervisor.docker.addon] RaspberryMatic CCU running with disabled protected mode!
22-11-02 12:14:00 INFO (SyncWorker_7) [supervisor.docker.addon] Starting Docker add-on ghcr.io/jens-maus/raspberrymatic with version 3.65.11.20221005
22-11-02 12:14:00 WARNING (MainThread) [supervisor.misc.tasks] Watchdog missing application response from de838cd8_raspberrymatic
22-11-02 12:14:10 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/eq3loop - None
22-11-02 12:14:10 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:0 rwm' for device /dev/eq3loop to addon_de838cd8_raspberrymatic
22-11-02 12:14:13 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_hmip - None
22-11-02 12:14:13 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_bidcos - None
22-11-02 12:14:13 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:1 rwm' for device /dev/mmd_hmip to addon_de838cd8_raspberrymatic
22-11-02 12:14:13 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:2 rwm' for device /dev/mmd_bidcos to addon_de838cd8_raspberrymatic
22-11-02 12:17:01 WARNING (MainThread) [supervisor.addons.addon] Watchdog found addon RaspberryMatic CCU is unhealthy, restarting...
22-11-02 12:17:01 INFO (SyncWorker_5) [supervisor.docker.interface] Stopping addon_de838cd8_raspberrymatic application
22-11-02 12:17:11 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_bidcos - None
22-11-02 12:17:11 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/mmd_hmip - None
22-11-02 12:17:13 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.REMOVE hardware /dev/eq3loop - None
22-11-02 12:17:18 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:17:18 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5145: 
22-11-02 12:17:18 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:17:18 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5146: 
22-11-02 12:17:18 ERROR (MainThread) [supervisor.utils.dbus] D-Bus method org.freedesktop.DBus.Properties.call_get_all not exists!
22-11-02 12:17:18 WARNING (MainThread) [supervisor.dbus.network] Can't process /org/freedesktop/NetworkManager/Devices/5145: 
22-11-02 12:17:18 INFO (SyncWorker_5) [supervisor.docker.interface] Cleaning addon_de838cd8_raspberrymatic application
22-11-02 12:17:18 WARNING (SyncWorker_1) [supervisor.docker.addon] RaspberryMatic CCU running with disabled protected mode!
22-11-02 12:17:20 INFO (SyncWorker_1) [supervisor.docker.addon] Starting Docker add-on ghcr.io/jens-maus/raspberrymatic with version 3.65.11.20221005
22-11-02 12:17:30 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/eq3loop - None
22-11-02 12:17:30 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:0 rwm' for device /dev/eq3loop to addon_de838cd8_raspberrymatic
22-11-02 12:17:33 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_hmip - None
22-11-02 12:17:33 INFO (MainThread) [supervisor.hardware.monitor] Detecting HardwareAction.ADD hardware /dev/mmd_bidcos - None
22-11-02 12:17:33 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:1 rwm' for device /dev/mmd_hmip to addon_de838cd8_raspberrymatic
22-11-02 12:17:33 INFO (MainThread) [supervisor.docker.addon] Added cgroup permissions 'c 234:2 rwm' for device /dev/mmd_bidcos to addon_de838cd8_raspberrymatic

Additional information

jens-maus · 2022-11-02T11:39:37Z

jens-maus
Nov 2, 2022
Maintainer

Sorry, but this is definitly no bug in RaspberryMatic, thus no "issue" but rather a local user related issue. That's why I have converted it into a discussion instead so that other users can potentially help you.

0 replies

Sydney1101 · 2022-11-02T15:06:49Z

Sydney1101
Nov 2, 2022
Author

@jens-maus I deactivated the watchdog of the Raspberrymatic Home Assistant Addon and now, I have no longer problems with the addon. It seems that the activated watchdog creates that trouble. How can I fix it? How can I remove the redmatic.cfg monitoring. Redmatic is not installed.

2 replies

Sydney1101 Nov 2, 2022
Author

Nov  2 15:41:46 de838cd8-raspberrymatic daemon.info : starting pid 1346, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Nov  2 15:41:46 de838cd8-raspberrymatic user.warn monit: /usr/local/etc/monit-redmatic.cfg:6: Program does not exist: '/etc/config/rc.d/redmatic'
Nov  2 15:41:46 de838cd8-raspberrymatic user.warn monit: /usr/local/etc/monit-redmatic.cfg:7: Program does not exist: '/etc/config/rc.d/redmatic'
Nov  2 15:41:46 de838cd8-raspberrymatic user.warn monit: /usr/local/etc/monit-redmatic.cfg:8: Program does not exist: '/etc/config/rc.d/redmatic'
Nov  2 15:41:46 de838cd8-raspberrymatic user.warn monit: /usr/local/etc/monit-redmatic.cfg:20: Program does not exist: '/usr/local/addons/redmatic/bin/monit-du'
Nov  2 15:41:46 de838cd8-raspberrymatic user.err monit: A 'check program' statement requires the program to exist '/usr/local/addons/redmatic/bin/monit-du'
Nov  2 15:41:46 de838cd8-raspberrymatic daemon.info : process '/usr/bin/monit -Ic /etc/monitrc' (pid 1346) exited. Scheduling for restart.

jens-maus Nov 3, 2022
Maintainer

Delete the file /usr/local/etc/monit-redmatic.cfg should be the solution!

So either install RedMatic and uninstall it correctly right away so that everything related will be correctly removed. Or login via ssh/terminal to the running RaspberryMatic and remove the mentioned cfg file manually.

Sydney1101 · 2022-11-10T23:51:52Z

Sydney1101
Nov 10, 2022
Author

Sorry for the delayed answer. Install and uninstall of RedMatic solved the problem. Many thanks for your support and respect for your helpful work.

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Home Assistant - Restarts and connection problems #2034

{{title}}

Replies: 3 comments 2 replies

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

Select a reply

Home Assistant - Restarts and connection problems #2034

Sydney1101 Nov 2, 2022

Describe the issue you are experiencing

Describe the behavior you expected

Steps to reproduce the issue

What is the version this bug report is based on?

Which base platform are you running?

Which HomeMatic/homematicIP radio module are you using?

Anything in the logs that might be useful for us?

Additional information

Replies: 3 comments · 2 replies

jens-maus Nov 2, 2022 Maintainer

Sydney1101 Nov 2, 2022 Author

Sydney1101 Nov 2, 2022 Author

jens-maus Nov 3, 2022 Maintainer

Sydney1101 Nov 10, 2022 Author

Sydney1101
Nov 2, 2022

Replies: 3 comments 2 replies

jens-maus
Nov 2, 2022
Maintainer

Sydney1101
Nov 2, 2022
Author

Sydney1101 Nov 2, 2022
Author

jens-maus Nov 3, 2022
Maintainer

Sydney1101
Nov 10, 2022
Author