Zooz ZST39 EU: BRIDGE_OFFLINE after restarting openhab

[yzrh]

Hi everyone,

I started playing with z-wave few days ago. Got serval (battery powered) sensors, relays, and a z-stick (Zooz ZST39 EU). I got zst39 working following

Zwave Z-stick SDK issue with OH Z-wave binding

Tl;dr The newest Z-sticks need an adjustment with Silicon Laboratory “Silabs” Simplicity 5 to work in openHAB (OH), until an automatic fix can be included in a stable OH Z-wave binding. The procedure is outlined below: Brief Context: Z-sticks are marketed by their chip (500, 700 and 800), but have different firmware (Silabs Development Kits (SDKs)). The latest SDK for a 500 chip is 6.x and has no issues with the OH Z-wave binding. The 700 and 800 chips use 7 series SDKs that support the Z-wave …

and with ser2net workaround.

After including a few devices, I stopped openhab and move zst39 to an usb extension cable.
But after starting openhab, the bridge does not come online. There is something clearly wrong in the log.

2025-02-23 16:35:52.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.391 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 02 FE
2025-02-23 16:35:52.391 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 02 FE   
2025-02-23 16:35:52.391 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:52.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 12: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.448 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 12: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty   
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:53.947 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 25 01 02 0A 08 1D 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 08 00 32 01 25
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (2)
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 08 1D 00 00 00 00 00 00 00 00
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.950 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x08.
2025-02-23 16:35:53.950 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.950 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x32.
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 25 01 02 0A 08 1D 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 08 00 32 01 25
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (2)
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 08 1D 00 00 00 00 00 00 00 00
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x08.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x32.
2025-02-23 16:35:54.391 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 12: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2025-02-23 16:35:54.392 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 12: Transaction is current transaction, so clearing!!!!!
2025-02-23 16:35:54.392 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 12: Transaction CANCELLED
2025-02-23 16:35:54.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:12 CANCELLED
2025-02-23 16:35:54.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:54.393 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 05 F9
2025-02-23 16:35:54.393 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 05 F9
2025-02-23 16:35:54.393 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:54.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.397 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:54.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:54.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:54.398 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 05 1C E3
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=GetControllerCapabilities[5], type=Response[1], dest=255, callback=0, payload=1C
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=GetControllerCapabilities[5], type=Response[1], dest=255, callback=0, payload=1C
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.398 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=GetControllerCapabilities[5], type=Response[1], dest=255, callback=0, payload=1C
2025-02-23 16:35:54.398 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is secondary = false
2025-02-23 16:35:54.398 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is on other network = false
2025-02-23 16:35:54.398 [DEBUG] [etControllerCapabilitiesMessageClass] - Node ID Server is present = false
2025-02-23 16:35:54.399 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is real primary = true
2025-02-23 16:35:54.399 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is SUC = true
2025-02-23 16:35:54.399 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13: Transaction COMPLETED
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 6ms
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 13: Transaction completed
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:13 DONE
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

I have then performed a reset to zst39, upgraded the firmware, and included all devices again. After restarting openhab, the same error showed up in log. It does look like zst39 is talking something unknown to the z-wave binding.

This is with openhab 4.3.1 on FreeBSD 14.2 (jail).

I have no clue (other than getting another z-stick?) what to try next, any ideas is welcomed.

I do like openhab sorry for any formatting issues and thank you for your time.

[apella12 (Bob Eckhoff)]

Took a quick look and something is amiss, but don’t understand. Hope it is not something new When you reset for 8 bits, what SDK did you find? Also need the debug log from the beginning. Comparing to one of my working tests with the ZST39;
The command is

NODE 255: Sending REQUEST Message = 01 03 00 02 FE - yours
NODE 255: Sending REQUEST Message = 01 03 00 02 FE - mine

Inital message

 - Receive Message =   01 25 01 02 0A 08 1D 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 08 00 32 01 25
 Mine			       01 25 01 02 0A 00 1D 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 08 00 C7
 Count				                    	01 02 03 04 05 06 07 08 09 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29

Payload

Receive Message = 01 0A 08 1D 00 00 00 00 00 00 00 00 //Yours
                    =0A 00 1D 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 08 00  /mine

