New build stop getting updates from things

  • Platform information:
    • Hardware: x86_64, running docker image: openhab/openhab:latest - openHAB 3.3.0
  • Issue of the topic: many items/things aren’t reporting updates to controller

I’ve moved from a raspberry pi - zooz setup to x86_64 in docker with a GoControl CECOMINOD016164 HUSBZB-1 USB Hub.

My prior setup just broke and I’ve been manually doing a factory reset and inclusion to my new setup over the last few weeks ( All zwave stopped working ). When I first include the devices, everything works fine but now it seems that I can only send out updates ( e.g. turn on light ) but most devices aren’t sending updates back to the controlle ( e.g. window was opened ).

This of course leaves me in a bad state since I can’t really run rules like “if windows were left open, turn off a/c”

The interesting thing is that there is no rhyme to reason as some battery and powered devices to submit udpates: GC-TBZ48 sends things like Item 'Thermostat_SensorTemperature' changed from 75 °F to 76 °F and I also see many HS-FLS100+ alerts like Item 'DrivewayFloodlight_MotionAlarm' changed from ON to OFF

corresponding zwave logs

actually here’s the log from just 1 light switch. i just started openhab and then i turned on the switched (dimmed to about 50%), then i held the button to light it to 100%, then turned off the switch (all manual actions)

Screen shots of logs are pretty much useless. Post the text and use code fences.

```
code/logs goes here
```

Beyond the generic advice to make sure that the Things are configured to report to the controller I can’t help but no one will be able to help with screen shots of logs instead of real logs.

In the Thing, make sure that the Association Group property is set to “controller” for each of your Things.

1 Like

here are some logs, only including a few minutes to reduce noise. node 4 is my hallway light switch, i can send it commands and control it fine, but manual events dont seem to make it back:

