2020-12-09 23:33:03.119 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Handler disposed. Unregistering listener.
2020-12-09 23:33:03.247 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 4: Serialise aborted as static stages not complete
2020-12-09 23:33:33.718 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:27900de427
2020-12-09 23:33:33.868 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
2020-12-09 23:33:33.870 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:27900de427
2020-12-09 23:33:33.871 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2020-12-09 23:33:33.873 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2020-12-09 23:33:33.875 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion
2020-12-09 23:33:33.876 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2020-12-09 23:33:33.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 2285 to queue - size 1
2020-12-09 23:33:33.882 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:33:33.884 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 8A FB 
2020-12-09 23:33:33.886 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 8A FB 
2020-12-09 23:33:33.888 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:33:33.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:33:33.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:33:33.909 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 8A 01 00 00 39 
2020-12-09 23:33:33.914 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:33:33.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2285: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 138
2020-12-09 23:33:33.925 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2285: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 138
2020-12-09 23:33:33.926 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:33:33.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:33:33.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:33:33.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=138, payload=8A 01 00 00 
2020-12-09 23:33:33.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=138, payload=8A 01 00 00 
2020-12-09 23:33:33.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2285: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 138
2020-12-09 23:33:33.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:33:33.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2285: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 138
2020-12-09 23:33:33.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2285: (Callback 138)
2020-12-09 23:33:33.952 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-12-09 23:33:33.954 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2285: callback 138
2020-12-09 23:33:33.955 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=138, payload=8A 01 00 00 
2020-12-09 23:33:33.957 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2020-12-09 23:33:33.958 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart
2020-12-09 23:33:33.961 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2285: Transaction COMPLETED
2020-12-09 23:33:33.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 46ms
2020-12-09 23:33:33.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 2285: Transaction completed
2020-12-09 23:33:33.965 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:2285 DONE
2020-12-09 23:33:33.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:33:33.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:33:35.918 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery completed
2020-12-09 23:33:36.021 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery resolved to thingType zwave:aeon_zw100_01_008
2020-12-09 23:33:36.031 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery completed
2020-12-09 23:33:36.036 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery resolved to thingType zwave:zipato_bulb2rgbw_00_000
2020-12-09 23:33:36.043 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery completed
2020-12-09 23:33:36.051 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery could not resolve to a thingType! Manufacturer data not known.
2020-12-09 23:33:36.055 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:27900de427:node4' to inbox.
2020-12-09 23:33:36.073 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed
2020-12-09 23:33:36.081 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! Manufacturer data not known.
2020-12-09 23:33:36.084 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery completed
2020-12-09 23:33:36.087 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery resolved to thingType zwave:devolo_mt2755_00_000
2020-12-09 23:34:01.405 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:27900de427:node4.
2020-12-09 23:34:01.419 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: MANUFACTURER not set
2020-12-09 23:34:01.422 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Controller status changed to ONLINE.
2020-12-09 23:34:01.424 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Controller is ONLINE. Starting device initialisation.
2020-12-09 23:34:01.463 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating node properties.
2020-12-09 23:34:01.468 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Updating node properties. MAN=2147483647
2020-12-09 23:34:01.470 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Properties synchronised
2020-12-09 23:34:01.474 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2020-12-09 23:34:01.475 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Initialising Thing Node...
2020-12-09 23:34:01.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling initialised at 1800 seconds - start in 1132200 milliseconds.
2020-12-09 23:34:01.479 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Device initialisation complete.
2020-12-09 23:34:33.870 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:27900de427
2020-12-09 23:34:33.968 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart
2020-12-09 23:34:33.970 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-12-09 23:34:33.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 2286 to queue - size 1
2020-12-09 23:34:33.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:34:33.977 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 8B 3E 
2020-12-09 23:34:33.979 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 8B 3E 
2020-12-09 23:34:33.982 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:34:34.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:34:34.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:34:34.004 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:34:34.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2286: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 139
2020-12-09 23:34:34.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2286: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 139
2020-12-09 23:34:34.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:34:34.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:34:34.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:34:34.090 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 8B 06 06 00 39 
2020-12-09 23:34:34.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=139, payload=8B 06 06 00 
2020-12-09 23:34:34.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=139, payload=8B 06 06 00 
2020-12-09 23:34:34.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2286: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 139
2020-12-09 23:34:34.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:34:34.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2286: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 139
2020-12-09 23:34:34.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2286: (Callback 139)
2020-12-09 23:34:34.122 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-12-09 23:34:34.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2286: callback 139
2020-12-09 23:34:34.124 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=139, payload=8B 06 06 00 
2020-12-09 23:34:34.126 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2020-12-09 23:34:34.128 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovered
2020-12-09 23:34:34.133 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveInclusionEvent
2020-12-09 23:34:34.134 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone
2020-12-09 23:34:34.135 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-12-09 23:34:34.136 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 2287 to queue - size 1
2020-12-09 23:34:34.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:34:34.138 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion
2020-12-09 23:34:34.139 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done
2020-12-09 23:34:34.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2286: Advanced to DONE
2020-12-09 23:34:34.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 135ms
2020-12-09 23:34:34.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 2286: Transaction completed
2020-12-09 23:34:34.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:2286 DONE
2020-12-09 23:34:34.145 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:34:34.146 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:34:34.149 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 
2020-12-09 23:34:34.150 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 
2020-12-09 23:34:34.152 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:34:34.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:34:34.174 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:34:34.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2287: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-12-09 23:34:34.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:34:34.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2287: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-12-09 23:34:34.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:34:34.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:34:34.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:34:39.177 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 2287: Timeout at state WAIT_REQUEST. 3 retries remaining.
2020-12-09 23:34:39.205 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 2287: Transaction is current transaction, so clearing!!!!!
2020-12-09 23:34:39.206 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2287: Transaction CANCELLED
2020-12-09 23:34:39.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:2287 CANCELLED
2020-12-09 23:34:39.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:36:26.446 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...
2020-12-09 23:36:26.463 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:27900de427:node2:sensor_relhumidity
2020-12-09 23:36:26.464 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 2: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:26.465 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Creating new message for command SENSOR_MULTILEVEL_GET
2020-12-09 23:36:26.466 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:26.467 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2020-12-09 23:36:26.468 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:27900de427:node2:sensor_temperature
2020-12-09 23:36:26.469 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 2: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:26.470 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Creating new message for command SENSOR_MULTILEVEL_GET
2020-12-09 23:36:26.471 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:26.471 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2020-12-09 23:36:26.472 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:27900de427:node2:sensor_luminance
2020-12-09 23:36:26.473 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 2: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:26.474 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Creating new message for command SENSOR_MULTILEVEL_GET
2020-12-09 23:36:26.475 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:26.476 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2020-12-09 23:36:26.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:27900de427:node2:sensor_ultraviolet
2020-12-09 23:36:26.477 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 2: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:26.478 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Creating new message for command SENSOR_MULTILEVEL_GET
2020-12-09 23:36:26.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:26.480 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2020-12-09 23:36:26.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:27900de427:node2:alarm_motion
2020-12-09 23:36:26.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
2020-12-09 23:36:26.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added 2288 to queue - size 2
2020-12-09 23:36:26.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:36:26.487 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 05 00 25 8D 79 
2020-12-09 23:36:26.488 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 05 00 25 8D 79 
2020-12-09 23:36:26.491 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:36:26.495 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:26.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:26.497 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-12-09 23:36:26.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:36:26.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:36:26.499 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:26.514 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:36:26.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2288: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 141
2020-12-09 23:36:26.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:26.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
2020-12-09 23:36:26.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added 2289 to queue - size 2
2020-12-09 23:36:26.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:26.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
2020-12-09 23:36:26.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added 2290 to queue - size 3
2020-12-09 23:36:26.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:26.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
2020-12-09 23:36:26.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added 2291 to queue - size 4
2020-12-09 23:36:26.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:26.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:26.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:26.547 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8D 00 00 02 64 
2020-12-09 23:36:26.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2288: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 141
2020-12-09 23:36:26.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:26.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2288: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 141
2020-12-09 23:36:26.552 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:26.553 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2020-12-09 23:36:26.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2288: Advanced to WAIT_REQUEST
2020-12-09 23:36:26.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2288: Transaction not completed
2020-12-09 23:36:26.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:26.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:26.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=141, payload=8D 00 00 02 
2020-12-09 23:36:26.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=141, payload=8D 00 00 02 
2020-12-09 23:36:26.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2288: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 141
2020-12-09 23:36:26.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:26.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2288: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 141
2020-12-09 23:36:26.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2288: (Callback 141)
2020-12-09 23:36:26.582 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-12-09 23:36:26.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2288: callback 141
2020-12-09 23:36:26.586 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=141, payload=8D 00 00 02 
2020-12-09 23:36:26.587 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 141, Status = Transmission complete and ACK received(0)
2020-12-09 23:36:26.589 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:26.591 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2288: Advanced to WAIT_DATA
2020-12-09 23:36:26.592 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2288: Transaction not completed
2020-12-09 23:36:26.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:26.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:27.109 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 02 05 31 05 05 01 3E F9 
2020-12-09 23:36:27.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 31 05 05 01 3E 
2020-12-09 23:36:27.136 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 31 05 05 01 3E 
2020-12-09 23:36:27.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:36:27.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:36:27.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:27.141 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:27.142 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:27.143 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:36:27.144 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Type = RelativeHumidity(5), Scale = 0
2020-12-09 23:36:27.146 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Value = 62
2020-12-09 23:36:27.147 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:36:27.148 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=62
2020-12-09 23:36:27.150 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 2: Sensor conversion not performed for RELATIVE_HUMIDITY.
2020-12-09 23:36:27.152 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:sensor_relhumidity to 62 [DecimalType]
2020-12-09 23:36:27.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:36:27.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@9ea3b9.
2020-12-09 23:36:27.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@9ea3b9.
2020-12-09 23:36:27.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:2288 DONE
2020-12-09 23:36:27.159 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-12-09 23:36:27.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-12-09 23:36:27.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:36:27.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:36:27.164 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 01 00 25 8E 7E 
2020-12-09 23:36:27.166 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 01 00 25 8E 7E 
2020-12-09 23:36:27.169 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:36:27.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:27.174 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-12-09 23:36:27.194 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:36:27.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2289: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 142
2020-12-09 23:36:27.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:27.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2289: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 142
2020-12-09 23:36:27.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:36:27.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:27.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:27.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:27.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2289: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 142
2020-12-09 23:36:27.228 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8E 00 00 02 67 
2020-12-09 23:36:27.228 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:27.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2289: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 142
2020-12-09 23:36:27.230 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:27.231 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2020-12-09 23:36:27.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2289: Advanced to WAIT_REQUEST
2020-12-09 23:36:27.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2289: Transaction not completed
2020-12-09 23:36:27.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:27.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=142, payload=8E 00 00 02 
2020-12-09 23:36:27.256 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=142, payload=8E 00 00 02 
2020-12-09 23:36:27.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2289: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 142
2020-12-09 23:36:27.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:27.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2289: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 142
2020-12-09 23:36:27.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2289: (Callback 142)
2020-12-09 23:36:27.260 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-12-09 23:36:27.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2289: callback 142
2020-12-09 23:36:27.263 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=142, payload=8E 00 00 02 
2020-12-09 23:36:27.265 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 142, Status = Transmission complete and ACK received(0)
2020-12-09 23:36:27.266 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:27.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2289: Advanced to WAIT_DATA
2020-12-09 23:36:27.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2289: Transaction not completed
2020-12-09 23:36:27.268 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:27.670 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 02 06 31 05 01 22 00 98 7C 
2020-12-09 23:36:27.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 06 31 05 01 22 00 98 
2020-12-09 23:36:27.696 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 06 31 05 01 22 00 98 
2020-12-09 23:36:27.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:36:27.750 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
2020-12-09 23:36:27.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:36:27.821 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:27.822 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:27.823 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:27.824 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:36:27.825 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Type = Temperature(1), Scale = 0
2020-12-09 23:36:27.827 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Value = 15.2
2020-12-09 23:36:27.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:36:27.830 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=15.2
2020-12-09 23:36:27.831 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:sensor_temperature to 15.2 °C [QuantityType]
2020-12-09 23:36:27.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:36:27.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1be6375.
2020-12-09 23:36:27.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1be6375.
2020-12-09 23:36:27.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:2289 DONE
2020-12-09 23:36:27.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-12-09 23:36:27.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-12-09 23:36:27.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:36:27.844 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:36:27.847 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 03 00 25 8F 7D 
2020-12-09 23:36:27.848 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 03 00 25 8F 7D 
2020-12-09 23:36:27.851 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:36:27.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:27.869 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-12-09 23:36:27.874 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:36:27.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2290: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 143
2020-12-09 23:36:27.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:27.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2290: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 143
2020-12-09 23:36:27.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:36:27.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.880 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:27.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:27.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:27.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2290: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 143
2020-12-09 23:36:27.906 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8F 00 00 02 66 
2020-12-09 23:36:27.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:27.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2290: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 143
2020-12-09 23:36:27.909 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:27.910 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2020-12-09 23:36:27.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2290: Advanced to WAIT_REQUEST
2020-12-09 23:36:27.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2290: Transaction not completed
2020-12-09 23:36:27.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:27.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 02 
2020-12-09 23:36:27.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 02 
2020-12-09 23:36:27.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2290: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 143
2020-12-09 23:36:27.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:27.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2290: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 143
2020-12-09 23:36:27.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2290: (Callback 143)
2020-12-09 23:36:27.939 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-12-09 23:36:27.940 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2290: callback 143
2020-12-09 23:36:27.941 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 02 
2020-12-09 23:36:27.942 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 143, Status = Transmission complete and ACK received(0)
2020-12-09 23:36:27.944 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:27.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2290: Advanced to WAIT_DATA
2020-12-09 23:36:27.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2290: Transaction not completed
2020-12-09 23:36:27.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:27.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:30.609 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 02 06 31 05 03 0A 00 00 CE 
2020-12-09 23:36:30.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 06 31 05 03 0A 00 00 
2020-12-09 23:36:30.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 06 31 05 03 0A 00 00 
2020-12-09 23:36:30.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:36:30.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:36:30.638 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:30.639 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:30.640 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:30.641 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:36:30.641 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Type = Luminance(3), Scale = 1
2020-12-09 23:36:30.642 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Value = 0E+1
2020-12-09 23:36:30.643 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:36:30.644 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2020-12-09 23:36:30.645 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:sensor_luminance to 0E+1 % [QuantityType]
2020-12-09 23:36:30.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:36:30.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@b23538.
2020-12-09 23:36:30.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@b23538.
2020-12-09 23:36:30.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:2290 DONE
2020-12-09 23:36:30.651 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-12-09 23:36:30.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-12-09 23:36:30.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:36:30.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:30.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:36:30.655 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 1B 00 25 90 7A 
2020-12-09 23:36:30.657 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 1B 00 25 90 7A 
2020-12-09 23:36:30.659 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:36:30.661 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:30.664 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-12-09 23:36:30.684 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:36:30.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2291: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 144
2020-12-09 23:36:30.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:36:30.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2291: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 144
2020-12-09 23:36:30.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:36:30.688 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:30.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:30.724 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:30.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:30.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2291: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 144
2020-12-09 23:36:30.726 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 90 00 00 02 79 
2020-12-09 23:36:30.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:30.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2291: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 144
2020-12-09 23:36:30.729 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2020-12-09 23:36:30.730 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2020-12-09 23:36:30.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2291: Advanced to WAIT_REQUEST
2020-12-09 23:36:30.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2291: Transaction not completed
2020-12-09 23:36:30.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:30.733 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:30.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=144, payload=90 00 00 02 
2020-12-09 23:36:30.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=144, payload=90 00 00 02 
2020-12-09 23:36:30.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2291: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 144
2020-12-09 23:36:30.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:36:30.758 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2291: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 144
2020-12-09 23:36:30.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2291: (Callback 144)
2020-12-09 23:36:30.760 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-12-09 23:36:30.761 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2291: callback 144
2020-12-09 23:36:30.762 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=144, payload=90 00 00 02 
2020-12-09 23:36:30.763 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 144, Status = Transmission complete and ACK received(0)
2020-12-09 23:36:30.764 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:30.765 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2291: Advanced to WAIT_DATA
2020-12-09 23:36:30.766 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 2291: Transaction not completed
2020-12-09 23:36:30.766 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:30.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:36:33.509 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 02 05 31 05 1B 01 00 D9 
2020-12-09 23:36:33.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 31 05 1B 01 00 
2020-12-09 23:36:33.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 05 31 05 1B 01 00 
2020-12-09 23:36:33.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:36:33.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:36:33.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:36:33.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:36:33.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:36:33.542 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:36:33.543 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Type = Ultraviolet(27), Scale = 0
2020-12-09 23:36:33.544 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Value = 0E+1
2020-12-09 23:36:33.545 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:36:33.546 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2020-12-09 23:36:33.547 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 2: Sensor conversion not performed for ULTRAVIOLET.
2020-12-09 23:36:33.549 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:sensor_ultraviolet to 0 [DecimalType]
2020-12-09 23:36:33.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:36:33.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@bcbc70.
2020-12-09 23:36:33.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@bcbc70.
2020-12-09 23:36:33.554 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:2291 DONE
2020-12-09 23:36:33.556 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-12-09 23:36:33.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-12-09 23:36:33.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:36:33.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:36:33.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:37:17.129 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 30 03 FF 3F 
2020-12-09 23:37:17.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 30 03 FF 
2020-12-09 23:37:17.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 30 03 FF 
2020-12-09 23:37:17.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:37:17.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:37:17.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:37:17.162 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2020-12-09 23:37:17.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:37:17.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2020-12-09 23:37:17.165 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 2: Sensor Binary report, type=Unknown, value=255
2020-12-09 23:37:17.166 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2020-12-09 23:37:17.167 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255
2020-12-09 23:37:17.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:37:17.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10842f5.
2020-12-09 23:37:17.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:17.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:17.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:37:17.172 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:37:17.330 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 08 00 00 67 
2020-12-09 23:37:17.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 08 00 00 
2020-12-09 23:37:17.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 08 00 00 
2020-12-09 23:37:17.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:37:17.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:37:17.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:37:17.359 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2020-12-09 23:37:17.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:37:17.361 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2020-12-09 23:37:17.361 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2020-12-09 23:37:17.362 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Alarm Type = BURGLAR (0)
2020-12-09 23:37:17.363 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2020-12-09 23:37:17.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2020-12-09 23:37:17.365 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter processing NOTIFICATION
2020-12-09 23:37:17.367 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter NOTIFICATION event is 8, type OnOffType
2020-12-09 23:37:17.368 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter processing NOTIFICATION
2020-12-09 23:37:17.369 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter NOTIFICATION event is 8, type OnOffType
2020-12-09 23:37:17.370 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:alarm_motion to ON [OnOffType]
2020-12-09 23:37:17.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:37:17.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@16d3cea.
2020-12-09 23:37:17.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:17.374 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:17.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:37:17.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:37:24.713 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1F 00 04 00 05 19 8F 01 04 03 80 03 18 04 30 03 FF 0C 05 31 05 03 01 01 06 31 05 01 0A 00 45 60 
2020-12-09 23:37:24.745 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 19 8F 01 04 03 80 03 18 04 30 03 FF 0C 05 31 05 03 01 01 06 31 05 01 0A 00 45 
2020-12-09 23:37:24.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 19 8F 01 04 03 80 03 18 04 30 03 FF 0C 05 31 05 03 01 01 06 31 05 01 0A 00 45 
2020-12-09 23:37:24.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:37:24.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2020-12-09 23:37:24.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2020-12-09 23:37:24.751 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_MULTI_CMD
2020-12-09 23:37:24.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
2020-12-09 23:37:24.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BATTERY
2020-12-09 23:37:24.754 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT
2020-12-09 23:37:24.755 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 5: Battery report value = 24
2020-12-09 23:37:24.757 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-12-09 23:37:24.758 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=24
2020-12-09 23:37:24.759 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2020-12-09 23:37:24.760 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
2020-12-09 23:37:24.761 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
2020-12-09 23:37:24.762 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 12
2020-12-09 23:37:24.764 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - Sensor Type is MOTION
2020-12-09 23:37:24.765 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 5: Sensor Binary report, type=Motion, value=255
2020-12-09 23:37:24.766 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2020-12-09 23:37:24.767 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255
2020-12-09 23:37:24.768 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:37:24.769 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-12-09 23:37:24.770 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:37:24.771 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Luminance(3), Scale = 0
2020-12-09 23:37:24.772 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 1
2020-12-09 23:37:24.773 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:37:24.774 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=1
2020-12-09 23:37:24.775 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:37:24.776 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-12-09 23:37:24.777 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:37:24.778 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Temperature(1), Scale = 1
2020-12-09 23:37:24.779 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 69
2020-12-09 23:37:24.780 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:37:24.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=69
2020-12-09 23:37:24.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 4.
2020-12-09 23:37:24.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f5da8a.
2020-12-09 23:37:24.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fda118.
2020-12-09 23:37:24.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.789 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@254638.
2020-12-09 23:37:24.790 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.791 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@83aa7a.
2020-12-09 23:37:24.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:24.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:37:24.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:37:44.311 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 05 08 8F 01 01 04 30 03 00 0C 4C 
2020-12-09 23:37:44.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 08 8F 01 01 04 30 03 00 0C 
2020-12-09 23:37:44.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 08 8F 01 01 04 30 03 00 0C 
2020-12-09 23:37:44.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:37:44.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2020-12-09 23:37:44.348 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2020-12-09 23:37:44.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_MULTI_CMD
2020-12-09 23:37:44.369 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2020-12-09 23:37:44.379 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_BINARY
2020-12-09 23:37:44.380 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_BINARY V2 SENSOR_BINARY_REPORT
2020-12-09 23:37:44.383 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - Processing Sensor Type 12
2020-12-09 23:37:44.388 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - Sensor Type is MOTION
2020-12-09 23:37:44.390 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 5: Sensor Binary report, type=Motion, value=0
2020-12-09 23:37:44.391 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2020-12-09 23:37:44.396 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0
2020-12-09 23:37:44.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2020-12-09 23:37:44.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@ca0e2a.
2020-12-09 23:37:44.403 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:44.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:37:44.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:37:44.412 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:39:04.653 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update received
2020-12-09 23:39:04.770 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update set action_reinit to true (Boolean)
2020-12-09 23:39:04.774 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Re-initialising node!
2020-12-09 23:39:04.823 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2020-12-09 23:39:04.827 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored wakeup_node to 1 (BigDecimal)
2020-12-09 23:39:04.827 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 5: Init node thread start
2020-12-09 23:39:04.831 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored wakeup_interval to 3600 (BigDecimal)
2020-12-09 23:39:04.836 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored group_1 to [] (EmptyList)
2020-12-09 23:39:04.838 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored node_id to 5 (BigDecimal)
2020-12-09 23:39:04.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored group_2 to [] (EmptyList)
2020-12-09 23:39:04.898 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 5: Serializing from file /var/lib/openhab/zwave/network_c6edc37f__node_5.xml
2020-12-09 23:39:04.900 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 5: Error serializing from file: file does not exist.
2020-12-09 23:39:04.902 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from EMPTYNODE
2020-12-09 23:39:04.906 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 5: Init node thread finished
2020-12-09 23:39:04.907 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer - advancing to IDENTIFY_NODE
2020-12-09 23:39:04.925 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2020-12-09 23:39:04.926 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer: Initialisation starting
2020-12-09 23:39:04.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@10c0e29
2020-12-09 23:39:04.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 2292 to queue - size 1
2020-12-09 23:39:04.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:39:04.937 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 05 BF 
2020-12-09 23:39:04.938 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 05 BF 
2020-12-09 23:39:04.941 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-12-09 23:39:04.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:39:04.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-12-09 23:39:04.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 53 9C 01 04 07 01 7A 
2020-12-09 23:39:04.965 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-12-09 23:39:04.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2292: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-12-09 23:39:04.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2292: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-12-09 23:39:04.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-12-09 23:39:04.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:39:04.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-12-09 23:39:04.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=53 9C 01 04 07 01 
2020-12-09 23:39:04.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=53 9C 01 04 07 01 
2020-12-09 23:39:04.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2292: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-12-09 23:39:05.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-12-09 23:39:05.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2292: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-12-09 23:39:05.003 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=53 9C 01 04 07 01 
2020-12-09 23:39:05.005 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: ProtocolInfo
2020-12-09 23:39:05.006 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Listening = false
2020-12-09 23:39:05.007 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Routing   = true
2020-12-09 23:39:05.008 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Beaming   = true
2020-12-09 23:39:05.009 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Version   = 4
2020-12-09 23:39:05.010 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: FLIRS     = false
2020-12-09 23:39:05.011 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Security  = false
2020-12-09 23:39:05.012 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Max Baud  = 40000
2020-12-09 23:39:05.013 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Basic    = BASIC_TYPE_ROUTING_SLAVE
2020-12-09 23:39:05.014 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Generic  = GENERIC_TYPE_SENSOR_NOTIFICATION
2020-12-09 23:39:05.016 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 5: Specific = SPECIFIC_TYPE_NOTIFICATION_SENSOR
2020-12-09 23:39:05.017 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Creating new instance of command class COMMAND_CLASS_NO_OPERATION
2020-12-09 23:39:05.018 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created
2020-12-09 23:39:05.021 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Version = 1, version set. Enabling extra functionality.
2020-12-09 23:39:05.023 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes.
2020-12-09 23:39:05.026 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Creating new instance of command class COMMAND_CLASS_BASIC
2020-12-09 23:39:05.029 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Command class COMMAND_CLASS_BASIC, endpoint 0 created
2020-12-09 23:39:05.030 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes.
2020-12-09 23:39:05.031 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2292: Transaction COMPLETED
2020-12-09 23:39:05.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 65ms
2020-12-09 23:39:05.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 2292: Transaction completed
2020-12-09 23:39:05.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:2292 DONE
2020-12-09 23:39:05.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:39:05.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2292: Transaction event listener: DONE: DONE -> 
2020-12-09 23:39:05.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:39:05.053 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1f46559
2020-12-09 23:39:05.054 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Node Init transaction completed with response COMPLETE
2020-12-09 23:39:05.055 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Node advancer - advancing to REQUEST_NIF
2020-12-09 23:39:05.057 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2020-12-09 23:39:05.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@f6f803
2020-12-09 23:39:05.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Bump transaction 2293 priority from Controller to Immediate
2020-12-09 23:39:05.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue
2020-12-09 23:39:05.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Added 2293 to queue - size 2
2020-12-09 23:39:05.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:41:02.206 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 00 04 12 9F 03 9D 00 E0 9C 7C D5 74 3E 6A DE 1A 9D 5C 8F E2 88 E1 
2020-12-09 23:41:02.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 12 9F 03 9D 00 E0 9C 7C D5 74 3E 6A DE 1A 9D 5C 8F E2 88 
2020-12-09 23:41:02.237 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 12 9F 03 9D 00 E0 9C 7C D5 74 3E 6A DE 1A 9D 5C 8F E2 88 
2020-12-09 23:41:02.240 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:41:02.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:REQUEST_NIF)
2020-12-09 23:41:02.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SECURITY_2, endpoint 0
2020-12-09 23:41:02.242 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Command class COMMAND_CLASS_SECURITY_2 not found, trying to add it.
2020-12-09 23:41:02.243 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Unsupported command class COMMAND_CLASS_SECURITY_2
2020-12-09 23:41:02.244 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Unsupported Z-Wave command class COMMAND_CLASS_SECURITY_2
2020-12-09 23:41:02.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2020-12-09 23:41:02.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2b077c.
2020-12-09 23:41:02.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:41:02.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:41:02.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:41:02.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:41:51.449 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 30 03 00 C0 
2020-12-09 23:41:51.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 30 03 00 
2020-12-09 23:41:51.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 30 03 00 
2020-12-09 23:41:51.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:41:51.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:41:51.478 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:41:51.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2020-12-09 23:41:51.480 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:41:51.481 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2020-12-09 23:41:51.482 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 2: Sensor Binary report, type=Unknown, value=0
2020-12-09 23:41:51.483 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2020-12-09 23:41:51.485 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0
2020-12-09 23:41:51.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:41:51.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19caf39.
2020-12-09 23:41:51.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:41:51.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:41:51.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:41:51.491 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:41:51.650 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 00 00 00 6F 
2020-12-09 23:41:51.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 00 00 00 
2020-12-09 23:41:51.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 00 00 00 
2020-12-09 23:41:51.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:41:51.678 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:41:51.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:41:51.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2020-12-09 23:41:51.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:41:51.682 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2020-12-09 23:41:51.683 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0
2020-12-09 23:41:51.684 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Alarm Type = BURGLAR (0)
2020-12-09 23:41:51.685 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2020-12-09 23:41:51.686 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2020-12-09 23:41:51.687 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter processing NOTIFICATION
2020-12-09 23:41:51.689 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter NOTIFICATION event is 0, type OnOffType
2020-12-09 23:41:51.690 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:alarm_tamper to OFF [OnOffType]
2020-12-09 23:41:51.691 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter processing NOTIFICATION
2020-12-09 23:41:51.692 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter NOTIFICATION event is 0, type OnOffType
2020-12-09 23:41:51.693 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:alarm_motion to OFF [OnOffType]
2020-12-09 23:41:51.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:41:51.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@16544fe.
2020-12-09 23:41:51.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:41:51.700 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:41:51.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:41:51.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:48:54.708 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 30 03 FF 3F 
2020-12-09 23:48:54.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 30 03 FF 
2020-12-09 23:48:54.789 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 30 03 FF 
2020-12-09 23:48:54.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:48:54.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:48:54.807 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:48:54.808 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2020-12-09 23:48:54.809 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:48:54.810 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2020-12-09 23:48:54.811 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 2: Sensor Binary report, type=Unknown, value=255
2020-12-09 23:48:54.813 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2020-12-09 23:48:54.815 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255
2020-12-09 23:48:54.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:48:54.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19409d0.
2020-12-09 23:48:54.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:54.819 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:54.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:48:54.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:48:54.910 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 08 00 00 67 
2020-12-09 23:48:54.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 08 00 00 
2020-12-09 23:48:54.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 08 00 00 
2020-12-09 23:48:54.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:48:54.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE)
2020-12-09 23:48:54.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
2020-12-09 23:48:54.940 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2020-12-09 23:48:54.941 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2020-12-09 23:48:54.942 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2020-12-09 23:48:54.943 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2020-12-09 23:48:54.944 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Alarm Type = BURGLAR (0)
2020-12-09 23:48:54.945 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2020-12-09 23:48:54.946 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2020-12-09 23:48:54.947 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter processing NOTIFICATION
2020-12-09 23:48:54.949 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter NOTIFICATION event is 8, type OnOffType
2020-12-09 23:48:54.950 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter processing NOTIFICATION
2020-12-09 23:48:54.951 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 2: Alarm converter NOTIFICATION event is 8, type OnOffType
2020-12-09 23:48:54.952 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:27900de427:node2:alarm_motion to ON [OnOffType]
2020-12-09 23:48:54.954 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1.
2020-12-09 23:48:54.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1cef6f9.
2020-12-09 23:48:54.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:54.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:54.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:48:54.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:48:59.307 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1F 00 04 00 05 19 8F 01 04 03 80 03 46 04 30 03 FF 0C 05 31 05 03 01 01 06 31 05 01 0A 00 45 3E 
2020-12-09 23:48:59.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 19 8F 01 04 03 80 03 46 04 30 03 FF 0C 05 31 05 03 01 01 06 31 05 01 0A 00 45 
2020-12-09 23:48:59.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 19 8F 01 04 03 80 03 46 04 30 03 FF 0C 05 31 05 03 01 01 06 31 05 01 0A 00 45 
2020-12-09 23:48:59.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:48:59.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:REQUEST_NIF)
2020-12-09 23:48:59.339 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_MULTI_CMD
2020-12-09 23:48:59.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Command class COMMAND_CLASS_MULTI_CMD not found, trying to add it.
2020-12-09 23:48:59.341 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Creating new instance of command class COMMAND_CLASS_MULTI_CMD
2020-12-09 23:48:59.342 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Command class COMMAND_CLASS_MULTI_CMD, endpoint 0 created
2020-12-09 23:48:59.344 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Adding command class COMMAND_CLASS_MULTI_CMD to endpoint 0
2020-12-09 23:48:59.345 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0
2020-12-09 23:48:59.346 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Command class COMMAND_CLASS_BATTERY not found, trying to add it.
2020-12-09 23:48:59.347 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Creating new instance of command class COMMAND_CLASS_BATTERY
2020-12-09 23:48:59.348 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Command class COMMAND_CLASS_BATTERY, endpoint 0 created
2020-12-09 23:48:59.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Adding command class COMMAND_CLASS_BATTERY to endpoint 0
2020-12-09 23:48:59.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported
2020-12-09 23:48:59.351 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BATTERY V0 BATTERY_REPORT
2020-12-09 23:48:59.352 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 5: Battery report value = 70
2020-12-09 23:48:59.354 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-12-09 23:48:59.355 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=70
2020-12-09 23:48:59.355 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2020-12-09 23:48:59.356 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Command class COMMAND_CLASS_SENSOR_BINARY not found, trying to add it.
2020-12-09 23:48:59.357 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Creating new instance of command class COMMAND_CLASS_SENSOR_BINARY
2020-12-09 23:48:59.358 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0 created
2020-12-09 23:48:59.359 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Adding command class COMMAND_CLASS_SENSOR_BINARY to endpoint 0
2020-12-09 23:48:59.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported
2020-12-09 23:48:59.361 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_BINARY V0 SENSOR_BINARY_REPORT
2020-12-09 23:48:59.361 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 5: Sensor Binary report, type=Unknown, value=255
2020-12-09 23:48:59.362 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2020-12-09 23:48:59.363 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255
2020-12-09 23:48:59.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:48:59.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Command class COMMAND_CLASS_SENSOR_MULTILEVEL not found, trying to add it.
2020-12-09 23:48:59.366 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Creating new instance of command class COMMAND_CLASS_SENSOR_MULTILEVEL
2020-12-09 23:48:59.367 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 created
2020-12-09 23:48:59.368 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Adding command class COMMAND_CLASS_SENSOR_MULTILEVEL to endpoint 0
2020-12-09 23:48:59.369 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported
2020-12-09 23:48:59.370 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V0 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:48:59.370 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Adding new sensor Type = Luminance(3)
2020-12-09 23:48:59.371 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Luminance(3), Scale = 0
2020-12-09 23:48:59.372 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 1
2020-12-09 23:48:59.373 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:48:59.374 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=1
2020-12-09 23:48:59.375 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-12-09 23:48:59.376 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported
2020-12-09 23:48:59.377 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V0 SENSOR_MULTILEVEL_REPORT
2020-12-09 23:48:59.378 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Adding new sensor Type = Temperature(1)
2020-12-09 23:48:59.379 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = Temperature(1), Scale = 1
2020-12-09 23:48:59.379 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 69
2020-12-09 23:48:59.380 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-12-09 23:48:59.381 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=69
2020-12-09 23:48:59.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 4.
2020-12-09 23:48:59.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1bb755e.
2020-12-09 23:48:59.384 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.385 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.385 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@e490e3.
2020-12-09 23:48:59.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1dbd3e6.
2020-12-09 23:48:59.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1da98da.
2020-12-09 23:48:59.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:48:59.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:48:59.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-12-09 23:49:18.525 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 05 08 8F 01 01 04 30 03 00 0C 4C 
2020-12-09 23:49:18.554 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 08 8F 01 01 04 30 03 00 0C 
2020-12-09 23:49:18.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 08 8F 01 01 04 30 03 00 0C 
2020-12-09 23:49:18.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-12-09 23:49:18.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:REQUEST_NIF)
2020-12-09 23:49:18.559 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Decapsulating COMMAND_CLASS_MULTI_CMD
2020-12-09 23:49:18.559 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2020-12-09 23:49:18.560 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported
2020-12-09 23:49:18.561 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_BINARY V0 SENSOR_BINARY_REPORT
2020-12-09 23:49:18.562 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 5: Sensor Binary report, type=Unknown, value=0
2020-12-09 23:49:18.563 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2020-12-09 23:49:18.564 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0
2020-12-09 23:49:18.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2020-12-09 23:49:18.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@18f0ffa.
2020-12-09 23:49:18.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:49:18.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-12-09 23:49:18.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-12-09 23:49:18.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.