So somehow a 0x01 is being added (or not subtracted) to that response and it throws the whole thing off. The second byte (0, 1, 2) needs to be 0x1D

Also the full message looks off. there are supposed to be 29 bytes (0x1D) to represent the nodes in the network. In my example I just have node 1. Yours seem to only show Node 220 (27 x 8 +4), so the controller is missing?

[yzrh]

I think I need to mention that secure inclusion is on for one relay,
the rest 8 devices are battery powered sensors (secure off).
When I downloaded the firmware, it was version 1.50, SDK version 7.22.1.

https://www.support.getzooz.com/kb/article/1352-zst39-800-long-range-z-wave-stick-change-log/

Wait, that is your guide I was following. Hats off to you great sir.

Amend: before the reset and firmware upgrade, node1 would show up in inbox, but not
anymore.

I can’t upload attachment, so here it is.

2025-02-23 16:35:43.379 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Creating ZWave discovery service for zwave:serial_zstick:faafe1a9fc with scan time of 60
2025-02-23 16:35:43.379 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Active zwave:serial_zstick:faafe1a9fc
2025-02-23 16:35:43.379 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Attempting to add listener when controller is null
2025-02-23 16:35:44.137 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Initializing ZWave serial controller.
2025-02-23 16:35:44.137 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initializing ZWave Controller zwave:serial_zstick:faafe1a9fc.
2025-02-23 16:35:44.142 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node6.
2025-02-23 16:35:44.142 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: MANUFACTURER not set
2025-02-23 16:35:44.142 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node2.
2025-02-23 16:35:44.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node10.
2025-02-23 16:35:44.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node7.
2025-02-23 16:35:44.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node3.
2025-02-23 16:35:44.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: MANUFACTURER not set
2025-02-23 16:35:44.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: MANUFACTURER not set
2025-02-23 16:35:44.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: MANUFACTURER not set
2025-02-23 16:35:44.144 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node5.
2025-02-23 16:35:44.145 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: MANUFACTURER not set
2025-02-23 16:35:44.145 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node4.
2025-02-23 16:35:44.146 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: MANUFACTURER not set
2025-02-23 16:35:44.146 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node8.
2025-02-23 16:35:44.146 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:faafe1a9fc:node9.
2025-02-23 16:35:44.146 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: MANUFACTURER not set
2025-02-23 16:35:44.146 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: MANUFACTURER not set
2025-02-23 16:35:49.138 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Connecting to serial port 'rfc2217://127.0.0.1:3000'
2025-02-23 16:35:49.139 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Enabling receive threshold is unsupported
2025-02-23 16:35:49.139 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Enabling receive timeout is unsupported
2025-02-23 16:35:49.139 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Starting receive thread
2025-02-23 16:35:49.140 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Serial port is initialized
2025-02-23 16:35:49.140 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Initialising ZWave controller
2025-02-23 16:35:49.140 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Starting ZWave thread: Receive
2025-02-23 16:35:49.140 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2025-02-23 16:35:49.140 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2025-02-23 16:35:49.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:49.141 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2025-02-23 16:35:49.141 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2025-02-23 16:35:49.141 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Scheduling network mesh heal for 10 hours time.
2025-02-23 16:35:49.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.141 [DEBUG] [.ZWaveController$InitializeDelayTask] - Initialising network
2025-02-23 16:35:52.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 7 to queue - size 1
2025-02-23 16:35:52.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.142 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 15 E9 
2025-02-23 16:35:52.142 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2025-02-23 16:35:52.142 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:52.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 7: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 8 to queue - size 1
2025-02-23 16:35:52.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 9 to queue - size 2
2025-02-23 16:35:52.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 10 to queue - size 3
2025-02-23 16:35:52.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.143 [DEBUG] [rialmessage.GetSucNodeIdMessageClass] - Get SUC NodeID
2025-02-23 16:35:52.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 11 to queue - size 4
2025-02-23 16:35:52.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.145 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:52.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 7: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:52.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.147 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 01 15 5A 2D 57 61 76 65 20 37 2E 32 32 00 07 97 
2025-02-23 16:35:52.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=GetVersion[21], type=Response[1], dest=255, callback=0, payload=5A 2D 57 61 76 65 20 37 2E 32 32 00 07 
2025-02-23 16:35:52.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=GetVersion[21], type=Response[1], dest=255, callback=0, payload=5A 2D 57 61 76 65 20 37 2E 32 32 00 07 
2025-02-23 16:35:52.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 7: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-23 16:35:52.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 7: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.148 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=GetVersion[21], type=Response[1], dest=255, callback=0, payload=5A 2D 57 61 76 65 20 37 2E 32 32 00 07 
2025-02-23 16:35:52.148 [DEBUG] [serialmessage.GetVersionMessageClass] - Got MessageGetVersion response. Version=Z-Wave 7.22, Library Type=7
2025-02-23 16:35:52.148 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 7: Transaction COMPLETED
2025-02-23 16:35:52.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 6ms
2025-02-23 16:35:52.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 7: Transaction completed
2025-02-23 16:35:52.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:7 DONE
2025-02-23 16:35:52.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.149 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 20 DC 
2025-02-23 16:35:52.149 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2025-02-23 16:35:52.149 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:52.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 8: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.208 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:52.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 8: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:52.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.209 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 01 20 FC 34 EC FA 01 09 
2025-02-23 16:35:52.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=MemoryGetId[32], type=Response[1], dest=255, callback=0, payload=FC 34 EC FA 01 
2025-02-23 16:35:52.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=MemoryGetId[32], type=Response[1], dest=255, callback=0, payload=FC 34 EC FA 01 
2025-02-23 16:35:52.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 8: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-23 16:35:52.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 8: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.210 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=MemoryGetId[32], type=Response[1], dest=255, callback=0, payload=FC 34 EC FA 01 
2025-02-23 16:35:52.210 [DEBUG] [erialmessage.MemoryGetIdMessageClass] - Got MessageMemoryGetId response. Home id = 0xfc34ecfa, Controller Node id = 1
2025-02-23 16:35:52.210 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 8: Transaction COMPLETED
2025-02-23 16:35:52.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 61ms
2025-02-23 16:35:52.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 8: Transaction completed
2025-02-23 16:35:52.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:8 DONE
2025-02-23 16:35:52.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.211 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 07 FB 
2025-02-23 16:35:52.211 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2025-02-23 16:35:52.211 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:52.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.268 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:52.268 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:52.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.272 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 2B 01 07 01 32 02 7A 00 04 06 10 F6 87 3E 88 CF 2B C0 5F FB D7 FD E0 97 00 00 80 00 80 86 80 BA 05 00 70 00 00 EE 7F C0 00 00 00 09 
2025-02-23 16:35:52.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SerialApiGetCapabilities[7], type=Response[1], dest=255, callback=0, payload=01 32 02 7A 00 04 06 10 F6 87 3E 88 CF 2B C0 5F FB D7 FD E0 97 00 00 80 00 80 86 80 BA 05 00 70 00 00 EE 7F C0 00 00 00 
2025-02-23 16:35:52.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SerialApiGetCapabilities[7], type=Response[1], dest=255, callback=0, payload=01 32 02 7A 00 04 06 10 F6 87 3E 88 CF 2B C0 5F FB D7 FD E0 97 00 00 80 00 80 86 80 BA 05 00 70 00 00 EE 7F C0 00 00 00 
2025-02-23 16:35:52.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-23 16:35:52.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 9: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.274 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SerialApiGetCapabilities[7], type=Response[1], dest=255, callback=0, payload=01 32 02 7A 00 04 06 10 F6 87 3E 88 CF 2B C0 5F FB D7 FD E0 97 00 00 80 00 80 86 80 BA 05 00 70 00 00 EE 7F C0 00 00 00 
2025-02-23 16:35:52.274 [DEBUG] [SerialApiGetCapabilitiesMessageClass] - API Version    = 1.50
2025-02-23 16:35:52.274 [DEBUG] [SerialApiGetCapabilitiesMessageClass] - Manufacture ID = 0x27a
2025-02-23 16:35:52.274 [DEBUG] [SerialApiGetCapabilitiesMessageClass] - Device Type    = 0x4
2025-02-23 16:35:52.274 [DEBUG] [SerialApiGetCapabilitiesMessageClass] - Device ID      = 0x610
2025-02-23 16:35:52.274 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 9: Transaction COMPLETED
2025-02-23 16:35:52.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 12 to queue - size 3
2025-02-23 16:35:52.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 63ms
2025-02-23 16:35:52.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 9: Transaction completed
2025-02-23 16:35:52.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:9 DONE
2025-02-23 16:35:52.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.275 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 06 96 0F 65 
2025-02-23 16:35:52.275 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2025-02-23 16:35:52.275 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:52.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 10: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.328 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:52.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 10: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:52.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.329 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 01 06 96 0F 64 
2025-02-23 16:35:52.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SerialApiSetTimeouts[6], type=Response[1], dest=255, callback=0, payload=96 0F 
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SerialApiSetTimeouts[6], type=Response[1], dest=255, callback=0, payload=96 0F 
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 10: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 10: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.330 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SerialApiSetTimeouts[6], type=Response[1], dest=255, callback=0, payload=96 0F 
2025-02-23 16:35:52.330 [DEBUG] [age.SerialApiSetTimeoutsMessageClass] - Got SerialApiSetTimeouts response. ACK=150, BYTE=15
2025-02-23 16:35:52.330 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 10: Transaction COMPLETED
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 55ms
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 10: Transaction completed
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:10 DONE
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.331 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 56 AA 
2025-02-23 16:35:52.331 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2025-02-23 16:35:52.331 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:52.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 11: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.388 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:52.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 11: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.389 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 56 01 AD 
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=GetSucNodeId[86], type=Response[1], dest=255, callback=0, payload=01 
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=GetSucNodeId[86], type=Response[1], dest=255, callback=0, payload=01 
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 11: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-23 16:35:52.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 11: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.390 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=GetSucNodeId[86], type=Response[1], dest=255, callback=0, payload=01 
2025-02-23 16:35:52.390 [DEBUG] [rialmessage.GetSucNodeIdMessageClass] - Got SUC NodeID response.
2025-02-23 16:35:52.390 [DEBUG] [rialmessage.GetSucNodeIdMessageClass] - NODE 1: Node is SUC.
2025-02-23 16:35:52.390 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 11: Transaction COMPLETED
2025-02-23 16:35:52.390 [DEBUG] [etControllerCapabilitiesMessageClass] - Creating GET_CONTROLLER_CAPABILITIES message
2025-02-23 16:35:52.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 13 to queue - size 2
2025-02-23 16:35:52.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:52.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 59ms
2025-02-23 16:35:52.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 11: Transaction completed
2025-02-23 16:35:52.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:11 DONE
2025-02-23 16:35:52.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:52.391 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 02 FE 
2025-02-23 16:35:52.391 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 02 FE 
2025-02-23 16:35:52.391 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:52.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 12: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.448 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 12: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:53.947 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 25 01 02 0A 08 1D 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 08 00 32 01 25 
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (2)
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 08 1D 00 00 00 00 00 00 00 00 
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.949 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.950 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x08.
2025-02-23 16:35:53.950 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.950 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x32.
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 25 01 02 0A 08 1D 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 08 00 32 01 25 
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (2)
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 08 1D 00 00 00 00 00 00 00 00 
2025-02-23 16:35:53.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.961 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x08.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2025-02-23 16:35:53.962 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x32.
2025-02-23 16:35:54.391 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 12: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2025-02-23 16:35:54.392 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 12: Transaction is current transaction, so clearing!!!!!
2025-02-23 16:35:54.392 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 12: Transaction CANCELLED
2025-02-23 16:35:54.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:12 CANCELLED
2025-02-23 16:35:54.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:35:54.393 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 05 F9 
2025-02-23 16:35:54.393 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 05 F9 
2025-02-23 16:35:54.393 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2025-02-23 16:35:54.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.397 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2025-02-23 16:35:54.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:54.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2025-02-23 16:35:54.398 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 05 1C E3 
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=GetControllerCapabilities[5], type=Response[1], dest=255, callback=0, payload=1C 
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=GetControllerCapabilities[5], type=Response[1], dest=255, callback=0, payload=1C 
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2025-02-23 16:35:54.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2025-02-23 16:35:54.398 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=GetControllerCapabilities[5], type=Response[1], dest=255, callback=0, payload=1C 
2025-02-23 16:35:54.398 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is secondary = false
2025-02-23 16:35:54.398 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is on other network = false
2025-02-23 16:35:54.398 [DEBUG] [etControllerCapabilitiesMessageClass] - Node ID Server is present = false
2025-02-23 16:35:54.399 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is real primary = true
2025-02-23 16:35:54.399 [DEBUG] [etControllerCapabilitiesMessageClass] - Controller is SUC = true
2025-02-23 16:35:54.399 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 13: Transaction COMPLETED
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 6ms
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 13: Transaction completed
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:13 DONE
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 16:35:54.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2025-02-23 16:52:25.810 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - NODE 2: Node not found in getConfigDescription
2025-02-23 16:52:29.540 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:faafe1a9fc

