2019-10-22 15:44:10.921 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:400e534b
2019-10-22 15:44:10.922 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
2019-10-22 15:44:10.922 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:400e534b
2019-10-22 15:44:10.922 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2019-10-22 15:44:10.923 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Event listener added.
2019-10-22 15:44:10.923 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - ZWave controller start inclusion
2019-10-22 15:44:10.923 [DEBUG] [nternal.protocol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2019-10-22 15:44:10.923 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: Added 71 to queue - size 1
2019-10-22 15:44:10.923 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-10-22 15:44:10.923 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 2E 5F 
2019-10-22 15:44:10.924 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 2E 5F 
2019-10-22 15:44:10.924 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-10-22 15:44:10.924 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 71: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46
2019-10-22 15:44:10.925 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-10-22 15:44:10.925 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-10-22 15:44:10.926 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 2E 01 00 00 9D 
2019-10-22 15:44:10.927 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=46, payload=2E 01 00 00 
2019-10-22 15:44:10.928 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-10-22 15:44:10.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 71: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46
2019-10-22 15:44:10.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-10-22 15:44:10.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=46, payload=2E 01 00 00 
2019-10-22 15:44:10.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 71: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46
2019-10-22 15:44:10.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-10-22 15:44:10.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 71: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46
2019-10-22 15:44:10.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 71: (Callback 46)
2019-10-22 15:44:10.930 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-10-22 15:44:10.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 71: callback 46
2019-10-22 15:44:10.930 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=46, payload=2E 01 00 00 
2019-10-22 15:44:10.930 [DEBUG] [nternal.protocol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2019-10-22 15:44:10.930 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart
2019-10-22 15:44:10.930 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 71: Transaction COMPLETED
2019-10-22 15:44:10.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 6ms
2019-10-22 15:44:10.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: TID 71: Transaction completed
2019-10-22 15:44:10.931 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:71 DONE
2019-10-22 15:44:10.931 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-10-22 15:44:10.931 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-10-22 15:44:12.927 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery completed
2019-10-22 15:44:12.929 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery resolved to thingType zwave:leviton_dz15s_00_000
2019-10-22 15:44:12.932 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery completed
2019-10-22 15:44:12.935 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery resolved to thingType zwave:leviton_dz6hd_00_000
2019-10-22 15:44:12.937 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery completed
2019-10-22 15:44:12.938 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery resolved to thingType zwave:aeon_zw130_00_000
2019-10-22 15:44:12.939 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed
2019-10-22 15:44:12.941 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery resolved to thingType zwave:leviton_dz6hd_00_000
2019-10-22 15:44:12.942 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery completed
2019-10-22 15:44:12.943 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery resolved to thingType zwave:leviton_dz6hd_00_000
2019-10-22 15:44:12.944 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery completed
2019-10-22 15:44:12.945 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery resolved to thingType zwave:leviton_dz6hd_00_000
2019-10-22 15:44:12.946 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery completed
2019-10-22 15:44:12.947 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery resolved to thingType zwave:leviton_dz6hd_00_000
2019-10-22 15:44:12.949 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 9: Device discovery completed
2019-10-22 15:44:12.951 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 9: Device discovery resolved to thingType zwave:leviton_dz6hd_00_000
2019-10-22 15:44:12.952 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 12: Device discovery completed
2019-10-22 15:44:12.954 [WARN ] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - NODE 12: Device discovery could not resolve to a thingType! Manufacturer data not known.
2019-10-22 15:44:40.924 [DEBUG] [nhab.binding.zwave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:400e534b
2019-10-22 15:44:40.925 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart
2019-10-22 15:44:40.925 [DEBUG] [nternal.protocol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2019-10-22 15:44:40.925 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: Added 72 to queue - size 1
2019-10-22 15:44:40.926 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-10-22 15:44:40.926 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 2F 9A 
2019-10-22 15:44:40.927 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 2F 9A 
2019-10-22 15:44:40.927 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-10-22 15:44:40.928 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 72: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 47
2019-10-22 15:44:40.932 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-10-22 15:44:40.932 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-10-22 15:44:40.932 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-10-22 15:44:40.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 72: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 47
2019-10-22 15:44:40.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-10-22 15:44:40.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-10-22 15:44:40.933 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-10-22 15:44:41.020 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 2F 06 00 00 9B 
2019-10-22 15:44:41.021 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=47, payload=2F 06 00 00 
2019-10-22 15:44:41.021 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=47, payload=2F 06 00 00 
2019-10-22 15:44:41.021 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 72: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 47
2019-10-22 15:44:41.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-10-22 15:44:41.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 72: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 47
2019-10-22 15:44:41.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 72: (Callback 47)
2019-10-22 15:44:41.022 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-10-22 15:44:41.022 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 72: callback 47
2019-10-22 15:44:41.023 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=47, payload=2F 06 00 00 
2019-10-22 15:44:41.023 [DEBUG] [nternal.protocol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2019-10-22 15:44:41.023 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone
2019-10-22 15:44:41.023 [DEBUG] [nternal.protocol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2019-10-22 15:44:41.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: Added 73 to queue - size 1
2019-10-22 15:44:41.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-10-22 15:44:41.024 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - ZWave controller end exclusion
2019-10-22 15:44:41.024 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - ZWaveController include done
2019-10-22 15:44:41.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 72: Advanced to DONE
2019-10-22 15:44:41.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 97ms
2019-10-22 15:44:41.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: TID 72: Transaction completed
2019-10-22 15:44:41.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:72 DONE
2019-10-22 15:44:41.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-10-22 15:44:41.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-10-22 15:44:41.025 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 
2019-10-22 15:44:41.025 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 
2019-10-22 15:44:41.027 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-10-22 15:44:41.027 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 73: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2019-10-22 15:44:41.028 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-10-22 15:44:41.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-10-22 15:44:41.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-10-22 15:44:41.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 73: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2019-10-22 15:44:41.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-10-22 15:44:41.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-10-22 15:44:41.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-10-22 15:44:46.028 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 255: TID 73: Timeout at state WAIT_REQUEST. 3 retries remaining.
2019-10-22 15:44:46.028 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 73: Transaction is current transaction, so clearing!!!!!
2019-10-22 15:44:46.028 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 73: Transaction CANCELLED
2019-10-22 15:44:46.029 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:73 CANCELLED
2019-10-22 15:44:46.029 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-10-22 15:44:50.300 [DEBUG] [org.openhab.binding.zwave                         ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.status.ConfigStatusProvider}={service.id=385, service.bundleid=206, service.scope=singleton} - org.openhab.binding.zwave
2019-10-22 15:44:50.303 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:400e534b:node12.
2019-10-22 15:44:50.306 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: MANUFACTURER not set
2019-10-22 15:44:50.306 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Controller status changed to ONLINE.
2019-10-22 15:44:50.307 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Controller is ONLINE. Starting device initialisation.
2019-10-22 15:44:50.308 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating node properties.
2019-10-22 15:44:50.309 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating node properties. MAN=2147483647
2019-10-22 15:44:50.309 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Properties synchronised
2019-10-22 15:44:50.310 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Event listener added.
2019-10-22 15:44:50.310 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Initialising Thing Node...
2019-10-22 15:44:50.311 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling intialised at 1800 seconds - start in 811800 milliseconds.
2019-10-22 15:44:50.311 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Device initialisation complete.
2019-10-22 15:44:57.667 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
2019-10-22 15:44:57.668 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
2019-10-22 15:44:57.668 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
2019-10-22 15:44:57.668 [DEBUG] [g.zwave.internal.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised