Z-Wave devices are getting offline after controller restart

mindkicker · April 8, 2019, 9:37am

Platform information:
- Hardware: PRi 3B+
- openHAB version: openhabian 2.x + 2.5 z-wave binding

Hello!

I was trying to change rule engine configuration in accordance to topic Solutions for Lazy Execution of Rules
and after several restarts I got all my devices offline except of controller node which is still online.
USB port was persisted in advance according to topic Node not found in Z-Wave network

So my problem seems not to be related to usb device ports. Could you suggest any help?

See log after restarting RPi

7:38:42.273 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller status changed to ONLINE.
07:38:42.272 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Controller status changed to ONLINE.
07:38:42.285 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller is ONLINE. Starting device initialisation.
07:38:42.267 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Controller is ONLINE. Starting device initialisation.
07:38:42.269 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zwave:serial_zstick:5d08e7af' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
07:38:42.261 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Controller status changed to ONLINE.
07:38:42.286 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Controller is ONLINE. Starting device initialisation.
07:38:42.314 [DEBUG] [ave.internal.protocol.ZWaveController] - Event listener added.
07:38:42.281 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 01 BB 
07:38:42.321 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zwave:device:5d08e7af:node5' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to OFFLINE: Node not found in Z-Wave network
07:38:42.305 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Controller is ONLINE. Starting device initialisation.
07:38:42.340 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
07:38:42.335 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Initialising Thing Node...
07:38:42.335 [DEBUG] [ave.internal.protocol.ZWaveController] - Event listener added.
07:38:42.345 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 7: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
07:38:42.344 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zwave:device:5d08e7af:node4' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to OFFLINE: Node not found in Z-Wave network
07:38:42.354 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising Thing Node...
07:38:42.353 [DEBUG] [ave.internal.protocol.ZWaveController] - Event listener added.
07:38:42.375 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 2: Initialising Thing Node...
07:38:42.389 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
07:38:42.370 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zwave:device:5d08e7af:node2' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to OFFLINE: Node not found in Z-Wave network
07:38:42.396 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
07:38:42.377 [DEBUG] [ave.internal.protocol.ZWaveController] - Event listener added.
07:38:42.403 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'zwave:device:5d08e7af:node3' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to OFFLINE: Node not found in Z-Wave network
07:38:42.412 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Initialising Thing Node...
07:38:42.417 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'zwave:serial_zstick:5d08e7af' has been updated.<a class="attachment" href="//community-openhab-org.s3.dualstack.eu-central-1.amazonaws.com/original/3X/d/4/d46a858bf7eba940e00e3901ef6229a6e1c6f81d.log">device_not_found.log</a> (62.0 KB)

mindkicker · April 26, 2019, 7:13pm

It seems there is no one z-wave user on this forum

I tried to reset one of my end devices and after that I was surprised to see that controller has started to assign node ids from the beginning. The device got an id 2 after reset, but before the issue it was 5. So it looks like the controller has lost all device configuration stored in it, looks like hard reset but I didn’t ask for that and it is completely impossible that I did hard reset accidentally.

Anyway I will try to reproduce the issue in a couple of weeks.

rpwong · April 26, 2019, 7:26pm

Sorry you haven’t heard back from anyone. There have been lots of ZWave discussions recently, but this doesn’t sound similar to anything I’ve seen (I only got started with zwave two weeks ago, so I wasn’t paying attention when you first posted).

The only thing I can offer is that the 2.5 zwave binding has caused some issues, so you might want to update to the latest snapshot, revert to the 2.4 binding, or update OH to the 2.5 Milestone build.

chris · April 27, 2019, 9:17am

The log is pretty short (200mS long!) so it’s hard to tell what is happening. It indicates hat the controller doesn’t have these devices, but please provide a longer log showing the initialisation of the binding and messages to/from the controller.