2022-10-07 22:00:22.993 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DrivewayFloodlight_MotionAlarm' changed from ON to OFF
2022-10-07 22:00:56.853 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DrivewayFloodlight_MotionAlarm' changed from OFF to ON
2022-10-07 22:03:46.393 [INFO ] [openhab.event.ItemCommandEvent      ] - Item 'Hallway_Dimmer' received command 87
2022-10-07 22:03:46.395 [INFO ] [penhab.event.ItemStatePredictedEvent] - Item 'Hallway_Dimmer' predicted to become 87
2022-10-07 22:03:46.395 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Hallway_Dimmer' changed from 64 to 87
2022-10-07 22:04:32.996 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DrivewayFloodlight_MotionAlarm' changed from ON to OFF
2022-10-07 22:05:22.116 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'DrivewayFloodlight_MotionAlarm' changed from OFF to ON
2022-10-07 22:00:22.987 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 1B 0A 71 05 00 00 00 FF 07 00 01 08 7F 
2022-10-07 22:00:22.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0A 71 05 00 00 00 FF 07 00 01 08 
2022-10-07 22:00:22.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0A 71 05 00 00 00 FF 07 00 01 08 
2022-10-07 22:00:22.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:00:22.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:00:22.992 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:00:22.992 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-10-07 22:00:22.992 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_ALARM
2022-10-07 22:00:22.992 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2022-10-07 22:00:22.992 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1
2022-10-07 22:00:22.992 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = BURGLAR (0)
2022-10-07 22:00:22.992 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-10-07 22:00:22.992 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-10-07 22:00:22.992 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:00:22.993 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 0, type OnOffType
2022-10-07 22:00:22.993 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:00:22.993 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 0, type OnOffType
2022-10-07 22:00:22.993 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:alarm_motion to OFF [OnOffType]
2022-10-07 22:00:22.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:00:22.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@725394c0.
2022-10-07 22:00:22.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:22.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:22.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:22.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:00:33.199 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 0F 02 84 07 7D 
2022-10-07 22:00:33.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 02 84 07 
2022-10-07 22:00:33.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 02 84 07 
2022-10-07 22:00:33.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:00:33.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Application Command Request (ALIVE:DONE)
2022-10-07 22:00:33.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: resetResendCount initComplete=true isDead=false
2022-10-07 22:00:33.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: Is awake with 0 messages in the queue, state DONE
2022-10-07 22:00:33.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - awakeMax for this Timer Task 40 in 0.5 second intervals
2022-10-07 22:00:33.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: Start sleep timer with 500ms interval
2022-10-07 22:00:33.204 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2022-10-07 22:00:33.205 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 15: Node Status event - Node is AWAKE
2022-10-07 22:00:33.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Commands processed 1.
2022-10-07 22:00:33.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@185917aa.
2022-10-07 22:00:33.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:33.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:33.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:33.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:00:33.424 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 0F 02 84 07 7D 
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 02 84 07 
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 02 84 07 
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Application Command Request (ALIVE:DONE)
2022-10-07 22:00:33.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: resetResendCount initComplete=true isDead=false
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Commands processed 1.
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@46dbc6b9.
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:33.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:00:33.628 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 0F 02 84 07 7D 
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 02 84 07 
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=15, callback=0, payload=00 0F 02 84 07 
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Application Command Request (ALIVE:DONE)
2022-10-07 22:00:33.633 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: resetResendCount initComplete=true isDead=false
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Commands processed 1.
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2e3a5208.
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:33.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:00:33.705 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: WakeupTimerTask 0 Messages waiting, state DONE count 1
2022-10-07 22:00:33.705 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: Go back to sleep, state DONE, count 1 messages 0
2022-10-07 22:00:33.705 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2022-10-07 22:00:33.705 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: SECURITY NOT required on COMMAND_CLASS_WAKE_UP
2022-10-07 22:00:33.705 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
2022-10-07 22:00:33.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@32f67e1c
2022-10-07 22:00:33.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Bump transaction 904 priority from Immediate to Immediate
2022-10-07 22:00:33.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Adding to device queue
2022-10-07 22:00:33.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: Added 904 to queue - size 7
2022-10-07 22:00:33.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:00:33.705 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0F 02 84 08 25 B1 F0 
2022-10-07 22:00:33.705 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 15: Sending REQUEST Message = 01 09 00 13 0F 02 84 08 25 B1 F0 
2022-10-07 22:00:33.707 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-10-07 22:00:33.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:00:33.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:00:33.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:00:33.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-10-07 22:00:33.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:33.711 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2022-10-07 22:00:33.716 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-10-07 22:00:33.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 904: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 177
2022-10-07 22:00:33.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 904: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 177
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 904: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 177
2022-10-07 22:00:33.719 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:00:33.719 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 15: sentData successfully placed on stack.
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 904: Advanced to WAIT_REQUEST
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: TID 904: Transaction not completed
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:33.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:00:38.719 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 15: TID 904: Timeout at state WAIT_REQUEST. 3 retries remaining.
2022-10-07 22:00:38.720 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2022-10-07 22:00:38.720 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 904: Transaction ABORTED
2022-10-07 22:00:38.720 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2022-10-07 22:00:38.720 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2022-10-07 22:00:38.721 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-10-07 22:00:38.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:00:38.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:00:38.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 904: [ABORTED] priority=Immediate, requiresResponse=true, callback: 177
2022-10-07 22:00:38.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-10-07 22:00:38.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:38.723 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-10-07 22:00:38.723 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:00:38.723 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:00:41.431 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 B1 01 59 
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=177, payload=B1 01 
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=177, payload=B1 01 
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 904: [ABORTED] priority=Immediate, requiresResponse=true, callback: 177
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 904: [ABORTED] priority=Immediate, requiresResponse=true, callback: 177
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 904: (Callback 177)
2022-10-07 22:00:41.436 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 904: callback 177
2022-10-07 22:00:41.436 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=177, payload=B1 01 
2022-10-07 22:00:41.436 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 15: SendData Request. CallBack ID = 177, Status = Transmission complete, no ACK received(1)
2022-10-07 22:00:41.436 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 15: WAKE_UP_NO_MORE_INFORMATION. Treated as ACK.
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: TID 904: Transaction not completed
2022-10-07 22:00:41.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:41.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:00:50.720 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 15: TID 904: Timeout at state ABORTED. 3 retries remaining.
2022-10-07 22:00:50.720 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 904: Transaction is current transaction, so clearing!!!!!
2022-10-07 22:00:50.720 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 904: Transaction CANCELLED
2022-10-07 22:00:50.720 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-10-07 22:00:50.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 15: notifyTransactionResponse TID:904 CANCELLED
2022-10-07 22:00:50.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:00:50.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 904: Transaction event listener: DONE: CANCELLED -> ABORTED
2022-10-07 22:00:50.721 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 15: Went to sleep COMPLETE
2022-10-07 22:00:56.847 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 1B 09 71 05 00 00 00 FF 07 08 00 62 
2022-10-07 22:00:56.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:00:56.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:00:56.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:00:56.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:00:56.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:00:56.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-10-07 22:00:56.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_ALARM
2022-10-07 22:00:56.853 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2022-10-07 22:00:56.853 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2022-10-07 22:00:56.853 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = BURGLAR (0)
2022-10-07 22:00:56.853 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-10-07 22:00:56.853 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-10-07 22:00:56.853 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:00:56.853 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:00:56.853 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:00:56.853 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:00:56.853 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:alarm_motion to ON [OnOffType]
2022-10-07 22:00:56.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:00:56.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4021269b.
2022-10-07 22:00:56.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:56.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:00:56.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:00:56.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:01:27.479 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 22 06 31 05 03 0A 00 FA 14 
2022-10-07 22:01:27.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=34, callback=0, payload=00 22 06 31 05 03 0A 00 FA 
2022-10-07 22:01:27.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=34, callback=0, payload=00 22 06 31 05 03 0A 00 FA 
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 34: Application Command Request (ALIVE:DONE)
2022-10-07 22:01:27.484 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 34: resetResendCount initComplete=true isDead=false
2022-10-07 22:01:27.484 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 34: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2022-10-07 22:01:27.484 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 34: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2022-10-07 22:01:27.484 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 34: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2022-10-07 22:01:27.484 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 34: Sensor Type = Luminance(3), Scale = 1
2022-10-07 22:01:27.484 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 34: Sensor Value = 2.5E+2
2022-10-07 22:01:27.484 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 34: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2022-10-07 22:01:27.484 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 34: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=2.5E+2
2022-10-07 22:01:27.484 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 34: Updating channel state zwave:device:zwave-controller:node34:sensor_luminance to 250 lx [QuantityType]
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 34: Commands processed 1.
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 34: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10158d72.
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:01:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:01:45.989 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 1B 09 71 05 00 00 00 FF 07 08 00 62 
2022-10-07 22:01:45.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:01:45.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:01:45.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:01:45.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:01:45.994 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:01:45.994 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-10-07 22:01:45.994 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_ALARM
2022-10-07 22:01:45.994 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2022-10-07 22:01:45.995 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2022-10-07 22:01:45.995 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = BURGLAR (0)
2022-10-07 22:01:45.995 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-10-07 22:01:45.995 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-10-07 22:01:45.995 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:01:45.995 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:01:45.995 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:01:45.995 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:01:45.995 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:alarm_motion to ON [OnOffType]
2022-10-07 22:01:45.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:01:45.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@8e37fc7.
2022-10-07 22:01:45.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:01:45.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:01:45.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:01:45.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:02:43.879 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 1B 09 71 05 00 00 00 FF 07 08 00 62 
2022-10-07 22:02:43.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:02:43.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:02:43.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:02:43.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:02:43.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:02:43.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-10-07 22:02:43.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_ALARM
2022-10-07 22:02:43.884 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2022-10-07 22:02:43.884 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2022-10-07 22:02:43.884 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = BURGLAR (0)
2022-10-07 22:02:43.884 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-10-07 22:02:43.884 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-10-07 22:02:43.884 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:02:43.884 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:02:43.884 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:02:43.884 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:02:43.884 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:alarm_motion to ON [OnOffType]
2022-10-07 22:02:43.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:02:43.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7435d6c1.
2022-10-07 22:02:43.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:02:43.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:02:43.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:02:43.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:03:32.990 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 1B 09 71 05 00 00 00 FF 07 08 00 62 
2022-10-07 22:03:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:03:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:03:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:03:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:03:32.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:03:32.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-10-07 22:03:32.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_ALARM
2022-10-07 22:03:32.995 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2022-10-07 22:03:32.996 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2022-10-07 22:03:32.996 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = BURGLAR (0)
2022-10-07 22:03:32.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-10-07 22:03:32.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-10-07 22:03:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:03:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:03:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:03:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:03:32.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:alarm_motion to ON [OnOffType]
2022-10-07 22:03:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:03:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3d692322.
2022-10-07 22:03:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:03:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:03:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:03:46.396 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Command received zwave:device:zwave-controller:node4:switch_dimmer --> 87 [PercentType]
2022-10-07 22:03:46.397 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Creating new message for command SWITCH_MULTILEVEL_SET
2022-10-07 22:03:46.397 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-10-07 22:03:46.397 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2022-10-07 22:03:46.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
2022-10-07 22:03:46.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Added 905 to queue - size 7
2022-10-07 22:03:46.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:03:46.397 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 04 03 26 01 57 25 B2 06 
2022-10-07 22:03:46.397 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 26 01 57 25 B2 06 
2022-10-07 22:03:46.399 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-10-07 22:03:46.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:03:46.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:03:46.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:03:46.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-10-07 22:03:46.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:46.403 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2022-10-07 22:03:46.408 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-10-07 22:03:46.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 905: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 178
2022-10-07 22:03:46.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:03:46.409 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 905: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 178
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 905: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 178
2022-10-07 22:03:46.412 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:03:46.412 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 905: Advanced to WAIT_REQUEST
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 905: Transaction not completed
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:46.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:03:46.460 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 B2 00 5B 
2022-10-07 22:03:46.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=178, payload=B2 00 
2022-10-07 22:03:46.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=178, payload=B2 00 
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 905: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 178
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 905: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 178
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 905: (Callback 178)
2022-10-07 22:03:46.465 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 905: callback 178
2022-10-07 22:03:46.465 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=178, payload=B2 00 
2022-10-07 22:03:46.465 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 178, Status = Transmission complete and ACK received(0)
2022-10-07 22:03:46.465 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-10-07 22:03:46.465 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 905: Transaction COMPLETED
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Response processed after 57ms
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 905: Transaction completed
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:905 DONE
2022-10-07 22:03:46.465 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:46.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:03:47.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling...
2022-10-07 22:03:47.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:zwave-controller:node4:switch_dimmer
2022-10-07 22:03:47.909 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 4: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2022-10-07 22:03:47.909 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Creating new message for command SWITCH_MULTILEVEL_GET
2022-10-07 22:03:47.909 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-10-07 22:03:47.909 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2022-10-07 22:03:47.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
2022-10-07 22:03:47.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Added 906 to queue - size 7
2022-10-07 22:03:47.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:03:47.909 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 04 02 26 02 25 B3 51 
2022-10-07 22:03:47.909 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 09 00 13 04 02 26 02 25 B3 51 
2022-10-07 22:03:47.911 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2022-10-07 22:03:47.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:03:47.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2022-10-07 22:03:47.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:03:47.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2022-10-07 22:03:47.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:47.915 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2022-10-07 22:03:47.920 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2022-10-07 22:03:47.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 906: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 179
2022-10-07 22:03:47.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:03:47.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:03:47.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:03:47.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 906: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 179
2022-10-07 22:03:47.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-10-07 22:03:47.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 906: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 179
2022-10-07 22:03:47.924 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2022-10-07 22:03:47.924 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
2022-10-07 22:03:47.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 906: Advanced to WAIT_REQUEST
2022-10-07 22:03:47.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 906: Transaction not completed
2022-10-07 22:03:47.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:47.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:03:47.977 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 B3 00 5A 
2022-10-07 22:03:47.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=179, payload=B3 00 
2022-10-07 22:03:47.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=179, payload=B3 00 
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 906: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 179
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 906: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 179
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 906: (Callback 179)
2022-10-07 22:03:47.982 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 906: callback 179
2022-10-07 22:03:47.982 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=179, payload=B3 00 
2022-10-07 22:03:47.982 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 179, Status = Transmission complete and ACK received(0)
2022-10-07 22:03:47.982 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 906: Advanced to WAIT_DATA
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: TID 906: Transaction not completed
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:47.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2022-10-07 22:03:48.052 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 04 05 26 03 57 57 00 D4 
2022-10-07 22:03:48.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 26 03 57 57 00 
2022-10-07 22:03:48.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 26 03 57 57 00 
2022-10-07 22:03:48.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:03:48.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2022-10-07 22:03:48.058 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-10-07 22:03:48.058 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2022-10-07 22:03:48.058 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-10-07 22:03:48.058 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT
2022-10-07 22:03:48.058 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Switch Multi Level report, value = 87
2022-10-07 22:03:48.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2022-10-07 22:03:48.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=87
2022-10-07 22:03:48.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating channel state zwave:device:zwave-controller:node4:switch_dimmer to 87 [PercentType]
2022-10-07 22:03:48.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2022-10-07 22:03:48.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@12b57e0.
2022-10-07 22:03:48.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@12b57e0.
2022-10-07 22:03:48.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:906 DONE
2022-10-07 22:03:48.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2022-10-07 22:03:48.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2022-10-07 22:03:48.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:03:48.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:48.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:03:52.977 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 1B 06 31 05 03 0A 00 FA 2D 
2022-10-07 22:03:52.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 06 31 05 03 0A 00 FA 
2022-10-07 22:03:52.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 06 31 05 03 0A 00 FA 
2022-10-07 22:03:52.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:03:52.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:03:52.983 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:03:52.983 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2022-10-07 22:03:52.983 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2022-10-07 22:03:52.983 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2022-10-07 22:03:52.983 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 27: Sensor Type = Luminance(3), Scale = 1
2022-10-07 22:03:52.983 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 27: Sensor Value = 2.5E+2
2022-10-07 22:03:52.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2022-10-07 22:03:52.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=2.5E+2
2022-10-07 22:03:52.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:sensor_luminance to 250 lx [QuantityType]
2022-10-07 22:03:52.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:03:52.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@12a51d45.
2022-10-07 22:03:52.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:03:52.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:03:52.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:03:52.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:04:32.991 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 1B 0A 71 05 00 00 00 FF 07 00 01 08 7F 
2022-10-07 22:04:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0A 71 05 00 00 00 FF 07 00 01 08 
2022-10-07 22:04:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 0A 71 05 00 00 00 FF 07 00 01 08 
2022-10-07 22:04:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:04:32.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:04:32.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:04:32.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-10-07 22:04:32.996 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_ALARM
2022-10-07 22:04:32.996 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2022-10-07 22:04:32.996 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1
2022-10-07 22:04:32.996 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = BURGLAR (0)
2022-10-07 22:04:32.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-10-07 22:04:32.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-10-07 22:04:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:04:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 0, type OnOffType
2022-10-07 22:04:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:04:32.996 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 0, type OnOffType
2022-10-07 22:04:32.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:alarm_motion to OFF [OnOffType]
2022-10-07 22:04:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:04:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@487c420e.
2022-10-07 22:04:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:04:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:04:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:04:32.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-10-07 22:05:22.110 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 1B 09 71 05 00 00 00 FF 07 08 00 62 
2022-10-07 22:05:22.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:05:22.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 09 71 05 00 00 00 FF 07 08 00 
2022-10-07 22:05:22.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-10-07 22:05:22.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2022-10-07 22:05:22.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2022-10-07 22:05:22.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2022-10-07 22:05:22.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_ALARM
2022-10-07 22:05:22.115 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V4 NOTIFICATION_REPORT
2022-10-07 22:05:22.115 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2022-10-07 22:05:22.115 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = BURGLAR (0)
2022-10-07 22:05:22.115 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2022-10-07 22:05:22.116 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2022-10-07 22:05:22.116 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:05:22.116 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:05:22.116 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing NOTIFICATION
2022-10-07 22:05:22.116 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter NOTIFICATION event is 8, type OnOffType
2022-10-07 22:05:22.116 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:zwave-controller:node27:alarm_motion to ON [OnOffType]
2022-10-07 22:05:22.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2022-10-07 22:05:22.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3cf80810.
2022-10-07 22:05:22.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:05:22.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-10-07 22:05:22.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-10-07 22:05:22.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

during this period, i manually turned on the switch, then used OH to adjust dimmer, then manually changed dimmer value. i think i only see 1 set of events for the outgoing in the logs.

the lifeline value is set to controller. i’ve had “status” also, but i also tried clearing it to see if it helped but it didn’t.

What is the Zwave Database entry for node 4 (or what is make model, Id numbers in the thing properties)

i just tried removing and adding it again (not exclude/include though) and its still acting the same. i’m tempted to just go through each semi-functional item again one by one and reincluding them. its such a hassle though b/c it took me so long to figure out how to do a factory reset on each and include it to this new zwave dongle.

Z-Wave Node 004: ZEN77 Paddle Dimmer (S2)

    zwave_class_basic
    BASIC_TYPE_ROUTING_SLAVE
    zwave_class_generic
    GENERIC_TYPE_SWITCH_MULTILEVEL
    zwave_frequent
    false
    zwave_neighbours
    1,6,12,13,15,17,28,29,30,31,34
    modelId
    ZEN77
    zwave_listening
    true
    zwave_version
    10.0
    zwave_plus_devicetype
    NODE_TYPE_ZWAVEPLUS_NODE
    manufacturerId
    027A
    manufacturerRef
    7000:A007
    dbReference
    1345
    zwave_deviceid
    40967
    zwave_nodeid
    4
    zwave_lastheal
    2022-10-08T02:59:16Z
    vendor
    Zooz
    defaultAssociations
    1
    zwave_routing
    true
    zwave_plus_roletype
    ROLE_TYPE_SLAVE_ALWAYS_ON
    zwave_beaming
    true
    zwave_secure
    false
    zwave_class_specific
    SPECIFIC_TYPE_POWER_SWITCH_MULTILEVEL
    zwave_devicetype
    28672
    zwave_manufacturer
    634

