2019-10-31 09:39:20.441 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Handler disposed. Unregistering listener. 2019-10-31 09:39:20.464 [DEBUG] [ternal.protocol.initialization.ZWaveNodeSerializer] - NODE 12: Serializing to file C:\openHAB2\userdata\zwave\network_d5fdd4e4__node_12.xml 2019-10-31 09:39:20.492 [DEBUG] [org.openhab.binding.zwave ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.core.status.ConfigStatusProvider}={service.id=379, service.bundleid=206, service.scope=singleton} - org.openhab.binding.zwave 2019-10-31 09:39:32.142 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 0C 02 84 07 7E 2019-10-31 09:39:32.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 02 84 07 2019-10-31 09:39:32.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 02 84 07 2019-10-31 09:39:32.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-31 09:39:32.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-31 09:39:32.144 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Is awake with 0 messages in the queue 2019-10-31 09:39:32.144 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Start sleep timer at 1000ms 2019-10-31 09:39:32.144 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 12: Node Status event - Node is AWAKE 2019-10-31 09:39:32.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-31 09:39:32.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3c5384c. 2019-10-31 09:39:32.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-31 09:39:32.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-31 09:39:32.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:32.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-31 09:39:32.644 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: WakeupTimerTask 0 Messages waiting, state GET_CONFIGURATION 2019-10-31 09:39:33.145 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: WakeupTimerTask 0 Messages waiting, state GET_CONFIGURATION 2019-10-31 09:39:33.145 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: No more messages, go back to sleep 2019-10-31 09:39:33.145 [DEBUG] [rnal.protocol.commandclass.ZWaveWakeUpCommandClass] - NODE 12: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION 2019-10-31 09:39:33.146 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_WAKE_UP 2019-10-31 09:39:33.146 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured 2019-10-31 09:39:33.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@2e456e9d 2019-10-31 09:39:33.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Bump transaction 3943 priority from Immediate to Immediate 2019-10-31 09:39:33.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue 2019-10-31 09:39:33.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Added 3943 to queue - size 1 2019-10-31 09:39:33.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-31 09:39:33.148 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0C 02 84 08 25 36 74 2019-10-31 09:39:33.148 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 09 00 13 0C 02 84 08 25 36 74 2019-10-31 09:39:33.149 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-31 09:39:33.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 3943: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 54 2019-10-31 09:39:33.149 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-31 09:39:33.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-31 09:39:33.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-31 09:39:33.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 3943: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 54 2019-10-31 09:39:33.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-31 09:39:33.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:33.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-31 09:39:33.156 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-31 09:39:33.156 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-31 09:39:33.156 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-31 09:39:33.157 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 3943: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 54 2019-10-31 09:39:33.157 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-31 09:39:33.157 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 3943: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 54 2019-10-31 09:39:33.157 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-31 09:39:33.157 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-31 09:39:33.157 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 3943: Advanced to WAIT_REQUEST 2019-10-31 09:39:33.157 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 3943: Transaction not completed 2019-10-31 09:39:33.158 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:33.158 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-31 09:39:33.172 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 36 00 00 02 DF 2019-10-31 09:39:33.172 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=54, payload=36 00 00 02 2019-10-31 09:39:33.172 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=54, payload=36 00 00 02 2019-10-31 09:39:33.172 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 3943: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 54 2019-10-31 09:39:33.173 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-31 09:39:33.173 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 3943: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 54 2019-10-31 09:39:33.173 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 3943: (Callback 54) 2019-10-31 09:39:33.173 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-31 09:39:33.173 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 3943: callback 54 2019-10-31 09:39:33.173 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=54, payload=36 00 00 02 2019-10-31 09:39:33.173 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 54, Status = Transmission complete and ACK received(0) 2019-10-31 09:39:33.173 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 3943: Transaction COMPLETED 2019-10-31 09:39:33.174 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 25ms 2019-10-31 09:39:33.174 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 3943: Transaction completed 2019-10-31 09:39:33.174 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:3943 DONE 2019-10-31 09:39:33.174 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:33.174 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-31 09:39:33.175 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 3943: Transaction event listener: DONE: DONE -> 2019-10-31 09:39:33.175 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Went to sleep COMPLETE 2019-10-31 09:39:33.619 [DEBUG] [org.openhab.binding.zwave ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.status.ConfigStatusProvider}={service.id=380, service.bundleid=206, service.scope=singleton} - org.openhab.binding.zwave 2019-10-31 09:39:33.620 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:400e534b:node12. 2019-10-31 09:39:33.627 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Controller status changed to ONLINE. 2019-10-31 09:39:33.627 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Controller is ONLINE. Starting device initialisation. 2019-10-31 09:39:33.627 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating node properties. 2019-10-31 09:39:33.627 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating node properties. MAN=881 2019-10-31 09:39:33.627 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating node properties. MAN=881. SET. Was 881 2019-10-31 09:39:33.628 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Properties synchronised 2019-10-31 09:39:33.628 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Update ASSOCIATION group_ZWaveAssociationGroup [index=1, name=null, profile1=0, profile2=1, associations=[node_1]]: Adding node_1 2019-10-31 09:39:33.628 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Configuration synchronised 2019-10-31 09:39:33.631 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Channel zwave:device:400e534b:node12:sensor_temperature linked - polling started. 2019-10-31 09:39:33.631 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Channel zwave:device:400e534b:node12:sensor_luminance linked - polling started. 2019-10-31 09:39:33.631 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Channel zwave:device:400e534b:node12:alarm_motion linked - polling started. 2019-10-31 09:39:33.631 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Event listener added. 2019-10-31 09:39:33.631 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Channel zwave:device:400e534b:node12:battery-level linked - polling started. 2019-10-31 09:39:33.631 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising Thing Node... 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising cmd channel zwave:device:400e534b:node12:sensor_temperature for QuantityType 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising state channel zwave:device:400e534b:node12:sensor_temperature for QuantityType 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising cmd channel zwave:device:400e534b:node12:sensor_luminance for DecimalType 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising state channel zwave:device:400e534b:node12:sensor_luminance for DecimalType 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising cmd channel zwave:device:400e534b:node12:alarm_motion for OnOffType 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising state channel zwave:device:400e534b:node12:alarm_motion for OnOffType 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising cmd channel zwave:device:400e534b:node12:battery-level for PercentType 2019-10-31 09:39:33.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising state channel zwave:device:400e534b:node12:battery-level for PercentType 2019-10-31 09:39:33.633 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling intialised at 3600 seconds - start in 1771200 milliseconds. 2019-10-31 09:39:33.633 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Device initialisation complete. 2019-10-31 09:39:38.328 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 0C 02 84 07 7E 2019-10-31 09:39:38.329 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 02 84 07 2019-10-31 09:39:38.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 02 84 07 2019-10-31 09:39:38.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-31 09:39:38.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-31 09:39:38.330 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Is awake with 0 messages in the queue 2019-10-31 09:39:38.330 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Start sleep timer at 1000ms 2019-10-31 09:39:38.331 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-10-31 09:39:38.332 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 12: Node Status event - Node is AWAKE 2019-10-31 09:39:38.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-31 09:39:38.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@47f4251a. 2019-10-31 09:39:38.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-31 09:39:38.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-31 09:39:38.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:38.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-31 09:39:38.830 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: WakeupTimerTask 0 Messages waiting, state GET_CONFIGURATION 2019-10-31 09:39:39.330 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: WakeupTimerTask 0 Messages waiting, state GET_CONFIGURATION 2019-10-31 09:39:39.330 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: No more messages, go back to sleep 2019-10-31 09:39:39.330 [DEBUG] [rnal.protocol.commandclass.ZWaveWakeUpCommandClass] - NODE 12: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION 2019-10-31 09:39:39.330 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_WAKE_UP 2019-10-31 09:39:39.330 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured 2019-10-31 09:39:39.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@665fca47 2019-10-31 09:39:39.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Bump transaction 3944 priority from Immediate to Immediate 2019-10-31 09:39:39.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue 2019-10-31 09:39:39.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Added 3944 to queue - size 1 2019-10-31 09:39:39.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-31 09:39:39.331 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0C 02 84 08 25 37 75 2019-10-31 09:39:39.332 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 09 00 13 0C 02 84 08 25 37 75 2019-10-31 09:39:39.332 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-31 09:39:39.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 3944: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 55 2019-10-31 09:39:39.333 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-31 09:39:39.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-31 09:39:39.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-31 09:39:39.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 3944: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 55 2019-10-31 09:39:39.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-31 09:39:39.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:39.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-31 09:39:39.340 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-31 09:39:39.341 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-31 09:39:39.341 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-31 09:39:39.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 3944: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 55 2019-10-31 09:39:39.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-31 09:39:39.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 3944: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 55 2019-10-31 09:39:39.342 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-31 09:39:39.342 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-31 09:39:39.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 3944: Advanced to WAIT_REQUEST 2019-10-31 09:39:39.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 3944: Transaction not completed 2019-10-31 09:39:39.343 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:39.343 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-31 09:39:39.357 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 37 00 00 02 DE 2019-10-31 09:39:39.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 2019-10-31 09:39:39.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 2019-10-31 09:39:39.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 3944: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 55 2019-10-31 09:39:39.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-31 09:39:39.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 3944: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 55 2019-10-31 09:39:39.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 3944: (Callback 55) 2019-10-31 09:39:39.358 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-31 09:39:39.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 3944: callback 55 2019-10-31 09:39:39.359 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 2019-10-31 09:39:39.359 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 55, Status = Transmission complete and ACK received(0) 2019-10-31 09:39:39.359 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 3944: Transaction COMPLETED 2019-10-31 09:39:39.359 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 27ms 2019-10-31 09:39:39.359 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 3944: Transaction completed 2019-10-31 09:39:39.359 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:3944 DONE 2019-10-31 09:39:39.360 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-31 09:39:39.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-31 09:39:39.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-31 09:39:39.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 3944: Transaction event listener: DONE: DONE -> 2019-10-31 09:39:39.360 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Went to sleep COMPLETE