18:10:05.091 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery completed
18:10:05.093 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery resolved to thingType zwave:fibaro_fgk101_03_002
18:10:05.097 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery completed
18:10:05.098 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery resolved to thingType zwave:fibaro_fgr222_24_024
18:10:05.101 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery completed
18:10:05.103 [WARN ] [zwave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery could not resolve to a thingType! Manufacturer data not known.
18:10:05.105 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed
18:10:05.106 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery resolved to thingType zwave:fibaro_fgr222_24_024
18:10:05.109 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery completed
18:10:05.124 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery resolved to thingType zwave:fibaro_fgr222_24_024
18:10:05.136 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery completed
18:10:05.138 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery resolved to thingType zwave:fibaro_fgs223_00_000
18:10:05.144 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery completed
18:10:05.177 [WARN ] [zwave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery could not resolve to a thingType! Manufacturer data not known.
18:10:05.182 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 9: Device discovery completed
18:10:05.187 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 9: Device discovery resolved to thingType zwave:fibaro_fgwp102_03_002
18:10:05.199 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 10: Device discovery completed
18:10:05.201 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 10: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:05.207 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 11: Device discovery completed
18:10:05.209 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 11: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:05.212 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 12: Device discovery completed
18:10:05.216 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 12: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:05.226 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 13: Device discovery completed
18:10:05.230 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 13: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:05.234 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 14: Device discovery completed
18:10:05.237 [WARN ] [zwave.discovery.ZWaveDiscoveryService] - NODE 14: Device discovery could not resolve to a thingType! Manufacturer data not known.
18:10:05.240 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 15: Device discovery completed
18:10:05.260 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 15: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:05.264 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 16: Device discovery completed
18:10:05.271 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 16: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:05.283 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 17: Device discovery completed
18:10:05.286 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 17: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:05.291 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 18: Device discovery completed
18:10:05.293 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 18: Device discovery resolved to thingType zwave:fibaro_fgdw002_00_000
18:10:33.061 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:dd7f023f
18:10:33.072 [DEBUG] [nal.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart
18:10:33.083 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
18:10:33.084 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 6269 to queue - size 1
18:10:33.086 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:10:33.088 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 EA 5F
18:10:33.090 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 EA 5F
18:10:33.092 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:10:33.093 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 6269: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 234
18:10:33.105 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:10:33.110 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:10:33.111 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:10:33.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6269: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 234
18:10:33.118 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:10:33.119 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:10:33.120 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:10:33.142 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A EA 06 12 00 4C
18:10:33.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=234, payload=EA 06 12 00
18:10:33.146 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=234, payload=EA 06 12 00
18:10:33.147 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6269: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 234
18:10:33.149 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:10:33.150 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 6269: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 234
18:10:33.151 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 6269: (Callback 234)
18:10:33.152 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:10:33.153 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 6269: callback 234
18:10:33.154 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=234, payload=EA 06 12 00
18:10:33.156 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Add Node: Done.
18:10:33.157 [DEBUG] [zwave.discovery.ZWaveDiscoveryService] - NODE 18: Device discovered
18:10:33.161 [INFO ] [smarthome.event.BindingEvent         ] - org.openhab.binding.zwave.event.BindingEvent@70712c8e
18:10:33.160 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveInclusionEvent
18:10:33.171 [DEBUG] [nal.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone
18:10:33.173 [DEBUG] [col.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
18:10:33.174 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Added 6270 to queue - size 1
18:10:33.175 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:10:33.177 [DEBUG] [nal.protocol.ZWaveInclusionController] - ZWave controller end exclusion
18:10:33.178 [DEBUG] [ave.internal.protocol.ZWaveController] - ZWaveController include done
18:10:33.179 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 6269: Advanced to DONE
18:10:33.180 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 87ms
18:10:33.181 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: TID 6269: Transaction completed
18:10:33.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:6269 DONE
18:10:33.184 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:10:33.185 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:10:33.186 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5
18:10:33.187 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5
18:10:33.188 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:10:33.189 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:10:33.191 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:10:33.192 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 6270: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
18:10:33.194 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:10:33.195 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6270: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
18:10:33.196 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:10:33.198 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:10:33.199 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:10:38.195 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - NODE 255: TID 6270: Timeout at state WAIT_REQUEST. 3 retries remaining.
18:10:38.196 [DEBUG] [nsactionManager$ZWaveTransactionTimer] - TID 6270: Transaction is current transaction, so clearing!!!!!
18:10:38.198 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 6270: Transaction CANCELLED
18:10:38.200 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:6270 CANCELLED
18:10:38.201 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:10:49.365 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1C 00 49 84 06 16 04 11 06 8E 72 86 70 85 73 32 26 31 25 91 75 EF 32 31 91 2B 26 25 60
18:10:49.378 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=6, callback=132, payload=84 06 16 04 11 06 8E 72 86 70 85 73 32 26 31 25 91 75 EF 32 31 91 2B 26 25
18:10:49.389 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=6, callback=132, payload=84 06 16 04 11 06 8E 72 86 70 85 73 32 26 31 25 91 75 EF 32 31 91 2B 26 25
18:10:49.390 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:10:49.391 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
18:10:49.392 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
18:10:49.394 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=6, callback=132, payload=84 06 16 04 11 06 8E 72 86 70 85 73 32 26 31 25 91 75 EF 32 31 91 2B 26 25
18:10:49.403 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 6: Application update request. Node information received. Transaction null
18:10:49.414 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
18:10:49.416 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 6: Application update - no transaction.
18:10:49.417 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:10:49.419 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:11:23.015 [DEBUG] [nal.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
18:11:23.053 [DEBUG] [nal.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
18:11:25.190 [DEBUG] [ng.zwave.internal.ZWaveConfigProvider] - No bridgeUID found in getConfigDescription thing:zwave:serial_zstick:dd7f023f
18:11:31.457 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling...
18:11:31.477 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling zwave:device:dd7f023f:node15:sensor_temperature
18:11:31.490 [DEBUG] [verter.ZWaveMultiLevelSensorConverter] - NODE 15: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
18:11:31.492 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 15: Creating new message for command SENSOR_MULTILEVEL_GET
18:11:31.498 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
18:11:31.513 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
18:11:31.515 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling zwave:device:dd7f023f:node15:sensor_door
18:11:31.517 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 15: Polling zwave:device:dd7f023f:node15:battery-level
18:11:31.525 [DEBUG] [ernal.converter.ZWaveBatteryConverter] - NODE 15: Generating poll message for COMMAND_CLASS_BATTERY endpoint 0
18:11:31.527 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: SECURITY NOT required on COMMAND_CLASS_BATTERY
18:11:31.528 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 15: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured
18:11:31.531 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Bump transaction 6271 priority from Get to Immediate
18:11:31.533 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Adding to device queue
18:11:31.535 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Transaction already in queue - removed original
18:11:31.537 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Added 6271 to queue - size 17
18:11:31.539 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:11:31.564 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Bump transaction 6272 priority from Get to Immediate
18:11:31.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Adding to device queue
18:11:31.568 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Transaction already in queue - removed original
18:11:31.569 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 15: Added 6272 to queue - size 17
18:11:31.571 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:13:21.108 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling...
18:13:21.109 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:dd7f023f:node7:switch_binary1
18:13:21.121 [DEBUG] [.converter.ZWaveBinarySwitchConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 1
18:13:21.123 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_GET
18:13:21.130 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Encapsulating message, instance / endpoint 1
18:13:21.131 [DEBUG] [dclass.ZWaveMultiInstanceCommandClass] - NODE 7: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
18:13:21.133 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
18:13:21.134 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
18:13:21.135 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling skipped for zwave:device:dd7f023f:node7:switch_binary1 on COMMAND_CLASS_BASIC
18:13:21.136 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:dd7f023f:node7:switch_binary2
18:13:21.138 [DEBUG] [.converter.ZWaveBinarySwitchConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 2
18:13:21.139 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_GET
18:13:21.140 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Encapsulating message, instance / endpoint 2
18:13:21.141 [DEBUG] [dclass.ZWaveMultiInstanceCommandClass] - NODE 7: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
18:13:21.142 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
18:13:21.143 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
18:13:21.144 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling skipped for zwave:device:dd7f023f:node7:switch_binary2 on COMMAND_CLASS_BASIC
18:13:21.146 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:13:21.147 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 6273 to queue - size 18
18:13:21.148 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:13:21.150 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 07 06 60 0D 01 01 25 02 25 EC 63
18:13:21.169 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 0D 00 13 07 06 60 0D 01 01 25 02 25 EC 63
18:13:21.171 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:13:21.172 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:13:21.185 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 6273: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 236
18:13:21.187 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
18:13:21.188 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Added 6274 to queue - size 18
18:13:21.190 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:13:21.206 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:13:21.207 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:13:21.213 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:13:21.215 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:13:21.216 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6273: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 236
18:13:21.217 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:13:21.218 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:13:21.219 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6273: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 236
18:13:21.220 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:13:21.221 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 6273: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 236
18:13:21.223 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:13:21.241 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:13:21.254 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 6273: Advanced to WAIT_REQUEST
18:13:21.255 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 6273: Transaction not completed
18:13:21.257 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:13:21.259 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:13:21.308 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 EC 00 00 0D 0A
18:13:21.309 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=236, payload=EC 00 00 0D
18:13:21.311 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=236, payload=EC 00 00 0D
18:13:21.312 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6273: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 236
18:13:21.315 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:13:21.317 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 6273: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 236
18:13:21.319 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 6273: (Callback 236)
18:13:21.321 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:13:21.323 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 6273: callback 236
18:13:21.326 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=236, payload=EC 00 00 0D
18:13:21.327 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 236, Status = Transmission complete and ACK received(0)
18:13:21.329 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:13:21.330 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 6273: Advanced to WAIT_DATA
18:13:21.332 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 6273: Transaction not completed
18:13:21.335 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:13:21.336 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:13:21.428 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 07 07 60 0D 01 01 25 03 00 BD
18:13:21.430 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 07 60 0D 01 01 25 03 00
18:13:21.431 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 07 60 0D 01 01 25 03 00
18:13:21.433 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:13:21.434 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:13:21.435 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:13:21.436 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
18:13:21.438 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 1
18:13:21.439 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:13:21.440 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:13:21.453 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Switch Binary report, value = 0
18:13:21.466 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:13:21.474 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_BINARY, value = 0
18:13:21.479 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:dd7f023f:node7:switch_binary1 to OFF [OnOffType]
18:13:21.498 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:13:21.499 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2fedb7c3.
18:13:21.500 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2fedb7c3.
18:13:21.506 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:6273 DONE
18:13:21.519 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:13:21.521 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:13:21.523 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:13:21.525 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:13:21.526 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
18:13:21.528 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 07 06 60 0D 01 02 25 02 25 ED 61
18:13:21.530 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 0D 00 13 07 06 60 0D 01 02 25 02 25 ED 61
18:13:21.532 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
18:13:21.533 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
18:13:21.534 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 6274: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 237
18:13:21.534 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:13:21.537 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
18:13:21.538 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6274: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 237
18:13:21.539 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
18:13:21.540 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
18:13:21.540 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:13:21.541 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:13:21.542 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:13:21.543 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:13:21.544 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6274: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 237
18:13:21.545 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:13:21.546 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 6274: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 237
18:13:21.547 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
18:13:21.548 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack.
18:13:21.549 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 6274: Advanced to WAIT_REQUEST
18:13:21.550 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 6274: Transaction not completed
18:13:21.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:13:21.552 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:13:21.672 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 ED 00 00 0D 0B
18:13:21.673 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=237, payload=ED 00 00 0D
18:13:21.675 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=237, payload=ED 00 00 0D
18:13:21.676 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 6274: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 237
18:13:21.677 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
18:13:21.678 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 6274: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 237
18:13:21.678 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 6274: (Callback 237)
18:13:21.679 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
18:13:21.680 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 6274: callback 237
18:13:21.682 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=237, payload=ED 00 00 0D
18:13:21.684 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 237, Status = Transmission complete and ACK received(0)
18:13:21.686 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:13:21.687 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 6274: Advanced to WAIT_DATA
18:13:21.688 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: TID 6274: Transaction not completed
18:13:21.689 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:13:21.690 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
18:13:21.799 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 07 07 60 0D 02 01 25 03 FF 41
18:13:21.800 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 07 60 0D 02 01 25 03 FF
18:13:21.801 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 07 60 0D 02 01 25 03 FF
18:13:21.803 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
18:13:21.804 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
18:13:21.805 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
18:13:21.806 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
18:13:21.808 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 2
18:13:21.809 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
18:13:21.810 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
18:13:21.811 [DEBUG] [ndclass.ZWaveBinarySwitchCommandClass] - NODE 7: Switch Binary report, value = 255
18:13:21.813 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
18:13:21.814 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 2, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255
18:13:21.815 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:dd7f023f:node7:switch_binary2 to ON [OnOffType]
18:13:21.818 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
18:13:21.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@452be234.
18:13:21.821 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@452be234.
18:13:21.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:6274 DONE
18:13:21.824 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:13:21.825 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
18:13:21.825 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
18:13:21.827 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
18:13:21.828 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.