[apella12 (Bob Eckhoff)]

@yzrh

When I downloaded the firmware, it was version 1.50, SDK version 7.22.1.

That is the same version I used.

@yzrh

Amend: before the reset and firmware upgrade, node1 would show up in inbox, but not
anymore.

I’m concerned that something went awry with that, since it worked before. Also did you add these devices in OH or with Simplicity Studio? Not sure what to suggest except to delete everything and try from scratch. Also might want to go to OH 4.3.3. Sorry

[yzrh]

Both inclusion were with openhab. The problem always occurred after including
devices then restarting openhab, before and after firmware upgrade.

With the bridge still shows OFFLINE, there are more log entries. Showing just the first.

2025-02-23 19:29:30.615 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - ZWave product zwave:serial_zstick has no references!
2025-02-23 19:29:30.617 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - ZWave product zwave:device has no references!
2025-02-23 19:50:14.276 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 A8 00 01 06 06 31 05 05 02 00 44 00 AA 84
2025-02-23 19:50:14.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=BridgeApplicationCom
mandHandler[168], type=Request[0], dest=6, callback=0, payload=00 01 06 06 31 05 05 02 00 44 00 AA
2025-02-23 19:50:14.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=BridgeApplicationCommandHandler[168], t
ype=Request[0], dest=6, callback=0, payload=00 01 06 06 31 05 05 02 00 44 00 AA
2025-02-23 19:50:14.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2025-02-23 19:50:14.278 [WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 6: Not initialized (ie node unknown), ignoring message.
2025-02-23 19:50:14.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2025-02-23 19:50:14.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

I also noticed using Simplicity Studio, I was able to toggle the relay. The same z-stick
would still display BRIDGE_OFFLINE in openhab.

I did not see anything about z-wave in changelog of 4.3.3, let me build a package and try.

[apella12 (Bob Eckhoff)]

@yzrh

Showing just the first.

That message looks fine. It is the failed message from above that is causing the problem. Unfortunately, it is critical. It directs OH to what nodes have been included. The message from the controller is bad. The reason I suggested 4.3.3 is only because OH doesn’t read the message correctly, also. I know there is no change in the binding but wanted to eliminate some sort of corruption with a new binding copy. However, the base problem is the message (that I manually read correctly) is garbage.

How did you get the devices included in the first place?

[yzrh]

@apella12

How did you get the devices included in the first place?

By activating inclusion mode then add the device from things/z-wave binding/scan.

[yzrh]

I only restarted openhab because despite the fact that all devices were included,
all (8) battery powered sensors displayed unknown device and had no reading.
Mains powered relay had the correct type and channels, yet toggling
relay output only worked sometimes.

Amend: Simplicity Studio does display all devices included with openhab, and is able
to toggle the relay. openhab is running on a server with ECC memory, I have recreated the jail and installed openhab again with pkg. I will build it locally as I have run out of things to test…

[apella12 (Bob Eckhoff)]

@yzrh

By activating inclusion mode then add the device from things/z-wave binding/scan.

I meant with the bridge offline, but I get it now . Inclusion in OH worked with the controller until the restart. That must mean that the message that is not working after the restart worked originally (when no devices were added). Correct (Bridge ONLINE)? You wouldn’t happen to have the log from when the controller was first manually created in OH prior to adding devices?

As to battery devices, they sometimes need to be woken to get fully configured. Also they need to be in the ZW DB. Are there any XML files in the userdata/zwave folder? Some [background on unknown devices].(Guidance for "unknown" Zwave Device) There should at least be the controller from the manual creation. I don’t think the devices are the root problem

EDIT: I might help to get a debug by deleting the controller thing and then manually creating a controller again. (besides the start from scratch idea).
If you do start from scratch get a debug of the manual creation of the controller. Then restart OH before adding any devices. Trying to isolate where this occurs.

[yzrh]

I have reset the z-stick again to get the log (adding bridge). Here it is (also added some sensors and a relay afterwards):

PrivateBin

It seems that the communication between devices had problems.
There are Message is invalid' and Protocol error’ everywhere.
This is the log of adding a battery powered sensor (Node 8), then wake it up a few times.

PrivateBin

[yzrh]

And we have our BRIDGE_OFFLINE again

PrivateBin

[apella12 (Bob Eckhoff)]

Just started looking. File one starts okay at the start. I was hoping for a OH restart after just the controller to avoid any interaction with the devices. I am also concerned about the security aspect. Might suggest including a device without, then restart (again to try to isolate), but let me see.

Also old business, forgot welcome you to OH, sorry it is under these circumstances. Also there is a ZWave debug viewer that I’m using that provides a color-coded scan available to all. It points out areas (and times) to look at the file in detail.

[yzrh]

I still have the vm with Simplicity Studio running, let me reset another time.

[yzrh]

@apella12

Also old business, forgot welcome you to OH, sorry it is under these circumstances. Also there is a ZWave debug viewer that I’m using that provides a color-coded scan available to all. It points out areas (and times) to look at the file in detail.

Thank you for your help. I am sure OH is great, maybe I should have got Zigbee instead. Well, I can still do that can’t I?

[yzrh]

Adding the bridge then a sensor:

PrivateBin

After restart:

PrivateBin

I have pressed the wake up button quite a few times.

[apella12 (Bob Eckhoff)]

I’m starting to think there are issues with your setup using ser2net. There seems to be secondary messages of a shorter length in some cases that provide garbage information or blow up completely. It is just not going to be stable with random byte streams.

There are other things mostly with security it seems, that may or may not be related. In the first section Node2 failed with security scheme. Maybe because of a bad message, maybe not. Also node 3 stopped responding.

I think there is enough information to rule out a binding issue IMO.

EDIT: Quick look at the last 2. Node 2 security is not working. It looks like my last picture. Only interesting thing is the bridge looks like it came back, but it is hard to say this is reliable

[apella12 (Bob Eckhoff)]

I’m going to have to run, but one last thought is that some devices only use S2 security and OH ZW only supports S0. Maybe that is also an issue (besides the duplicate communication). If you want S2 security you could look at this option. It requires MQTT, has S2 security. Zwave-JS-UI in place of OH Zwave binding - Tutorials & Examples / openHAB Stories - openHAB Community

[yzrh]

It does work (S2 security) and it shows readings right away. But I am not into the idea of nodejs, it is really inconvenient.

I think I will play with zigbee instead. Thanks again for your time and patience.
Depending upon if zigbee (also in the form of usb modem) works, we will know what the problem is.

[apella12 (Bob Eckhoff)]

Zigbee is popular. There is an OH binding, but many in OH use the Zigbee to MQTT application.

I don’t understand the nodejs comment. From where you are you could activate the HomeAssistant configs and pick them up in OH (via MQTT). Also, one of the core OH developers has a draft binding to connect to ZUI via WebSocket.

Good luck in whatever direction you take.

[yzrh]

@apella12

I don’t understand the nodejs comment. From where you are you could activate the HomeAssistant configs and pick them up in OH (via MQTT). Also, one of the core OH developers has a draft binding to connect to ZUI via WebSocket.

What I meant is zui is not packaged. While npm install is okay for testing, it is not reproducible.

It is fair to say z-wave support (at least with 800 series and S2 security) within OH is only possible with zui. Perhaps I will get zui submitted to ports, so others can benefit from what we learnt.