Z-Way binding pollingIntervall (NeoDoorWindowSensor)

Hi,

I have a Neo DoorWindow Sensor which works well in Z-Way.
I use the openhab z-way binding and added the thing.
Changes to the door sensor are not directly send to openhab. I don’t see the status in openhab.
What works is to reduce the z-way server pollingIntervall to 60s. With this, I have a max of 60s until I see the correct state in openhab. I assume openhab is polling the state.

Questions:
a) isn’t there a better way then polling? Why don’t I get a state change event from zway send to openhabe?
b) 60s is the lowest pollingInterval. I would like to be informed directly. Can the pollingInterval be reduced? What are the implications?

Tx
Jean-Marc

a) There should be but I don’t know the Z-way binding except that a lot of users abandon it because it’s slow and flakey. In the Zwave binding you would set the lifeline parameter to the controller, or set the association group to the Controller.

b) Yes, I believe it is. Anything faster and you will quickly overwhelm your zwave network.

I don’t know anything about zway other than the complaints people have had in the forum as they are migrating to the zwave binding. But it sounds like your device is not configured properly… possibly missing the association to the controller, but some devices have configuration parameters that will will effect this too. If this is your device, it looks like parameter 2 or 3 would be a place to start.

This will drain a battery device very quickly!

1 Like

Thanks for the responses.
I actually had much better experience from the zwave network point of view with zway compared to the zwave binding, this is why I still use it.
But I will give zwave binding another try. Maybe it will work better this time.

So I switched to zwave binding. Excldued all in zway, uninstalled zway, hard reset the controler, added the contoler in zwave-binding, included the devices.
I have 4 devices: a simple plug, a fibaro plug, a fibaro smoke sensor and the Neo Door Sensor (mentioned above).
All could be included, and have been recognized (at least I saw the name in the UI).

  • The 2 plugs seems to work.
  • But I get no data from the smoke sensor (worked well using zway + binding) and not from the Neo Door sensor (worked in zway, but no data using zway-binding).

Btw: in habmin I see nothing of my zwave network.

What can I do further?

This is OK, but you made work for yourself. The devices are stored in the controller, so there was no need to exclude and reinclude them.

This is likely due to them being battery powered and not having completed initialization. The initialization should complete after the device wakes up a few times. Depending on the wakeup interval, this can take a while. To speed up the process, manually wake the devices up. This is usually the same procedure as what you did for inclusion… like pressing a button. You can find this information in the device’s manual or in the device database for your devices.

Screenshot? You should find them under Configuration> Things.

Yes, I see them as things in hadmin.

Here for example the smoke sensor:
image

I woke it up several times, but no new data.
Wakeup interval is 14400, polling interval is one day.

Saw that there is no association group
image

I set them all to “controller”, then woke uo the devices again, but still same result.

On the controller, the default wake up time is one hour
image

This is the default wakeup interval that all battery devices will be set to. You can change this on individual devices.

Polling interval is irrelevant here.

So, 4 hours. This is strange if the default is 1 hour, unless you’ve manually changed it. But maybe this is set later in the initialization.

What do you mean by “No new data”? Try tailing the log file with the binding in debug while waking up the device. The lifeline association will be forced by the binding, so you should not have to manually set this. Your screenshot shows the smoke detector is initializing and at the stage of SET_ASSOCIATION, so the binding has tried to set the association and is waiting for the device to respond. Have you made progress with your door sensor?

Here is the log when I wake up the smoke sensor.