A couple of questions-focusing on Node 4

  1. Has this device ever reported changes from manual operations?
  2. What is parameter 15? (manual operation disabled?) :wink:

There is one anomaly in the XML generated by the database, but I do not know if it is relevant. However, it (adding the BASIC CC) did fix a problem I had with manual operation reporting of a Zooz switch (not dimmer) back to OH a long time ago. I need to flag @chris for comment. Also I do not know how to add this, if it does make a difference. It is probably only relevant if the answer to question #1 is no, since it appears it was always set-up this way.

yours (Zen77)
<property name="binding:*:PercentType">COMMAND_CLASS_SWITCH_MULTILEVEL</property>
another zooz dimmer (ZEN24)
<property name="binding:*:PercentType">COMMAND_CLASS_SWITCH_MULTILEVEL,COMMAND_CLASS_BASIC</property>

You just tick the Basic box in the database for that channel.

It might help since the device is sending BASIC reports in the image above, although apparently not in the text log, so who knows.

However looking at the log there is something else wrong with the device as it’s flooding the network by sending these reports every 20 milliseconds. This will likely cause problems with devices reporting when this happens.

If a device isn’t sending reports, then it’s likely a problem with association configuration. If a device is occasionally sending reports, then there’s likely a problem with the network that prevents data being received. This could be an RF problem, or devices flooding the network (as we see above) - it’s impossible to say but if it works more than once, then probably it’s not configuration.

1 Like

Thanks. Found it, I was looking at the dimmer, not the channel. It appears changed from the XML export, but oddly the device is not shown as modified and the request review button did not pop up. Are there changes so minor that that is not triggered?

I don’t think this could hurt and I noticed all the basic sets in one of the pictures too.

1 Like

No - in theory any change someone makes marks the entry as “dirty” and requires approval.

So all my devices were added to OH before on my old setup where the controller stopped sending out messages.

In this new setup, I’m running it in docker and initially I was running it on my laptop and I was copying around the “userdata” folder. I’ve since moved it to the computer I intend to keep it on and I made sure that I copied all of the data across but I wonder if the things not properly syncing were those I added when I ran it on my laptop. I did this since it was easier to “move it closer” as I did the factory reset and included again.

I bring this up because I notice node 26 has neighbors: 22, 23. Then 22 has neighbors: 23, 26, and as expected, node 23 has 22 and 26. Shouldn’t at least one of those lead back to node 1? In the graph, the edge from 1 to 23 says “unidirectional”

image

oh, wait a second, i think i have a breakthrough. i hit the ‘reinitialize the device’ on a few things including the node 4 i was testing with (b/c it was fewest steps to walk to test) and it seems to be updating in OH now. i’ll verify the rest tomorrow as it doesn’t seem all zwave things show this link.