Fresh openhabian install on a different RPi4 with a brand new Aeotec Z-stick Gen 5+, no USB hub. Aeotec claims the Gen5+ is RPi4 compatible.
Added udev rule for a symlink and installed Z-wave binding. Serial Controller shows ONLINE.
On a soft reset (systemctl or reboot) the Z-wave binding recovers ONLINE.
On a hard reset (power cycle) the Z-wave binding stops working every few cycles and then won’t restart. I’d like to say I’ve found the formula to restart it but I can’t say whether it’s removing the bindings and reloading, hard reset followed by soft reset or random.
What I see in the logs is the “Starting Zwave Controller” message is missing when the binding remains OFFLINE and a “HANDLER MISSING ERROR / Handler Factory not found” from the thing log.
Any thoughts on what would keep the ZWave Controller from starting? The device and the symlink are present in /dev. Stick is showing a green light.
I’ve ordered an Alchemy UPS hat for the PI, but IRL I can’t guarantee the PI will never encounter a hard reset. This is easy to reproduce: just load Openhabian on a PI, plug in a ZWave stick, load the binding and try a series of hard resets.
============================
2023-05-18 07:25:01.085 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to ‘America/Los_Angeles’.
2023-05-18 07:25:01.172 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to ‘36.85618392376164,-121.87500178813936’.
2023-05-18 07:25:01.175 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to ‘en_US’.
2023-05-18 07:25:27.853 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2023-05-18 07:25:35.132 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel
2023-05-18 07:25:37.352 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2023-05-18 07:25:43.436 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2023-05-18 07:27:16.224 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Stopped HABPanel
2023-05-18 07:27:16.267 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI
==> /var/log/openhab/events.log <==
2023-05-18 07:25:43.537 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:c17dc773cc’ changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-05-18 07:25:43.556 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:c17dc773cc’ changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2023-05-18 07:27:15.892 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:c17dc773cc’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to UNINITIALIZED
2023-05-18 07:27:15.913 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:c17dc773cc’ changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
==> /var/log/openhab/openhab.log <==
2023-05-18 07:27:52.760 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to ‘America/Los_Angeles’.
2023-05-18 07:27:52.848 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to ‘36.85618392376164,-121.87500178813936’.
2023-05-18 07:27:52.851 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to ‘en_US’.
2023-05-18 07:28:18.835 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2023-05-18 07:28:24.037 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2023-05-18 07:28:25.720 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel
2023-05-18 07:28:33.044 [INFO ] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
==> /var/log/openhab/events.log <==
2023-05-18 07:28:33.106 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:c17dc773cc’ changed from UNINITIALIZED (HANDLER_MISSING_ERROR): Handler factory not found to INITIALIZING
2023-05-18 07:28:33.120 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:c17dc773cc’ changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
==> /var/log/openhab/openhab.log <==
2023-05-18 07:28:38.211 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2023-05-18 07:28:38.213 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
==> /var/log/openhab/events.log <==
2023-05-18 07:28:41.699 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘zwave:serial_zstick:c17dc773cc’ changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE