18:27:12.754 [INFO ] [smarthome.event.ItemCommandEvent     ] - Item 'POPP_Smoke_1_Sw1' received command ON
18:27:12.757 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Command received zwave:device:e8445cf8:node3:switch_binary --> ON [OnOffType]
18:27:12.759 [INFO ] [smarthome.event.ItemStateChangedEvent] - POPP_Smoke_1_Sw1 changed from OFF to ON
18:27:12.761 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_SET
18:27:12.763 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:12.765 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:12.767 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 9175 priority from Set to Immediate
18:27:12.770 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
18:27:12.772 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Added 9175 to queue - size 11
18:27:12.774 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:12.777 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 03 03 25 01 FF 25 9E 86
18:27:12.779 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0A 00 13 03 03 25 01 FF 25 9E 86
18:27:12.782 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:12.782 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:12.783 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 158
18:27:12.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:12.787 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling intialised at 86400 seconds - start in 1500 milliseconds.
18:27:12.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:12.789 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 158
18:27:12.789 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:12.790 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:12.790 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:12.791 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:12.791 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:12.792 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:12.792 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 158
18:27:12.793 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:12.794 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 158
18:27:12.795 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:12.796 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
18:27:12.797 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9175: Advanced to WAIT_REQUEST
18:27:12.798 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9175: Transaction not completed
18:27:12.799 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:12.800 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.063 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 9E 00 00 80 F5
18:27:14.065 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 80
18:27:14.067 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 80
18:27:14.068 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9175: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 158
18:27:14.069 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:14.072 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9175: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 158
18:27:14.072 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9175: (Callback 158)
18:27:14.074 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:14.074 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 FF 2B
18:27:14.076 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9175: callback 158
18:27:14.077 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF
18:27:14.078 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=158, payload=9E 00 00 80
18:27:14.080 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 158, Status = Transmission complete and ACK received(0)
18:27:14.082 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:14.084 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 9175: Transaction COMPLETED
18:27:14.085 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Response processed after 1302ms
18:27:14.087 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9175: Transaction completed
18:27:14.089 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:9175 DONE
18:27:14.091 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:14.093 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF
18:27:14.095 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:14.097 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
18:27:14.099 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:14.101 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:14.102 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:14.104 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:27:14.106 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 255
18:27:14.108 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:14.109 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255
18:27:14.110 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:e8445cf8:node3:switch_binary to ON [OnOffType]
18:27:14.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
18:27:14.113 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@77c39846.
18:27:14.114 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:14.115 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:14.116 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.117 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:14.288 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...
18:27:14.289 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:e8445cf8:node3:sensor_binary
18:27:14.290 [DEBUG] [.converter.ZWaveBinarySensorConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:14.291 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 3: Creating new message for application command SENSOR_BINARY_GET
18:27:14.292 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:14.293 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured
18:27:14.294 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:e8445cf8:node3:alarm_general
18:27:14.295 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:e8445cf8:node3:battery-level
18:27:14.296 [DEBUG] [ernal.converter.ZWaveBatteryConverter] - NODE 3: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0
18:27:14.298 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:14.299 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured
18:27:14.300 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 9176 priority from Get to Immediate
18:27:14.301 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
18:27:14.302 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Added 9176 to queue - size 11
18:27:14.303 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:14.304 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 30 02 25 9F 6C
18:27:14.306 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 30 02 25 9F 6C
18:27:14.307 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:14.307 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:14.308 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9176: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159
18:27:14.309 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:14.309 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 9177 priority from Get to Immediate
18:27:14.310 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:14.310 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
18:27:14.311 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9176: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159
18:27:14.311 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Added 9177 to queue - size 11
18:27:14.311 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:14.312 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.312 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:14.312 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.313 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.313 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:14.315 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:14.315 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9176: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159
18:27:14.316 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:14.317 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9176: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 159
18:27:14.318 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:14.319 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
18:27:14.320 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9176: Advanced to WAIT_REQUEST
18:27:14.321 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9176: Transaction not completed
18:27:14.321 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.322 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.331 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 9F 00 00 02 76
18:27:14.332 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 02
18:27:14.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 02
18:27:14.334 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9176: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 159
18:27:14.335 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:14.336 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9176: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 159
18:27:14.337 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9176: (Callback 159)
18:27:14.338 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:14.339 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9176: callback 159
18:27:14.340 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=159, payload=9F 00 00 02
18:27:14.341 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 159, Status = Transmission complete and ACK received(0)
18:27:14.342 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:14.343 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9176: Advanced to WAIT_DATA
18:27:14.344 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 03 04 30 03 FF 02 38
18:27:14.345 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9176: Transaction not completed
18:27:14.345 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 04 30 03 FF 02
18:27:14.346 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.347 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.348 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 04 30 03 FF 02
18:27:14.349 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:14.350 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
18:27:14.351 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:14.352 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:14.353 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:14.354 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
18:27:14.355 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 2
18:27:14.356 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is SMOKE
18:27:14.357 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 3: Sensor Binary report, type=Smoke, value=255
18:27:14.359 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
18:27:14.360 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 255
18:27:14.362 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:e8445cf8:node3:sensor_binary to ON [OnOffType]
18:27:14.364 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
18:27:14.366 [INFO ] [smarthome.event.ItemStateChangedEvent] - POPP_Smoke_1_A changed from NULL to ON
18:27:14.367 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@62b75ed2.
18:27:14.370 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@62b75ed2.
18:27:14.371 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:9176 DONE
18:27:14.372 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:14.373 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:14.374 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:14.375 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.376 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:14.377 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 80 02 25 A0 E3
18:27:14.378 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 80 02 25 A0 E3
18:27:14.379 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:14.380 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:14.380 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9177: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 160
18:27:14.381 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:14.382 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:14.383 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9177: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 160
18:27:14.384 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:14.384 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:14.385 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.386 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:14.386 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.387 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:14.388 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9177: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 160
18:27:14.389 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:14.390 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9177: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 160
18:27:14.391 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:14.392 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
18:27:14.393 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9177: Advanced to WAIT_REQUEST
18:27:14.394 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9177: Transaction not completed
18:27:14.395 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.396 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.402 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A0 00 00 02 49
18:27:14.404 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 02
18:27:14.405 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 02
18:27:14.406 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9177: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 160
18:27:14.407 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:14.408 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9177: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 160
18:27:14.409 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9177: (Callback 160)
18:27:14.410 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:14.411 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9177: callback 160
18:27:14.412 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=160, payload=A0 00 00 02
18:27:14.412 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 160, Status = Transmission complete and ACK received(0)
18:27:14.413 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:14.414 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9177: Advanced to WAIT_DATA
18:27:14.415 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 80 03 64 15
18:27:14.415 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9177: Transaction not completed
18:27:14.416 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 80 03 64
18:27:14.416 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.418 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:14.419 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 80 03 64
18:27:14.420 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:14.420 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
18:27:14.421 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:14.422 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
18:27:14.423 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:14.424 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
18:27:14.425 [DEBUG] [commandclass.ZWaveBatteryCommandClass] - NODE 3: Battery report value = 100
18:27:14.426 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:14.427 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BATTERY, value = 100
18:27:14.428 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:e8445cf8:node3:battery-level to 100 [DecimalType]
18:27:14.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
18:27:14.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2a24d8c5.
18:27:14.431 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2a24d8c5.
18:27:14.432 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:9177 DONE
18:27:14.434 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:14.435 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:14.435 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:14.436 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:14.437 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:16.582 [INFO ] [smarthome.event.ItemCommandEvent     ] - Item 'POPP_Smoke_1_Sw1' received command OFF
18:27:16.585 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Command received zwave:device:e8445cf8:node3:switch_binary --> OFF [OnOffType]
18:27:16.587 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_SET
18:27:16.590 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:16.591 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:16.593 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 9178 priority from Set to Immediate
18:27:16.594 [INFO ] [smarthome.event.ItemStateChangedEvent] - POPP_Smoke_1_Sw1 changed from ON to OFF
18:27:16.595 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
18:27:16.597 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Added 9178 to queue - size 11
18:27:16.599 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:16.601 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 03 03 25 01 00 25 A1 46
18:27:16.602 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0A 00 13 03 03 25 01 00 25 A1 46
18:27:16.604 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:16.605 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:16.606 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9178: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 161
18:27:16.607 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:16.608 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling intialised at 86400 seconds - start in 1500 milliseconds.
18:27:16.609 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:16.610 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:16.610 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9178: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 161
18:27:16.611 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:16.612 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:16.613 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:16.614 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9178: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 161
18:27:16.615 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:16.616 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9178: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 161
18:27:16.618 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:16.619 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
18:27:16.621 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9178: Advanced to WAIT_REQUEST
18:27:16.622 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9178: Transaction not completed
18:27:16.624 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:16.625 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:16.629 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A1 00 00 02 48
18:27:16.630 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=161, payload=A1 00 00 02
18:27:16.631 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=161, payload=A1 00 00 02
18:27:16.632 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9178: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 161
18:27:16.633 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:16.634 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9178: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 161
18:27:16.635 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9178: (Callback 161)
18:27:16.636 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:16.637 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9178: callback 161
18:27:16.638 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=161, payload=A1 00 00 02
18:27:16.639 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 161, Status = Transmission complete and ACK received(0)
18:27:16.640 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:16.641 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 9178: Transaction COMPLETED
18:27:16.641 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 00 D4
18:27:16.642 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Response processed after 36ms
18:27:16.643 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 00
18:27:16.643 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9178: Transaction completed
18:27:16.645 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:9178 DONE
18:27:16.646 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:16.647 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 00
18:27:16.648 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:16.649 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
18:27:16.650 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:16.651 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:16.652 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:16.653 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:27:16.654 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 0
18:27:16.655 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:16.656 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 0
18:27:16.657 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:e8445cf8:node3:switch_binary to OFF [OnOffType]
18:27:16.658 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
18:27:16.659 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2039eb04.
18:27:16.660 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:16.661 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:16.662 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:16.663 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:18.108 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...
18:27:18.109 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:e8445cf8:node3:sensor_binary
18:27:18.110 [DEBUG] [.converter.ZWaveBinarySensorConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:18.111 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 3: Creating new message for application command SENSOR_BINARY_GET
18:27:18.112 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:18.113 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured
18:27:18.114 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:e8445cf8:node3:alarm_general
18:27:18.115 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:e8445cf8:node3:battery-level
18:27:18.116 [DEBUG] [ernal.converter.ZWaveBatteryConverter] - NODE 3: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0
18:27:18.117 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:18.118 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured
18:27:18.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 9179 priority from Get to Immediate
18:27:18.120 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
18:27:18.121 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Added 9179 to queue - size 11
18:27:18.122 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:18.123 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 30 02 25 A2 51
18:27:18.124 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 30 02 25 A2 51
18:27:18.126 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:18.126 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:18.127 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9179: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 162
18:27:18.127 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:18.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 9180 priority from Get to Immediate
18:27:18.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:18.129 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue
18:27:18.129 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9179: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 162
18:27:18.130 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Added 9180 to queue - size 11
18:27:18.130 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:18.130 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.130 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:18.131 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.132 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.132 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.133 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.134 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9179: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 162
18:27:18.135 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:18.136 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9179: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 162
18:27:18.137 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.138 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
18:27:18.139 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9179: Advanced to WAIT_REQUEST
18:27:18.140 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9179: Transaction not completed
18:27:18.140 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.141 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.150 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A2 00 00 02 4B
18:27:18.151 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=162, payload=A2 00 00 02
18:27:18.152 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=162, payload=A2 00 00 02
18:27:18.153 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9179: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 162
18:27:18.154 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:18.155 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9179: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 162
18:27:18.156 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9179: (Callback 162)
18:27:18.157 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:18.158 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9179: callback 162
18:27:18.159 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=162, payload=A2 00 00 02
18:27:18.159 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 162, Status = Transmission complete and ACK received(0)
18:27:18.160 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:18.162 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9179: Advanced to WAIT_DATA
18:27:18.163 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9179: Transaction not completed
18:27:18.163 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 03 04 30 03 00 02 C7
18:27:18.164 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.164 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 04 30 03 00 02
18:27:18.164 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.166 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 04 30 03 00 02
18:27:18.167 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:18.168 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
18:27:18.168 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:18.169 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:18.170 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:18.171 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
18:27:18.172 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 2
18:27:18.173 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is SMOKE
18:27:18.174 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 3: Sensor Binary report, type=Smoke, value=0
18:27:18.175 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
18:27:18.176 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 0
18:27:18.177 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:e8445cf8:node3:sensor_binary to OFF [OnOffType]
18:27:18.178 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
18:27:18.179 [INFO ] [smarthome.event.ItemStateChangedEvent] - POPP_Smoke_1_A changed from ON to OFF
18:27:18.180 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4b5346ed.
18:27:18.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4b5346ed.
18:27:18.182 [INFO ] [smarthome.event.ItemCommandEvent     ] - Item 'POPP_Smoke_5_Sw' received command OFF
18:27:18.184 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Command received zwave:device:e8445cf8:node7:switch_binary --> OFF [OnOffType]
18:27:18.184 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:9179 DONE
18:27:18.185 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_SET
18:27:18.185 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:18.186 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:18.187 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:18.187 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:18.188 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:18.188 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9181 priority from Set to Immediate
18:27:18.189 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.190 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:18.191 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9181 to queue - size 12
18:27:18.192 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:18.193 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 80 02 25 A3 E0
18:27:18.193 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 80 02 25 A3 E0
18:27:18.194 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:18.195 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:18.195 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9180: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 163
18:27:18.196 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:18.196 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.197 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:18.198 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling intialised at 86400 seconds - start in 1500 milliseconds.
18:27:18.198 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9180: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 163
18:27:18.199 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:18.199 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:18.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.202 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.203 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.203 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9180: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 163
18:27:18.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:18.205 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9180: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 163
18:27:18.206 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.207 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.
18:27:18.208 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9180: Advanced to WAIT_REQUEST
18:27:18.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9180: Transaction not completed
18:27:18.209 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.210 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.218 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A3 00 00 02 4A
18:27:18.220 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=163, payload=A3 00 00 02
18:27:18.221 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=163, payload=A3 00 00 02
18:27:18.222 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9180: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 163
18:27:18.222 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:18.223 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9180: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 163
18:27:18.224 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9180: (Callback 163)
18:27:18.225 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:18.226 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9180: callback 163
18:27:18.227 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=163, payload=A3 00 00 02
18:27:18.228 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 163, Status = Transmission complete and ACK received(0)
18:27:18.229 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:18.230 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9180: Advanced to WAIT_DATA
18:27:18.231 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 80 03 64 15
18:27:18.231 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: TID 9180: Transaction not completed
18:27:18.233 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.233 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 80 03 64
18:27:18.233 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.235 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 80 03 64
18:27:18.236 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:18.237 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)
18:27:18.237 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false
18:27:18.238 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
18:27:18.239 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:18.240 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
18:27:18.241 [DEBUG] [commandclass.ZWaveBatteryCommandClass] - NODE 3: Battery report value = 100
18:27:18.242 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:18.243 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BATTERY, value = 100
18:27:18.244 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:e8445cf8:node3:battery-level to 100 [DecimalType]
18:27:18.246 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
18:27:18.249 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@134ac09b.
18:27:18.250 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@134ac09b.
18:27:18.251 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:9180 DONE
18:27:18.253 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:18.253 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:18.254 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:18.255 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.256 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:18.258 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 07 03 25 01 00 25 A4 47
18:27:18.259 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 0A 00 13 07 03 25 01 00 25 A4 47
18:27:18.260 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:18.261 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:18.261 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9181: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 164
18:27:18.262 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:18.263 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:18.264 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9181: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 164
18:27:18.265 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:18.265 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:18.266 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.267 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:18.267 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.268 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.269 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9181: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 164
18:27:18.270 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:18.271 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9181: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 164
18:27:18.272 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:18.273 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:18.274 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9181: Advanced to WAIT_REQUEST
18:27:18.275 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9181: Transaction not completed
18:27:18.276 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:18.277 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.497 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A4 00 00 7C 33
18:27:19.499 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=164, payload=A4 00 00 7C
18:27:19.501 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=164, payload=A4 00 00 7C
18:27:19.502 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9181: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 164
18:27:19.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:19.505 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9181: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 164
18:27:19.507 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9181: (Callback 164)
18:27:19.508 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:19.509 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9181: callback 164
18:27:19.511 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=164, payload=A4 00 00 7C
18:27:19.512 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 164, Status = Transmission complete and ACK received(0)
18:27:19.514 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:19.515 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 9181: Transaction COMPLETED
18:27:19.516 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Response processed after 1255ms
18:27:19.518 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9181: Transaction completed
18:27:19.519 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9181 DONE
18:27:19.521 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:19.522 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.523 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:19.699 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling...
18:27:19.700 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:switch_binary
18:27:19.700 [DEBUG] [.converter.ZWaveBinarySwitchConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:19.701 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_GET
18:27:19.702 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:19.703 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:19.704 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling skipped for zwave:device:e8445cf8:node7:switch_binary on COMMAND_CLASS_BASIC
18:27:19.705 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:sensor_binary
18:27:19.706 [DEBUG] [.converter.ZWaveBinarySensorConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:19.707 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Creating new message for application command SENSOR_BINARY_GET
18:27:19.707 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:19.708 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured
18:27:19.709 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:alarm_general
18:27:19.710 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:battery-level
18:27:19.711 [DEBUG] [ernal.converter.ZWaveBatteryConverter] - NODE 7: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0
18:27:19.712 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:19.713 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured
18:27:19.713 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9182 priority from Get to Immediate
18:27:19.714 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:19.715 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9182 to queue - size 11
18:27:19.716 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:19.717 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 25 02 25 A5 47
18:27:19.718 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 25 02 25 A5 47
18:27:19.719 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:19.720 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:19.720 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9182: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 165
18:27:19.721 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:19.721 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9183 priority from Get to Immediate
18:27:19.722 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:19.722 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:19.722 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9182: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 165
18:27:19.723 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9183 to queue - size 11
18:27:19.723 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:19.723 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.724 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.724 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:19.724 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9184 priority from Get to Immediate
18:27:19.724 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.725 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.726 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:19.726 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9184 to queue - size 12
18:27:19.727 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.728 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.729 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9182: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 165
18:27:19.729 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:19.730 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9182: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 165
18:27:19.731 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.732 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:19.733 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9182: Advanced to WAIT_REQUEST
18:27:19.733 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9182: Transaction not completed
18:27:19.734 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.735 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.744 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A5 00 00 03 4D
18:27:19.746 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=165, payload=A5 00 00 03
18:27:19.747 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=165, payload=A5 00 00 03
18:27:19.748 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9182: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 165
18:27:19.749 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:19.750 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9182: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 165
18:27:19.751 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9182: (Callback 165)
18:27:19.751 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:19.752 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9182: callback 165
18:27:19.753 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=165, payload=A5 00 00 03
18:27:19.754 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 165, Status = Transmission complete and ACK received(0)
18:27:19.755 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:19.756 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9182: Advanced to WAIT_DATA
18:27:19.756 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9182: Transaction not completed
18:27:19.756 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 25 03 00 D0
18:27:19.757 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.757 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 00
18:27:19.758 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.759 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 00
18:27:19.760 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:19.762 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:19.762 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:19.763 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:19.764 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:19.765 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:27:19.766 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Switch Binary report, value = 0
18:27:19.767 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:19.768 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 0
18:27:19.769 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:switch_binary to OFF [OnOffType]
18:27:19.770 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:19.771 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fa4d0c7.
18:27:19.772 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fa4d0c7.
18:27:19.773 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9182 DONE
18:27:19.774 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:19.775 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:19.776 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:19.777 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.778 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:19.779 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 30 02 25 A6 51
18:27:19.780 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 30 02 25 A6 51
18:27:19.782 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:19.782 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:19.783 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9183: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 166
18:27:19.783 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:19.784 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:19.785 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9183: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 166
18:27:19.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:19.786 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:19.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.788 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.789 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9183: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 166
18:27:19.790 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:19.791 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9183: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 166
18:27:19.792 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.793 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:19.794 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9183: Advanced to WAIT_REQUEST
18:27:19.795 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9183: Transaction not completed
18:27:19.796 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.796 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.806 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A6 00 00 03 4E
18:27:19.807 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=166, payload=A6 00 00 03
18:27:19.808 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=166, payload=A6 00 00 03
18:27:19.809 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9183: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 166
18:27:19.810 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:19.811 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9183: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 166
18:27:19.812 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9183: (Callback 166)
18:27:19.813 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:19.814 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9183: callback 166
18:27:19.815 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=166, payload=A6 00 00 03
18:27:19.816 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 166, Status = Transmission complete and ACK received(0)
18:27:19.817 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:19.818 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9183: Advanced to WAIT_DATA
18:27:19.819 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 07 04 30 03 00 02 C3
18:27:19.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9183: Transaction not completed
18:27:19.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 00 02
18:27:19.821 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 00 02
18:27:19.823 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:19.823 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:19.824 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:19.825 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:19.826 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:19.827 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
18:27:19.828 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 2
18:27:19.828 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is SMOKE
18:27:19.829 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Sensor Binary report, type=Smoke, value=0
18:27:19.830 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
18:27:19.831 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 0
18:27:19.832 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:sensor_binary to OFF [OnOffType]
18:27:19.833 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:19.834 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@702a79fc.
18:27:19.835 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@702a79fc.
18:27:19.836 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9183 DONE
18:27:19.837 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:19.838 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:19.839 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:19.839 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.840 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:19.841 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 80 02 25 A7 E0
18:27:19.842 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 80 02 25 A7 E0
18:27:19.843 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:19.844 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:19.844 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9184: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 167
18:27:19.844 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:19.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:19.846 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9184: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 167
18:27:19.847 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:19.848 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.848 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:19.849 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.850 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.851 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.851 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9184: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 167
18:27:19.852 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:19.853 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9184: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 167
18:27:19.854 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:19.855 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:19.856 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9184: Advanced to WAIT_REQUEST
18:27:19.857 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9184: Transaction not completed
18:27:19.858 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.858 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.868 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A7 00 00 02 4E
18:27:19.869 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=167, payload=A7 00 00 02
18:27:19.870 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=167, payload=A7 00 00 02
18:27:19.871 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9184: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 167
18:27:19.872 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:19.872 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9184: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 167
18:27:19.873 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9184: (Callback 167)
18:27:19.874 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:19.875 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9184: callback 167
18:27:19.876 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=167, payload=A7 00 00 02
18:27:19.877 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 167, Status = Transmission complete and ACK received(0)
18:27:19.878 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:19.879 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9184: Advanced to WAIT_DATA
18:27:19.880 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9184: Transaction not completed
18:27:19.880 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 80 03 64 11
18:27:19.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.882 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:19.882 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 80 03 64
18:27:19.883 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 80 03 64
18:27:19.884 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:19.885 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:19.886 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:19.887 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
18:27:19.887 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:19.888 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
18:27:19.889 [DEBUG] [commandclass.ZWaveBatteryCommandClass] - NODE 7: Battery report value = 100
18:27:19.890 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:19.891 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BATTERY, value = 100
18:27:19.892 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:battery-level to 100 [DecimalType]
18:27:19.894 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:19.895 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@219f2d3a.
18:27:19.896 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@219f2d3a.
18:27:19.897 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9184 DONE
18:27:19.897 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:19.898 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:19.899 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:19.900 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:19.901 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:30.494 [INFO ] [smarthome.event.ItemCommandEvent     ] - Item 'POPP_Smoke_5_Sw' received command ON
18:27:30.497 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Command received zwave:device:e8445cf8:node7:switch_binary --> ON [OnOffType]
18:27:30.498 [INFO ] [smarthome.event.ItemStateChangedEvent] - POPP_Smoke_5_Sw changed from OFF to ON
18:27:30.499 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_SET
18:27:30.500 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:30.502 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:30.503 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9185 priority from Set to Immediate
18:27:30.505 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:30.507 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9185 to queue - size 11
18:27:30.508 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:30.510 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 07 03 25 01 FF 25 A8 B4
18:27:30.512 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 0A 00 13 07 03 25 01 FF 25 A8 B4
18:27:30.514 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:30.515 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:30.516 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9185: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 168
18:27:30.517 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:30.518 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling intialised at 86400 seconds - start in 1500 milliseconds.
18:27:30.518 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:30.519 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:30.519 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9185: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 168
18:27:30.519 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:30.520 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:30.520 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:30.521 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:30.522 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:30.522 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9185: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 168
18:27:30.524 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:30.525 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9185: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 168
18:27:30.525 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:30.526 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:30.527 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9185: Advanced to WAIT_REQUEST
18:27:30.528 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9185: Transaction not completed
18:27:30.529 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:30.530 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:31.780 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A8 00 00 7E 3D
18:27:31.783 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=168, payload=A8 00 00 7E
18:27:31.785 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=168, payload=A8 00 00 7E
18:27:31.786 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9185: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 168
18:27:31.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:31.789 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9185: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 168
18:27:31.790 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9185: (Callback 168)
18:27:31.791 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:31.793 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9185: callback 168
18:27:31.793 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 25 03 FF 2F
18:27:31.794 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=168, payload=A8 00 00 7E
18:27:31.796 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 FF
18:27:31.796 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 168, Status = Transmission complete and ACK received(0)
18:27:31.798 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:31.799 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 9185: Transaction COMPLETED
18:27:31.801 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Response processed after 1285ms
18:27:31.802 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9185: Transaction completed
18:27:31.803 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9185 DONE
18:27:31.805 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:31.806 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 FF
18:27:31.807 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:31.808 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:31.809 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:31.810 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:31.811 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:31.811 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:27:31.812 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Switch Binary report, value = 255
18:27:31.813 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:31.814 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255
18:27:31.815 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:switch_binary to ON [OnOffType]
18:27:31.816 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:31.817 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@64947f8a.
18:27:31.818 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:31.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:31.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:31.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:32.019 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling...
18:27:32.021 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:switch_binary
18:27:32.022 [DEBUG] [.converter.ZWaveBinarySwitchConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:32.024 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_GET
18:27:32.025 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:32.027 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:32.029 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling skipped for zwave:device:e8445cf8:node7:switch_binary on COMMAND_CLASS_BASIC
18:27:32.030 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:sensor_binary
18:27:32.032 [DEBUG] [.converter.ZWaveBinarySensorConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:32.033 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Creating new message for application command SENSOR_BINARY_GET
18:27:32.035 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:32.036 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured
18:27:32.038 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:alarm_general
18:27:32.039 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:battery-level
18:27:32.041 [DEBUG] [ernal.converter.ZWaveBatteryConverter] - NODE 7: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0
18:27:32.043 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:32.044 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured
18:27:32.046 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9186 priority from Get to Immediate
18:27:32.048 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:32.049 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9186 to queue - size 11
18:27:32.050 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:32.052 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 25 02 25 A9 4B
18:27:32.054 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 25 02 25 A9 4B
18:27:32.056 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:32.056 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:32.057 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9186: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 169
18:27:32.058 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:32.059 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9187 priority from Get to Immediate
18:27:32.060 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:32.060 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:32.062 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:32.062 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9187 to queue - size 11
18:27:32.064 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9186: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 169
18:27:32.064 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.064 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.064 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:32.066 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9188 priority from Get to Immediate
18:27:32.066 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.067 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:32.067 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9186: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 169
18:27:32.068 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9188 to queue - size 12
18:27:32.069 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.070 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:32.073 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9186: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 169
18:27:32.074 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.075 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:32.076 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9186: Advanced to WAIT_REQUEST
18:27:32.077 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9186: Transaction not completed
18:27:32.078 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.079 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A9 00 00 02 40
18:27:32.079 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.080 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=169, payload=A9 00 00 02
18:27:32.081 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=169, payload=A9 00 00 02
18:27:32.082 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9186: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 169
18:27:32.083 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:32.084 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9186: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 169
18:27:32.085 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9186: (Callback 169)
18:27:32.086 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:32.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9186: callback 169
18:27:32.087 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=169, payload=A9 00 00 02
18:27:32.088 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 169, Status = Transmission complete and ACK received(0)
18:27:32.089 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:32.090 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9186: Advanced to WAIT_DATA
18:27:32.090 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9186: Transaction not completed
18:27:32.091 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 25 03 FF 2F
18:27:32.091 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.092 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 FF
18:27:32.092 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.094 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 FF
18:27:32.095 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:32.096 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:32.097 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:32.098 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:32.099 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:32.100 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:27:32.101 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Switch Binary report, value = 255
18:27:32.101 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:32.102 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255
18:27:32.103 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:switch_binary to ON [OnOffType]
18:27:32.104 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:32.105 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5d8fb937.
18:27:32.106 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5d8fb937.
18:27:32.107 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9186 DONE
18:27:32.108 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:32.109 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:32.110 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:32.111 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:32.114 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 30 02 25 AA 5D
18:27:32.115 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 30 02 25 AA 5D
18:27:32.116 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:32.116 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:32.117 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9187: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 170
18:27:32.117 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:32.118 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:32.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9187: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 170
18:27:32.120 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:32.120 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:32.121 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.122 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.122 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.123 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.124 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9187: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 170
18:27:32.125 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:32.126 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9187: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 170
18:27:32.126 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.127 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:32.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9187: Advanced to WAIT_REQUEST
18:27:32.129 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9187: Transaction not completed
18:27:32.130 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.131 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.140 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 AA 00 00 02 43
18:27:32.142 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=170, payload=AA 00 00 02
18:27:32.142 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=170, payload=AA 00 00 02
18:27:32.143 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9187: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 170
18:27:32.144 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:32.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9187: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 170
18:27:32.146 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9187: (Callback 170)
18:27:32.147 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:32.148 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9187: callback 170
18:27:32.149 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=170, payload=AA 00 00 02
18:27:32.149 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 170, Status = Transmission complete and ACK received(0)
18:27:32.150 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:32.151 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9187: Advanced to WAIT_DATA
18:27:32.152 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9187: Transaction not completed
18:27:32.153 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.153 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 07 04 30 03 FF 02 3C
18:27:32.153 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.154 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 FF 02
18:27:32.156 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 FF 02
18:27:32.156 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:32.157 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:32.158 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:32.159 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:32.160 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:32.160 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
18:27:32.161 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 2
18:27:32.162 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is SMOKE
18:27:32.163 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Sensor Binary report, type=Smoke, value=255
18:27:32.164 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
18:27:32.165 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 255
18:27:32.166 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:sensor_binary to ON [OnOffType]
18:27:32.167 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:32.167 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7ec9cee3.
18:27:32.168 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7ec9cee3.
18:27:32.169 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9187 DONE
18:27:32.170 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:32.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:32.172 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:32.173 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.173 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:32.174 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 80 02 25 AB EC
18:27:32.175 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 80 02 25 AB EC
18:27:32.176 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:32.177 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:32.178 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:32.177 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9188: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 171
18:27:32.179 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:32.180 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9188: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 171
18:27:32.181 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:32.181 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:32.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.183 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.184 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.185 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.186 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9188: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 171
18:27:32.187 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:32.188 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9188: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 171
18:27:32.188 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:32.189 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:32.190 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9188: Advanced to WAIT_REQUEST
18:27:32.191 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9188: Transaction not completed
18:27:32.192 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.192 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.200 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 AB 00 00 02 42
18:27:32.202 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=171, payload=AB 00 00 02
18:27:32.203 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=171, payload=AB 00 00 02
18:27:32.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9188: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 171
18:27:32.204 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:32.205 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9188: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 171
18:27:32.206 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9188: (Callback 171)
18:27:32.207 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:32.208 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9188: callback 171
18:27:32.208 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=171, payload=AB 00 00 02
18:27:32.209 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 171, Status = Transmission complete and ACK received(0)
18:27:32.210 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:32.211 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9188: Advanced to WAIT_DATA
18:27:32.212 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9188: Transaction not completed
18:27:32.213 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 80 03 64 11
18:27:32.213 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.214 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:32.214 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 80 03 64
18:27:32.216 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 80 03 64
18:27:32.217 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:32.217 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:32.218 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:32.219 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
18:27:32.220 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:32.221 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
18:27:32.221 [DEBUG] [commandclass.ZWaveBatteryCommandClass] - NODE 7: Battery report value = 100
18:27:32.222 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:32.223 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BATTERY, value = 100
18:27:32.224 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:battery-level to 100 [DecimalType]
18:27:32.225 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:32.226 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@26e7dafd.
18:27:32.227 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@26e7dafd.
18:27:32.228 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9188 DONE
18:27:32.229 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:32.230 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:32.231 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:32.232 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:32.233 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:42.384 [INFO ] [smarthome.event.ItemCommandEvent     ] - Item 'POPP_Smoke_5_Sw' received command OFF
18:27:42.389 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Command received zwave:device:e8445cf8:node7:switch_binary --> OFF [OnOffType]
18:27:42.391 [INFO ] [smarthome.event.ItemStateChangedEvent] - POPP_Smoke_5_Sw changed from ON to OFF
18:27:42.391 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_SET
18:27:42.393 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:42.394 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:42.396 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9189 priority from Set to Immediate
18:27:42.398 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:42.399 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9189 to queue - size 11
18:27:42.401 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:42.402 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 07 03 25 01 00 25 AC 4F
18:27:42.404 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 0A 00 13 07 03 25 01 00 25 AC 4F
18:27:42.406 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:42.407 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:42.408 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9189: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 172
18:27:42.409 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:42.410 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling intialised at 86400 seconds - start in 1500 milliseconds.
18:27:42.411 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:42.411 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:42.413 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9189: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 172
18:27:42.414 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:42.414 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:42.417 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:42.418 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9189: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 172
18:27:42.419 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:42.421 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9189: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 172
18:27:42.422 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:42.423 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:42.425 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9189: Advanced to WAIT_REQUEST
18:27:42.426 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9189: Transaction not completed
18:27:42.427 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:42.429 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:42.430 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 AC 00 00 02 45
18:27:42.432 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=172, payload=AC 00 00 02
18:27:42.433 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=172, payload=AC 00 00 02
18:27:42.434 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9189: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 172
18:27:42.435 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:42.435 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9189: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 172
18:27:42.436 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9189: (Callback 172)
18:27:42.437 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:42.438 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9189: callback 172
18:27:42.439 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=172, payload=AC 00 00 02
18:27:42.440 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 172, Status = Transmission complete and ACK received(0)
18:27:42.441 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:42.441 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 9189: Transaction COMPLETED
18:27:42.442 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Response processed after 34ms
18:27:42.443 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 25 03 00 D0
18:27:42.443 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9189: Transaction completed
18:27:42.444 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 00
18:27:42.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9189 DONE
18:27:42.446 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:42.447 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 00
18:27:42.448 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:42.449 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:42.450 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:42.451 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:42.452 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:42.453 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:27:42.454 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Switch Binary report, value = 0
18:27:42.455 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:42.456 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 0
18:27:42.457 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:switch_binary to OFF [OnOffType]
18:27:42.457 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:42.458 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@61489984.
18:27:42.460 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:42.460 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:42.462 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:42.463 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:43.910 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling...
18:27:43.912 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:switch_binary
18:27:43.913 [DEBUG] [.converter.ZWaveBinarySwitchConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:43.915 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_GET
18:27:43.916 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:43.918 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
18:27:43.919 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling skipped for zwave:device:e8445cf8:node7:switch_binary on COMMAND_CLASS_BASIC
18:27:43.921 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:sensor_binary
18:27:43.922 [DEBUG] [.converter.ZWaveBinarySensorConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:43.923 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Creating new message for application command SENSOR_BINARY_GET
18:27:43.925 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:43.927 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SENSOR_BINARY is NOT required to be secured
18:27:43.928 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:alarm_general
18:27:43.930 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:e8445cf8:node7:battery-level
18:27:43.932 [DEBUG] [ernal.converter.ZWaveBatteryConverter] - NODE 7: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0
18:27:43.933 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:43.935 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured
18:27:43.936 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9190 priority from Get to Immediate
18:27:43.938 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:43.939 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9190 to queue - size 11
18:27:43.941 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:43.943 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 25 02 25 AD 4F
18:27:43.944 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 25 02 25 AD 4F
18:27:43.946 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:43.947 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:43.948 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9190: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 173
18:27:43.949 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:43.950 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9191 priority from Get to Immediate
18:27:43.951 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:43.951 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:43.952 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9190: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 173
18:27:43.951 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:43.953 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:43.953 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9191 to queue - size 11
18:27:43.954 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:43.955 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:43.953 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:43.956 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Bump transaction 9192 priority from Get to Immediate
18:27:43.956 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:43.958 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:27:43.959 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 9192 to queue - size 12
18:27:43.960 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:43.961 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:43.962 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9190: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 173
18:27:43.963 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:43.964 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9190: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 173
18:27:43.965 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:43.966 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:43.966 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9190: Advanced to WAIT_REQUEST
18:27:43.967 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9190: Transaction not completed
18:27:43.968 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:43.969 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:43.970 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 AD 00 00 02 44
18:27:43.972 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=173, payload=AD 00 00 02
18:27:43.973 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=173, payload=AD 00 00 02
18:27:43.974 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9190: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 173
18:27:43.975 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:43.975 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9190: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 173
18:27:43.976 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9190: (Callback 173)
18:27:43.977 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:43.978 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9190: callback 173
18:27:43.979 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=173, payload=AD 00 00 02
18:27:43.980 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 173, Status = Transmission complete and ACK received(0)
18:27:43.981 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:43.982 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9190: Advanced to WAIT_DATA
18:27:43.983 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9190: Transaction not completed
18:27:43.983 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 25 03 00 D0
18:27:43.983 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:43.984 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:43.984 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 00
18:27:43.986 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 25 03 00
18:27:43.987 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:43.987 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:43.988 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:43.989 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
18:27:43.990 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:27:43.991 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:27:43.992 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Switch Binary report, value = 0
18:27:43.993 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:43.994 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 0
18:27:43.995 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:switch_binary to OFF [OnOffType]
18:27:43.997 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:43.999 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@412ec29b.
18:27:44.000 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@412ec29b.
18:27:44.001 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9190 DONE
18:27:44.002 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:44.003 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:44.004 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:44.005 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.006 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:44.007 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 30 02 25 AE 59
18:27:44.008 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 30 02 25 AE 59
18:27:44.009 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:44.009 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:44.010 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 174
18:27:44.010 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:44.012 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:44.013 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 174
18:27:44.013 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:44.013 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:44.014 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.014 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:44.015 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:44.016 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:44.017 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 174
18:27:44.018 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:44.019 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 174
18:27:44.020 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:44.020 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:44.021 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9191: Advanced to WAIT_REQUEST
18:27:44.022 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9191: Transaction not completed
18:27:44.023 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.024 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:44.032 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 AE 00 00 02 47
18:27:44.034 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=174, payload=AE 00 00 02
18:27:44.035 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=174, payload=AE 00 00 02
18:27:44.036 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9191: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 174
18:27:44.036 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:44.037 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9191: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 174
18:27:44.038 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9191: (Callback 174)
18:27:44.039 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:44.040 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9191: callback 174
18:27:44.041 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=174, payload=AE 00 00 02
18:27:44.042 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 174, Status = Transmission complete and ACK received(0)
18:27:44.043 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:44.043 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9191: Advanced to WAIT_DATA
18:27:44.045 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9191: Transaction not completed
18:27:44.045 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 07 04 30 03 00 02 C3
18:27:44.046 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.047 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 00 02
18:27:44.047 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:44.048 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 00 02
18:27:44.049 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:44.050 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:44.051 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:44.052 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:44.053 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:44.053 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
18:27:44.054 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 2
18:27:44.055 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is SMOKE
18:27:44.056 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Sensor Binary report, type=Smoke, value=0
18:27:44.057 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
18:27:44.058 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 0
18:27:44.059 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:sensor_binary to OFF [OnOffType]
18:27:44.060 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:44.061 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7120b28b.
18:27:44.062 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7120b28b.
18:27:44.063 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9191 DONE
18:27:44.064 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:44.065 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:44.066 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:44.066 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.067 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:44.068 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 80 02 25 AF E8
18:27:44.069 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 80 02 25 AF E8
18:27:44.070 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:27:44.071 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:27:44.071 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 175
18:27:44.072 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:44.073 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:27:44.073 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 175
18:27:44.074 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:27:44.075 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:27:44.075 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.076 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:44.076 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:44.078 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:44.079 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 175
18:27:44.080 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:44.081 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 175
18:27:44.082 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:27:44.083 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:27:44.084 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9192: Advanced to WAIT_REQUEST
18:27:44.084 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9192: Transaction not completed
18:27:44.085 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:44.095 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 AF 00 00 03 47
18:27:44.096 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=175, payload=AF 00 00 03
18:27:44.097 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=175, payload=AF 00 00 03
18:27:44.098 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 9192: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 175
18:27:44.099 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:27:44.099 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 9192: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 175
18:27:44.100 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 9192: (Callback 175)
18:27:44.101 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:27:44.102 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 9192: callback 175
18:27:44.103 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=175, payload=AF 00 00 03
18:27:44.104 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 175, Status = Transmission complete and ACK received(0)
18:27:44.105 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:44.106 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 9192: Advanced to WAIT_DATA
18:27:44.107 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 07 03 80 03 64 11
18:27:44.107 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 9192: Transaction not completed
18:27:44.108 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 80 03 64
18:27:44.108 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.110 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:27:44.111 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 03 80 03 64
18:27:44.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:44.113 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:44.113 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:44.114 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
18:27:44.115 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:27:44.116 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
18:27:44.117 [DEBUG] [commandclass.ZWaveBatteryCommandClass] - NODE 7: Battery report value = 100
18:27:44.118 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:27:44.118 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BATTERY, value = 100
18:27:44.119 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:battery-level to 100 [DecimalType]
18:27:44.120 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:44.122 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6908be60.
18:27:44.123 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6908be60.
18:27:44.124 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:9192 DONE
18:27:44.125 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:27:44.126 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:27:44.127 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:44.128 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:44.129 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:46.453 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 07 04 30 03 FF 02 3C
18:27:46.456 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 FF 02
18:27:46.458 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 FF 02
18:27:46.459 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:46.460 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:46.462 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:46.463 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:46.464 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:46.466 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
18:27:46.467 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 2
18:27:46.468 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is SMOKE
18:27:46.470 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Sensor Binary report, type=Smoke, value=255
18:27:46.471 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
18:27:46.473 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 255
18:27:46.474 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:sensor_binary to ON [OnOffType]
18:27:46.476 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:46.478 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 07 0B 71 05 00 00 00 FF 01 02 00 00 00 6E
18:27:46.478 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4c298e7c.
18:27:46.479 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:46.479 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0B 71 05 00 00 00 FF 01 02 00 00 00
18:27:46.480 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:46.481 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0B 71 05 00 00 00 FF 01 02 00 00 00
18:27:46.482 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:46.483 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:46.483 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:46.484 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
18:27:46.485 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_ALARM
18:27:46.486 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT
18:27:46.487 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 7: NOTIFICATION report - 0 = 0, event=2, status=255, plen=0
18:27:46.488 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 7: Alarm Type = SMOKE (0)
18:27:46.489 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveAlarmValueEvent
18:27:46.490 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ALARM, value = 255
18:27:46.491 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 7: Alarm converter processing NOTIFICATION
18:27:46.492 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 7: Alarm converter NOTIFICATION event is 2, type OnOffType
18:27:46.493 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 7: Alarm converter NOTIFICATION event is 2, channel alarm_general is not implemented.
18:27:46.494 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:46.495 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7ba4b778.
18:27:46.496 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:46.497 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:46.498 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:46.499 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:27:46.981 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 07 04 30 03 00 02 C3
18:27:46.983 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 00 02
18:27:46.985 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 04 30 03 00 02
18:27:46.986 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:46.988 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:46.989 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:46.990 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
18:27:46.992 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
18:27:46.993 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
18:27:46.995 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 2
18:27:46.996 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - Sensor Type is SMOKE
18:27:46.997 [DEBUG] [ndclass.ZWaveBinarySensorCommandClass] - NODE 7: Sensor Binary report, type=Smoke, value=0
18:27:46.999 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
18:27:47.001 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_BINARY, value = 0
18:27:47.002 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:e8445cf8:node7:sensor_binary to OFF [OnOffType]
18:27:47.004 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:47.005 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f64e66d.
18:27:47.005 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 07 0B 71 05 00 00 00 FF 01 00 01 02 00 6F
18:27:47.007 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:47.008 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0B 71 05 00 00 00 FF 01 00 01 02 00
18:27:47.008 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:47.011 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0B 71 05 00 00 00 FF 01 00 01 02 00
18:27:47.012 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:27:47.014 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:27:47.015 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:27:47.017 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
18:27:47.018 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_ALARM
18:27:47.019 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_ALARM V5 NOTIFICATION_REPORT
18:27:47.020 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 7: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1
18:27:47.021 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 7: Alarm Type = SMOKE (0)
18:27:47.022 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveAlarmValueEvent
18:27:47.023 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ALARM, value = 255
18:27:47.024 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 7: Alarm converter processing NOTIFICATION
18:27:47.025 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 7: Alarm converter NOTIFICATION event is 0, type OnOffType
18:27:47.026 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 7: Alarm converter NOTIFICATION event is 0, channel alarm_general is not implemented.
18:27:47.027 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:27:47.028 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@23b3a25f.
18:27:47.029 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:47.030 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:27:47.031 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:27:47.032 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.