11:08:41.564 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 49 84 04 0E 04 A1 02 9C 31 86 72 70 85 8E 8B 56 84 80 70
11:08:41.611 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=4, callback=132, payload=84 04 0E 04 A1 02 9C 31 86 72 70 85 8E 8B 56 84 80
11:08:41.622 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=4, callback=132, payload=84 04 0E 04 A1 02 9C 31 86 72 70 85 8E 8B 56 84 80
11:08:41.631 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
11:08:41.652 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
11:08:41.659 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
11:08:41.672 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=4, callback=132, payload=84 04 0E 04 A1 02 9C 31 86 72 70 85 8E 8B 56 84 80
11:08:41.682 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 4: Application update request. Node information received. Transaction null
11:08:41.691 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 4: Application update - no transaction.
11:08:41.703 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:41.716 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:08:41.953 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Is awake with 5 messages in the queue
11:08:41.963 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Start sleep timer at 5000ms
11:08:41.974 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveNodeStatusEvent
11:08:42.024 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 4: Node Status event - Node is AWAKE
11:08:44.473 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: WakeupTimerTask 5 Messages waiting, state GET_CONFIGURATION
11:08:46.974 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: WakeupTimerTask 5 Messages waiting, state GET_CONFIGURATION
11:08:46.983 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: No more messages, go back to sleep
11:08:46.992 [DEBUG] [.commandclass.ZWaveWakeUpCommandClass] - NODE 4: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
11:08:47.002 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
11:08:47.012 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
11:08:47.024 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1e8c7df
11:08:47.035 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Bump transaction 156 priority from Immediate to Immediate
11:08:47.059 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
11:08:47.069 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Added 156 to queue - size 7
11:08:47.078 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:08:47.107 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 04 03 70 05 07 25 86 30
11:08:47.118 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 70 05 07 25 86 30
11:08:47.132 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:08:47.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:47.149 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:08:47.154 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:47.166 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 151: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 134
11:08:47.159 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
11:08:47.166 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
11:08:47.188 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:08:47.190 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:47.196 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:47.204 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 86 00 00 04 69
11:08:47.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:08:47.221 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=134, payload=86 00 00 04
11:08:47.227 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:47.240 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 151: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 134
11:08:47.247 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 04 05 70 06 07 01 FF 7E
11:08:47.264 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:47.282 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 151: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 134
11:08:47.293 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:47.301 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 70 06 07 01 FF
11:08:47.306 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
11:08:47.318 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 151: Advanced to WAIT_REQUEST
11:08:47.326 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 151: Transaction not completed
11:08:47.337 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=134, payload=86 00 00 04
11:08:47.345 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 151: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 134
11:08:47.355 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:47.363 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 151: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 134
11:08:47.373 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 151: (Callback 134)
11:08:47.381 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:08:47.392 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 151: callback 134
11:08:47.402 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=134, payload=86 00 00 04
11:08:47.412 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 134, Status = Transmission complete and ACK received(0)
11:08:47.419 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 151: Advanced to WAIT_DATA
11:08:47.428 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 151: Transaction not completed
11:08:47.437 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 70 06 07 01 FF
11:08:47.446 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
11:08:47.453 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:GET_CONFIGURATION)
11:08:47.462 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_CONFIGURATION, endpoint 0
11:08:47.469 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
11:08:47.477 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_CONFIGURATION V1 CONFIGURATIONCMD_REPORT
11:08:47.485 [DEBUG] [dclass.ZWaveConfigurationCommandClass] - NODE 4: Node configuration report, parameter = 7, value = -1, size = 1
11:08:47.494 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
11:08:47.502 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_CONFIGURATION, value=org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@16239e1
11:08:47.512 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Update CONFIGURATION 7/1 to -1
11:08:47.520 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
11:08:47.528 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6cddbe.
11:08:47.535 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6cddbe.
11:08:47.543 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:151 DONE
11:08:47.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 151: Transaction event listener: DONE: DONE ->
11:08:47.551 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
11:08:47.567 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 4: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@76e5a8
11:08:47.570 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
11:08:47.577 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 4: Node Init transaction completed with response COMPLETE
11:08:47.583 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
11:08:47.590 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 4: Node advancer: GET_CONFIGURATION - checking config_10_2 - config
11:08:47.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:47.600 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 4: Node advancer: GET_CONFIGURATION - checking config_10_2 - index 10
11:08:47.605 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:08:47.613 [DEBUG] [dclass.ZWaveConfigurationCommandClass] - NODE 4: Creating new message for application command CONFIGURATIONCMD_GET
11:08:47.620 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 04 03 85 04 01 25 87 C3
11:08:47.625 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 4: ZWaveCommandClassTransactionPayload - send to node
11:08:47.632 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 85 04 01 25 87 C3
11:08:47.637 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
11:08:47.646 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:08:47.660 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:47.666 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
11:08:47.669 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:08:47.680 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@b70157
11:08:47.691 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
11:08:47.694 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 152: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 135
11:08:47.699 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Bump transaction 157 priority from Config to Immediate
11:08:47.710 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:47.719 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
11:08:47.722 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 152: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 135
11:08:47.728 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Added 157 to queue - size 6
11:08:47.735 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:08:47.742 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:47.741 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:08:47.749 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:47.764 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 87 00 00 04 68
11:08:47.776 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:08:47.792 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:47.805 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 152: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 135
11:08:47.814 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:47.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=135, payload=87 00 00 04
11:08:47.824 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 152: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 135
11:08:47.844 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:47.854 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
11:08:47.863 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 152: Advanced to WAIT_REQUEST
11:08:47.873 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 152: Transaction not completed
11:08:47.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=135, payload=87 00 00 04
11:08:47.890 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 152: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 135
11:08:47.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:47.902 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 152: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 135
11:08:47.911 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 152: (Callback 135)
11:08:47.918 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:08:47.926 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 152: callback 135
11:08:47.934 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=135, payload=87 00 00 04
11:08:47.942 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 135, Status = Transmission complete and ACK received(0)
11:08:47.949 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 152: Transaction COMPLETED
11:08:47.958 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Response processed after 264ms
11:08:47.964 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 152: Transaction completed
11:08:47.973 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:152 DONE
11:08:47.983 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
11:08:47.991 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:47.999 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:08:48.011 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 04 03 85 02 01 25 88 CA
11:08:48.021 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 85 02 01 25 88 CA
11:08:48.044 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:08:48.054 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:48.059 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:08:48.069 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 153: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 136
11:08:48.078 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
11:08:48.102 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:48.116 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 153: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 136
11:08:48.125 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:08:48.133 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:48.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.150 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:08:48.157 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 88 00 00 04 67
11:08:48.168 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.185 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 153: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 136
11:08:48.198 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:48.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 153: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 136
11:08:48.210 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.212 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=136, payload=88 00 00 04
11:08:48.220 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
11:08:48.233 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 153: Advanced to WAIT_REQUEST
11:08:48.240 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 153: Transaction not completed
11:08:48.245 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 04 05 85 03 01 05 00 73
11:08:48.248 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=136, payload=88 00 00 04
11:08:48.276 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 153: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 136
11:08:48.268 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 85 03 01 05 00
11:08:48.285 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:48.301 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 153: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 136
11:08:48.322 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 153: (Callback 136)
11:08:48.330 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:08:48.337 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 153: callback 136
11:08:48.344 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=136, payload=88 00 00 04
11:08:48.351 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 136, Status = Transmission complete and ACK received(0)
11:08:48.358 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 153: Advanced to WAIT_DATA
11:08:48.364 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 153: Transaction not completed
11:08:48.373 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 85 03 01 05 00
11:08:48.381 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
11:08:48.388 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:GET_CONFIGURATION)
11:08:48.396 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0
11:08:48.402 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
11:08:48.408 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT
11:08:48.416 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 4: association group 1 has max associations 5
11:08:48.423 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 4: association group 1 includes the following nodes:
11:08:48.430 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveAssociationEvent
11:08:48.438 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ASSOCIATION, value=0
11:08:48.446 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
11:08:48.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@e1cc2.
11:08:48.460 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@e1cc2.
11:08:48.467 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:153 DONE
11:08:48.481 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
11:08:48.489 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
11:08:48.497 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
11:08:48.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:48.511 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:08:48.520 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 04 03 85 04 02 25 89 CE
11:08:48.528 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 85 04 02 25 89 CE
11:08:48.541 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:08:48.552 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:48.560 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:08:48.569 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 154: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 137
11:08:48.578 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:48.586 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 154: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 137
11:08:48.582 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
11:08:48.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:08:48.603 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.607 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:48.623 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 89 00 00 04 66
11:08:48.633 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=137, payload=89 00 00 04
11:08:48.628 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:08:48.649 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.657 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 154: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 137
11:08:48.667 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:48.675 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 154: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 137
11:08:48.685 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.693 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
11:08:48.699 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 154: Advanced to WAIT_REQUEST
11:08:48.706 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 154: Transaction not completed
11:08:48.713 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=137, payload=89 00 00 04
11:08:48.718 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 154: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 137
11:08:48.724 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:48.729 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 154: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 137
11:08:48.736 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 154: (Callback 137)
11:08:48.742 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:08:48.748 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 154: callback 137
11:08:48.754 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=137, payload=89 00 00 04
11:08:48.760 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 137, Status = Transmission complete and ACK received(0)
11:08:48.767 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 154: Transaction COMPLETED
11:08:48.773 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Response processed after 203ms
11:08:48.779 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 154: Transaction completed
11:08:48.785 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:154 DONE
11:08:48.794 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
11:08:48.800 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:48.805 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:08:48.813 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 04 03 85 02 02 25 8A CB
11:08:48.820 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 85 02 02 25 8A CB
11:08:48.831 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:08:48.840 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:48.849 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:08:48.857 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 155: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 138
11:08:48.854 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
11:08:48.866 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:48.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 155: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 138
11:08:48.891 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:08:48.900 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.906 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:48.913 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8A 00 00 04 65
11:08:48.916 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:08:48.931 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.938 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 155: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 138
11:08:48.944 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:48.950 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 155: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 138
11:08:48.950 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=138, payload=8A 00 00 04
11:08:48.957 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:48.966 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 04 05 85 03 02 05 00 70
11:08:48.969 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
11:08:48.993 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 155: Advanced to WAIT_REQUEST
11:08:49.001 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 155: Transaction not completed
11:08:49.008 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=138, payload=8A 00 00 04
11:08:49.016 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 155: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 138
11:08:49.020 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 85 03 02 05 00
11:08:49.030 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:49.036 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 155: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 138
11:08:49.042 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 155: (Callback 138)
11:08:49.047 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:08:49.054 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 155: callback 138
11:08:49.060 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=138, payload=8A 00 00 04
11:08:49.067 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 138, Status = Transmission complete and ACK received(0)
11:08:49.073 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 155: Advanced to WAIT_DATA
11:08:49.079 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 155: Transaction not completed
11:08:49.091 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 05 85 03 02 05 00
11:08:49.097 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
11:08:49.103 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:GET_CONFIGURATION)
11:08:49.110 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0
11:08:49.116 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
11:08:49.122 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT
11:08:49.128 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 4: association group 2 has max associations 5
11:08:49.134 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 4: association group 2 includes the following nodes:
11:08:49.139 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveAssociationEvent
11:08:49.146 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ASSOCIATION, value=0
11:08:49.152 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
11:08:49.157 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1aa2fba.
11:08:49.163 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1aa2fba.
11:08:49.169 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:155 DONE
11:08:49.176 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
11:08:49.189 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
11:08:49.194 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
11:08:49.201 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:49.218 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
11:08:49.227 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 04 02 84 08 25 8B C1
11:08:49.236 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 09 00 13 04 02 84 08 25 8B C1
11:08:49.248 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
11:08:49.257 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:49.269 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
11:08:49.277 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 156: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
11:08:49.282 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
11:08:49.291 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
11:08:49.296 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:49.300 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 156: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
11:08:49.310 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
11:08:49.318 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:49.323 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 156: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
11:08:49.330 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:49.335 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 156: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
11:08:49.348 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
11:08:49.362 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: sentData successfully placed on stack.
11:08:49.383 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 156: Advanced to WAIT_REQUEST
11:08:49.390 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 156: Transaction not completed
11:08:49.397 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:49.403 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
11:08:49.459 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8B 00 00 14 74
11:08:49.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=139, payload=8B 00 00 14
11:08:49.475 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=139, payload=8B 00 00 14
11:08:49.482 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 156: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 139
11:08:49.489 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
11:08:49.494 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 156: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 139
11:08:49.501 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 156: (Callback 139)
11:08:49.506 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
11:08:49.513 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 156: callback 139
11:08:49.520 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=139, payload=8B 00 00 14
11:08:49.527 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 139, Status = Transmission complete and ACK received(0)
11:08:49.532 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 156: Transaction COMPLETED
11:08:49.539 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: Response processed after 262ms
11:08:49.544 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: TID 156: Transaction completed
11:08:49.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:156 DONE
11:08:49.557 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
11:08:49.558 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 156: Transaction event listener: DONE: DONE ->
11:08:49.570 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
11:08:49.581 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 4: Went to sleep COMPLETE
11:08:49.584 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Still same situation with door sensor than with the smoke sensor.

Not sure it is relevant, but during inclusion I saw the following in the log file:
Configuration update ignored config_73_2 to 0 (BigDecimal)

I waited 1 day, and now I see values for the smoke sensor.
image

I added door sensor as well and will wait. Maybe it will work as well.

The question is, how accurate the values will be at the end. Specially for door sensor the state should be updated within seconds…

I can’t speak for these specific devices but in general something like a smoke alarm or a temperature sensor will wake itself up periodically (usually a matter of hours and sometimes days) and report the latest values. But when there is an event that needs to be immediately reported, the device wakes itself up and immediately reports that event, e.g. a door opened or smoke was detected.

1 Like

Thanks. This is what I would expect.

I updated to 2.5M3 yesteray and tried zwave binding again.
Both smoke and door sensort are working :slightly_smiling_face:

But my 3 dumb power switches (2 TZ68G and one Fibaro WallPlug) now don’t communicate at all with zwave. I have debug on, trying to see any events (incl. inclusion, exclusion), but nothing :frowning: