2018-12-12 00:34:57.874 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:512 2018-12-12 00:34:57.878 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do 2018-12-12 00:34:57.886 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:512 2018-12-12 00:34:57.889 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2 2018-12-12 00:34:57.896 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added. 2018-12-12 00:34:57.900 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion 2018-12-12 00:34:57.908 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true. 2018-12-12 00:34:57.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 132 to queue - size 1 2018-12-12 00:34:57.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:34:57.920 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 5D 2C 2018-12-12 00:34:57.925 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 5D 2C 2018-12-12 00:34:57.928 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:34:57.930 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:34:57.932 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:34:57.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:34:57.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 132: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 93 2018-12-12 00:34:57.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:34:57.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:34:57.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:34:57.944 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 5D 01 00 00 EE 2018-12-12 00:34:57.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:34:57.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=93, payload=5D 01 00 00 2018-12-12 00:34:57.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=93, payload=5D 01 00 00 2018-12-12 00:34:57.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 132: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 93 2018-12-12 00:34:57.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:34:57.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 132: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 93 2018-12-12 00:34:57.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 132: (Callback 93) 2018-12-12 00:34:57.973 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:34:57.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 132: callback 93 2018-12-12 00:34:57.976 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=93, payload=5D 01 00 00 2018-12-12 00:34:57.977 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready. ==> /var/log/openhab2/events.log <== 2018-12-12 00:34:57.985 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@1a972b5 ==> /var/log/openhab2/openhab.log <== 2018-12-12 00:34:57.987 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart 2018-12-12 00:34:57.989 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 132: Transaction COMPLETED 2018-12-12 00:34:57.990 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 58ms 2018-12-12 00:34:57.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 132: Transaction completed 2018-12-12 00:34:57.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:132 DONE 2018-12-12 00:34:57.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:34:57.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:34:59.943 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 32: Device discovery completed 2018-12-12 00:34:59.970 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 32: Device discovery resolved to thingType zwave:elexa_dms01_00_000 2018-12-12 00:34:59.985 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed 2018-12-12 00:34:59.994 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery resolved to thingType zwave:aeon_zw112_00_000 2018-12-12 00:35:00.004 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 37: Device discovery completed 2018-12-12 00:35:00.009 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 37: Device discovery resolved to thingType zwave:mcohome_mhs513_00_000 2018-12-12 00:35:00.017 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 39: Device discovery completed 2018-12-12 00:35:00.023 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 39: Device discovery resolved to thingType zwave:aeon_zw100_01_008 2018-12-12 00:35:00.031 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 40: Device discovery completed 2018-12-12 00:35:00.037 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 40: Device discovery resolved to thingType zwave:yale_yrd220_00_000 2018-12-12 00:35:00.046 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 43: Device discovery completed 2018-12-12 00:35:00.050 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 43: Device discovery resolved to thingType zwave:aeon_zw100_01_008 2018-12-12 00:35:00.056 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 13: Device discovery completed 2018-12-12 00:35:00.060 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 13: Device discovery resolved to thingType zwave:aeon_zw112_00_000 2018-12-12 00:35:00.066 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 45: Device discovery completed 2018-12-12 00:35:00.072 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 45: Device discovery resolved to thingType zwave:wintop_wtrfid_00_000 2018-12-12 00:35:00.081 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 27: Device discovery completed 2018-12-12 00:35:00.085 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 27: Device discovery resolved to thingType zwave:aeon_zw100_01_008 2018-12-12 00:35:00.091 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 28: Device discovery completed 2018-12-12 00:35:00.094 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 28: Device discovery resolved to thingType zwave:aeon_zw100_01_008 2018-12-12 00:35:00.100 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 29: Device discovery completed 2018-12-12 00:35:00.104 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 29: Device discovery resolved to thingType zwave:aeon_zw100_01_008 2018-12-12 00:35:00.110 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 30: Device discovery completed 2018-12-12 00:35:00.114 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 30: Device discovery resolved to thingType zwave:aeon_zw100_01_008 2018-12-12 00:35:05.517 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 5D 02 00 00 ED 2018-12-12 00:35:05.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=2, callback=93, payload=5D 02 00 00 2018-12-12 00:35:05.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=2, callback=93, payload=5D 02 00 00 2018-12-12 00:35:05.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:35:05.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2018-12-12 00:35:05.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null 2018-12-12 00:35:05.532 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=2, callback=93, payload=5D 02 00 00 2018-12-12 00:35:05.534 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: New node found. 2018-12-12 00:35:05.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:05.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:05.803 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 4A 5D 03 32 19 04 10 01 5E 85 59 8E 60 55 86 72 5A 73 25 27 70 2C 2B 5B 20 7A EF 20 26 5B D7 2018-12-12 00:35:05.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=3, callback=93, payload=5D 03 32 19 04 10 01 5E 85 59 8E 60 55 86 72 5A 73 25 27 70 2C 2B 5B 20 7A EF 20 26 5B 2018-12-12 00:35:05.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=3, callback=93, payload=5D 03 32 19 04 10 01 5E 85 59 8E 60 55 86 72 5A 73 25 27 70 2C 2B 5B 20 7A EF 20 26 5B 2018-12-12 00:35:05.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:35:05.819 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2018-12-12 00:35:05.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null 2018-12-12 00:35:05.824 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=3, callback=93, payload=5D 03 32 19 04 10 01 5E 85 59 8E 60 55 86 72 5A 73 25 27 70 2C 2B 5B 20 7A EF 20 26 5B 2018-12-12 00:35:05.827 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - NODE 50: Adding slave. 2018-12-12 00:35:05.829 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeStart, new event IncludeSlaveFound 2018-12-12 00:35:05.836 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Including node. 2018-12-12 00:35:05.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:05.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:08.907 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 5D 05 32 00 D8 2018-12-12 00:35:08.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=93, payload=5D 05 32 00 2018-12-12 00:35:08.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=93, payload=5D 05 32 00 2018-12-12 00:35:08.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:35:08.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2018-12-12 00:35:08.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null 2018-12-12 00:35:08.921 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=93, payload=5D 05 32 00 2018-12-12 00:35:08.923 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - NODE 50: Add Node: Protocol done. 2018-12-12 00:35:08.925 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSlaveFound, new event IncludeProtocolDone 2018-12-12 00:35:08.932 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode. 2018-12-12 00:35:08.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 133 to queue - size 1 2018-12-12 00:35:08.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:08.939 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 5E EB 2018-12-12 00:35:08.941 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 5E EB 2018-12-12 00:35:08.943 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:35:08.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 133: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 94 2018-12-12 00:35:08.946 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:35:08.950 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion protocol completed. 2018-12-12 00:35:08.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:08.954 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_NO_OPERATION 2018-12-12 00:35:08.957 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created 2018-12-12 00:35:08.960 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Version = 1, version set. Enabling extra functionality. 2018-12-12 00:35:08.964 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes. 2018-12-12 00:35:08.967 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_BASIC 2018-12-12 00:35:08.971 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_BASIC, endpoint 0 created 2018-12-12 00:35:08.974 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes. 2018-12-12 00:35:08.977 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO 2018-12-12 00:35:08.981 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created 2018-12-12 00:35:08.984 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO. 2018-12-12 00:35:08.987 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes. 2018-12-12 00:35:08.990 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_ASSOCIATION 2018-12-12 00:35:08.994 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_ASSOCIATION, endpoint 0 created 2018-12-12 00:35:08.997 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_ASSOCIATION. 2018-12-12 00:35:08.997 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 5E 06 32 00 D8 2018-12-12 00:35:09.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=94, payload=5E 06 32 00 2018-12-12 00:35:09.000 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_ASSOCIATION to the list of supported command classes. 2018-12-12 00:35:09.003 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_ASSOCIATION_GRP_INFO 2018-12-12 00:35:09.007 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_ASSOCIATION_GRP_INFO, endpoint 0 created 2018-12-12 00:35:09.010 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO. 2018-12-12 00:35:09.013 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO to the list of supported command classes. 2018-12-12 00:35:09.016 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION 2018-12-12 00:35:09.020 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION, endpoint 0 created 2018-12-12 00:35:09.023 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION. 2018-12-12 00:35:09.025 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION to the list of supported command classes. 2018-12-12 00:35:09.028 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_MULTI_CHANNEL 2018-12-12 00:35:09.032 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_MULTI_CHANNEL, endpoint 0 created 2018-12-12 00:35:09.035 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_MULTI_CHANNEL. 2018-12-12 00:35:09.038 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_MULTI_CHANNEL to the list of supported command classes. 2018-12-12 00:35:09.040 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE 2018-12-12 00:35:09.041 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_VERSION 2018-12-12 00:35:09.044 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_VERSION, endpoint 0 created 2018-12-12 00:35:09.045 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_VERSION. 2018-12-12 00:35:09.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_VERSION to the list of supported command classes. 2018-12-12 00:35:09.049 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2018-12-12 00:35:09.051 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created 2018-12-12 00:35:09.053 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC. 2018-12-12 00:35:09.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes. 2018-12-12 00:35:09.056 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY 2018-12-12 00:35:09.058 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created 2018-12-12 00:35:09.060 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY. 2018-12-12 00:35:09.062 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes. 2018-12-12 00:35:09.064 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_POWERLEVEL 2018-12-12 00:35:09.066 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_POWERLEVEL, endpoint 0 created 2018-12-12 00:35:09.067 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_POWERLEVEL. 2018-12-12 00:35:09.069 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_POWERLEVEL to the list of supported command classes. 2018-12-12 00:35:09.071 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_SWITCH_BINARY 2018-12-12 00:35:09.073 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 created 2018-12-12 00:35:09.075 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_SWITCH_BINARY. 2018-12-12 00:35:09.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_SWITCH_BINARY to the list of supported command classes. 2018-12-12 00:35:09.078 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_SWITCH_ALL 2018-12-12 00:35:09.081 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_SWITCH_ALL, endpoint 0 created 2018-12-12 00:35:09.082 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_SWITCH_ALL. 2018-12-12 00:35:09.084 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_SWITCH_ALL to the list of supported command classes. 2018-12-12 00:35:09.086 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_CONFIGURATION 2018-12-12 00:35:09.088 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_CONFIGURATION, endpoint 0 created 2018-12-12 00:35:09.090 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_CONFIGURATION. 2018-12-12 00:35:09.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_CONFIGURATION to the list of supported command classes. 2018-12-12 00:35:09.093 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_SCENE_ACTUATOR_CONF 2018-12-12 00:35:09.095 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_SCENE_ACTUATOR_CONF, endpoint 0 created 2018-12-12 00:35:09.097 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_SCENE_ACTUATOR_CONF. 2018-12-12 00:35:09.099 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_SCENE_ACTUATOR_CONF to the list of supported command classes. 2018-12-12 00:35:09.101 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_SCENE_ACTIVATION 2018-12-12 00:35:09.103 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_SCENE_ACTIVATION, endpoint 0 created 2018-12-12 00:35:09.104 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_SCENE_ACTIVATION. 2018-12-12 00:35:09.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_SCENE_ACTIVATION to the list of supported command classes. 2018-12-12 00:35:09.108 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_CENTRAL_SCENE 2018-12-12 00:35:09.110 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_CENTRAL_SCENE, endpoint 0 created 2018-12-12 00:35:09.112 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_CENTRAL_SCENE. 2018-12-12 00:35:09.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_CENTRAL_SCENE to the list of supported command classes. 2018-12-12 00:35:09.115 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_BASIC 2018-12-12 00:35:09.117 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_BASIC, endpoint 0 created 2018-12-12 00:35:09.118 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_BASIC. 2018-12-12 00:35:09.119 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes. 2018-12-12 00:35:09.121 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_FIRMWARE_UPDATE_MD 2018-12-12 00:35:09.123 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_FIRMWARE_UPDATE_MD, endpoint 0 created 2018-12-12 00:35:09.124 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_FIRMWARE_UPDATE_MD. 2018-12-12 00:35:09.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_FIRMWARE_UPDATE_MD to the list of supported command classes. 2018-12-12 00:35:09.126 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_BASIC 2018-12-12 00:35:09.129 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_BASIC, endpoint 0 created 2018-12-12 00:35:09.131 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_BASIC. 2018-12-12 00:35:09.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes. 2018-12-12 00:35:09.134 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_SWITCH_MULTILEVEL 2018-12-12 00:35:09.136 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 created 2018-12-12 00:35:09.137 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_SWITCH_MULTILEVEL. 2018-12-12 00:35:09.138 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_SWITCH_MULTILEVEL to the list of supported command classes. 2018-12-12 00:35:09.140 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_CENTRAL_SCENE 2018-12-12 00:35:09.141 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_CENTRAL_SCENE, endpoint 0 created 2018-12-12 00:35:09.143 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 50: Inclusion is adding command class COMMAND_CLASS_CENTRAL_SCENE. 2018-12-12 00:35:09.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_CENTRAL_SCENE to the list of supported command classes. 2018-12-12 00:35:09.146 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 50: ZWaveController include node 2018-12-12 00:35:09.147 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Starting initialisation from INCLUSION_START 2018-12-12 00:35:09.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:09.150 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node advancer: Node just included (3973135) 2018-12-12 00:35:09.151 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 133: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 94 2018-12-12 00:35:09.151 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node advancer - advancing to IDENTIFY_NODE 2018-12-12 00:35:09.152 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:35:09.153 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node advancer: Initialisation starting from inclusion 2018-12-12 00:35:09.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=94, payload=5E 06 32 00 2018-12-12 00:35:09.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@13d5a29 2018-12-12 00:35:09.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 133: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 94 2018-12-12 00:35:09.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:09.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 133: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 94 2018-12-12 00:35:09.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 133: (Callback 94) 2018-12-12 00:35:09.163 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:35:09.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 133: callback 94 2018-12-12 00:35:09.167 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=94, payload=5E 06 32 00 2018-12-12 00:35:09.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 134 to queue - size 1 2018-12-12 00:35:09.169 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done. 2018-12-12 00:35:09.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:09.171 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 50: Device discovered 2018-12-12 00:35:09.190 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:512:node50' to inbox. 2018-12-12 00:35:09.194 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeProtocolDone, new event IncludeDone ==> /var/log/openhab2/events.log <== 2018-12-12 00:35:09.194 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:512:node50' has been added. ==> /var/log/openhab2/openhab.log <== 2018-12-12 00:35:09.196 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode. ==> /var/log/openhab2/events.log <== 2018-12-12 00:35:09.198 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@3945af ==> /var/log/openhab2/openhab.log <== 2018-12-12 00:35:09.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 135 to queue - size 2 2018-12-12 00:35:09.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:09.205 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion 2018-12-12 00:35:09.207 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done 2018-12-12 00:35:09.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 133: Advanced to DONE 2018-12-12 00:35:09.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 264ms 2018-12-12 00:35:09.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 133: Transaction completed 2018-12-12 00:35:09.214 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:133 DONE 2018-12-12 00:35:09.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:09.217 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:09.219 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 32 88 2018-12-12 00:35:09.221 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 32 88 2018-12-12 00:35:09.223 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:35:09.224 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:35:09.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 134: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2018-12-12 00:35:09.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:09.228 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:09.228 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 D3 9C 01 04 10 01 ED 2018-12-12 00:35:09.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 134: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2018-12-12 00:35:09.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:35:09.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 10 01 2018-12-12 00:35:09.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 10 01 2018-12-12 00:35:09.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 134: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2018-12-12 00:35:09.237 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:09.238 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 134: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2018-12-12 00:35:09.240 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 10 01 2018-12-12 00:35:09.242 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: ProtocolInfo 2018-12-12 00:35:09.244 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Listening = true 2018-12-12 00:35:09.245 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Routing = true 2018-12-12 00:35:09.247 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Beaming = true 2018-12-12 00:35:09.249 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Version = 4 2018-12-12 00:35:09.250 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: FLIRS = false 2018-12-12 00:35:09.252 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Security = false 2018-12-12 00:35:09.254 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Max Baud = 40000 2018-12-12 00:35:09.256 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Basic = BASIC_TYPE_ROUTING_SLAVE 2018-12-12 00:35:09.257 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Generic = GENERIC_TYPE_SWITCH_BINARY 2018-12-12 00:35:09.259 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 50: Specific = SPECIFIC_TYPE_POWER_SWITCH_BINARY 2018-12-12 00:35:09.261 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_NO_OPERATION 2018-12-12 00:35:09.263 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created 2018-12-12 00:35:09.264 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Version = 1, version set. Enabling extra functionality. 2018-12-12 00:35:09.266 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes. 2018-12-12 00:35:09.268 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Creating new instance of command class COMMAND_CLASS_BASIC 2018-12-12 00:35:09.270 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 50: Command class COMMAND_CLASS_BASIC, endpoint 0 created 2018-12-12 00:35:09.272 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes. 2018-12-12 00:35:09.274 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 134: Transaction COMPLETED 2018-12-12 00:35:09.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 51ms 2018-12-12 00:35:09.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 134: Transaction completed 2018-12-12 00:35:09.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:134 DONE 2018-12-12 00:35:09.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:09.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 134: Transaction event listener: DONE: DONE -> 2018-12-12 00:35:09.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:09.285 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 2018-12-12 00:35:09.284 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1c53159 2018-12-12 00:35:09.286 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init transaction completed with response COMPLETE 2018-12-12 00:35:09.287 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 2018-12-12 00:35:09.289 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:35:09.291 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:35:09.290 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 135: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0 2018-12-12 00:35:09.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:09.294 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:09.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 135: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0 2018-12-12 00:35:09.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:35:09.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:09.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:10.287 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node advancer - advancing to SECURITY_REPORT 2018-12-12 00:35:10.289 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: SECURE command class not supported 2018-12-12 00:35:10.292 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node advancer - advancing to MANUFACTURER 2018-12-12 00:35:10.294 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node advancer: MANUFACTURER - send ManufacturerSpecific 2018-12-12 00:35:10.297 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 50: Creating new message for command MANUFACTURER_SPECIFIC_GET 2018-12-12 00:35:10.299 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:35:10.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:35:10.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:35:10.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:35:10.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:35:10.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 136 to queue - size 8 2018-12-12 00:35:10.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:14.292 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 135: Timeout at state WAIT_REQUEST. 3 retries remaining. 2018-12-12 00:35:14.295 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 135: Transaction is current transaction, so clearing!!!!! 2018-12-12 00:35:14.297 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 135: Transaction CANCELLED 2018-12-12 00:35:14.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:135 CANCELLED 2018-12-12 00:35:14.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:14.305 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 60 E6 2018-12-12 00:35:14.307 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 60 E6 2018-12-12 00:35:14.310 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:35:14.311 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:35:14.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 136: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 96 2018-12-12 00:35:14.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:14.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:14.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 136: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 96 2018-12-12 00:35:14.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:35:14.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:14.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:14.323 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:35:14.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:14.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:14.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 136: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 96 2018-12-12 00:35:14.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:14.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 136: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 96 2018-12-12 00:35:14.338 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:14.339 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 60 00 00 03 88 2018-12-12 00:35:14.340 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:35:14.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 136: Advanced to WAIT_REQUEST 2018-12-12 00:35:14.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 136: Transaction not completed 2018-12-12 00:35:14.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=96, payload=60 00 00 03 2018-12-12 00:35:14.345 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=96, payload=60 00 00 03 2018-12-12 00:35:14.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 136: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 96 2018-12-12 00:35:14.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:14.351 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 136: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 96 2018-12-12 00:35:14.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 136: (Callback 96) 2018-12-12 00:35:14.354 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:35:14.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 136: callback 96 2018-12-12 00:35:14.358 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=96, payload=60 00 00 03 2018-12-12 00:35:14.360 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 96, Status = Transmission complete and ACK received(0) 2018-12-12 00:35:14.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 136: Advanced to WAIT_DATA 2018-12-12 00:35:14.364 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 136: Transaction not completed 2018-12-12 00:35:14.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:14.367 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:19.365 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 136: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:35:19.367 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 136: Transaction CANCELLED 2018-12-12 00:35:19.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:136 CANCELLED 2018-12-12 00:35:19.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:19.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 136: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:35:19.378 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@15249a6 2018-12-12 00:35:19.380 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 0) 2018-12-12 00:35:20.744 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:35:20.746 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:35:20.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:35:20.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:35:20.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:35:20.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 137 to queue - size 8 2018-12-12 00:35:20.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:20.759 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 61 E7 2018-12-12 00:35:20.761 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 61 E7 2018-12-12 00:35:20.764 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:35:20.765 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:35:20.766 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 137: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 97 2018-12-12 00:35:20.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:20.772 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:20.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 137: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 97 2018-12-12 00:35:20.773 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:35:20.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:35:20.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:20.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:20.779 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:20.781 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:20.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 137: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 97 2018-12-12 00:35:20.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:20.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 137: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 97 2018-12-12 00:35:20.789 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:20.790 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 61 00 00 02 88 2018-12-12 00:35:20.791 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:35:20.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 137: Advanced to WAIT_REQUEST 2018-12-12 00:35:20.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 137: Transaction not completed 2018-12-12 00:35:20.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 02 2018-12-12 00:35:20.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:20.799 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:20.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 02 2018-12-12 00:35:20.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 137: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 97 2018-12-12 00:35:20.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:20.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 137: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 97 2018-12-12 00:35:20.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 137: (Callback 97) 2018-12-12 00:35:20.811 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:35:20.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 137: callback 97 2018-12-12 00:35:20.814 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=97, payload=61 00 00 02 2018-12-12 00:35:20.816 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 97, Status = Transmission complete and ACK received(0) 2018-12-12 00:35:20.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 137: Advanced to WAIT_DATA 2018-12-12 00:35:20.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 137: Transaction not completed 2018-12-12 00:35:20.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:20.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:25.821 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 137: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:35:25.822 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 137: Transaction CANCELLED 2018-12-12 00:35:25.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:137 CANCELLED 2018-12-12 00:35:25.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:25.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 137: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:35:25.832 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (1) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1d14074 2018-12-12 00:35:25.834 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 1) 2018-12-12 00:35:27.885 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:512 2018-12-12 00:35:27.888 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do 2018-12-12 00:35:29.040 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:35:29.042 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:35:29.043 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:35:29.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:35:29.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:35:29.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 138 to queue - size 8 2018-12-12 00:35:29.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:29.054 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 62 E4 2018-12-12 00:35:29.056 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 62 E4 2018-12-12 00:35:29.059 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:35:29.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 138: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 98 2018-12-12 00:35:29.061 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:35:29.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:29.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:29.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 138: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 98 2018-12-12 00:35:29.067 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:35:29.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:35:29.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:29.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:29.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:29.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:29.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 138: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 98 2018-12-12 00:35:29.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:29.084 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 62 00 00 02 8B 2018-12-12 00:35:29.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 138: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 98 2018-12-12 00:35:29.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 02 2018-12-12 00:35:29.088 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:29.090 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:35:29.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 138: Advanced to WAIT_REQUEST 2018-12-12 00:35:29.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 138: Transaction not completed 2018-12-12 00:35:29.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 02 2018-12-12 00:35:29.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 138: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 98 2018-12-12 00:35:29.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:29.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 138: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 98 2018-12-12 00:35:29.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 138: (Callback 98) 2018-12-12 00:35:29.110 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:35:29.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 138: callback 98 2018-12-12 00:35:29.115 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=98, payload=62 00 00 02 2018-12-12 00:35:29.119 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 98, Status = Transmission complete and ACK received(0) 2018-12-12 00:35:29.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 138: Advanced to WAIT_DATA 2018-12-12 00:35:29.124 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 138: Transaction not completed 2018-12-12 00:35:29.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:29.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:34.125 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 138: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:35:34.126 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 138: Transaction CANCELLED 2018-12-12 00:35:34.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:138 CANCELLED 2018-12-12 00:35:34.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:34.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 138: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:35:34.135 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (2) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1aeeb6e 2018-12-12 00:35:34.137 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 2) 2018-12-12 00:35:41.149 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:35:41.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:35:41.153 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:35:41.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:35:41.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:35:41.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 139 to queue - size 8 2018-12-12 00:35:41.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:41.164 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 63 E5 2018-12-12 00:35:41.168 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 63 E5 2018-12-12 00:35:41.171 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:35:41.173 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:35:41.174 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 139: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 99 2018-12-12 00:35:41.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:41.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:35:41.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 139: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 99 2018-12-12 00:35:41.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:35:41.180 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:35:41.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:41.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:41.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:41.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:41.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 139: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 99 2018-12-12 00:35:41.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:41.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 139: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 99 2018-12-12 00:35:41.193 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:35:41.195 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:35:41.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 139: Advanced to WAIT_REQUEST 2018-12-12 00:35:41.197 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 63 00 00 02 8A 2018-12-12 00:35:41.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 139: Transaction not completed 2018-12-12 00:35:41.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:41.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=99, payload=63 00 00 02 2018-12-12 00:35:41.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:41.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=99, payload=63 00 00 02 2018-12-12 00:35:41.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 139: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 99 2018-12-12 00:35:41.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:35:41.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 139: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 99 2018-12-12 00:35:41.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 139: (Callback 99) 2018-12-12 00:35:41.214 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:35:41.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 139: callback 99 2018-12-12 00:35:41.217 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=99, payload=63 00 00 02 2018-12-12 00:35:41.220 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 99, Status = Transmission complete and ACK received(0) 2018-12-12 00:35:41.222 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 139: Advanced to WAIT_DATA 2018-12-12 00:35:41.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 139: Transaction not completed 2018-12-12 00:35:41.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:35:41.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:35:46.223 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 139: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:35:46.226 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 139: Transaction CANCELLED 2018-12-12 00:35:46.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:139 CANCELLED 2018-12-12 00:35:46.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:35:46.232 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 139: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:35:46.236 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (3) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@e8434a 2018-12-12 00:35:46.239 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 3) 2018-12-12 00:36:00.808 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:36:00.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:36:00.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:36:00.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:36:00.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:36:00.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 140 to queue - size 8 2018-12-12 00:36:00.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:36:00.823 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 64 E2 2018-12-12 00:36:00.825 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 64 E2 2018-12-12 00:36:00.828 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:36:00.830 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:36:00.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:36:00.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 140: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 100 2018-12-12 00:36:00.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:36:00.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:36:00.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:36:00.838 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:36:00.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:36:00.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:36:00.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:36:00.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:36:00.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 140: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 100 2018-12-12 00:36:00.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:36:00.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 140: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 100 2018-12-12 00:36:00.850 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:36:00.852 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:36:00.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 140: Advanced to WAIT_REQUEST 2018-12-12 00:36:00.854 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 64 00 00 02 8D 2018-12-12 00:36:00.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 140: Transaction not completed 2018-12-12 00:36:00.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:36:00.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=100, payload=64 00 00 02 2018-12-12 00:36:00.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:36:00.862 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=100, payload=64 00 00 02 2018-12-12 00:36:00.863 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 140: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 100 2018-12-12 00:36:00.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:36:00.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 140: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 100 2018-12-12 00:36:00.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 140: (Callback 100) 2018-12-12 00:36:00.870 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:36:00.872 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 140: callback 100 2018-12-12 00:36:00.874 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=100, payload=64 00 00 02 2018-12-12 00:36:00.876 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 100, Status = Transmission complete and ACK received(0) 2018-12-12 00:36:00.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 140: Advanced to WAIT_DATA 2018-12-12 00:36:00.880 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 140: Transaction not completed 2018-12-12 00:36:00.882 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:36:00.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:36:05.880 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 140: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:36:05.889 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 140: Transaction CANCELLED 2018-12-12 00:36:05.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:140 CANCELLED 2018-12-12 00:36:05.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:36:05.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 140: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:36:05.897 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (4) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1403208 2018-12-12 00:36:05.900 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 4) 2018-12-12 00:36:35.477 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:36:35.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:36:35.480 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:36:35.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:36:35.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:36:35.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 141 to queue - size 8 2018-12-12 00:36:35.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:36:35.495 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 65 E3 2018-12-12 00:36:35.497 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 65 E3 2018-12-12 00:36:35.500 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:36:35.502 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:36:35.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 141: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 101 2018-12-12 00:36:35.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:36:35.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:36:35.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 141: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 101 2018-12-12 00:36:35.509 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:36:35.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:36:35.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:36:35.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:36:35.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:36:35.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:36:35.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 141: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 101 2018-12-12 00:36:35.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:36:35.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 141: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 101 2018-12-12 00:36:35.523 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:36:35.525 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:36:35.526 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 65 00 00 03 8D 2018-12-12 00:36:35.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 141: Advanced to WAIT_REQUEST 2018-12-12 00:36:35.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 141: Transaction not completed 2018-12-12 00:36:35.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:36:35.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=101, payload=65 00 00 03 2018-12-12 00:36:35.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:36:35.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=101, payload=65 00 00 03 2018-12-12 00:36:35.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 141: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 101 2018-12-12 00:36:35.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:36:35.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 141: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 101 2018-12-12 00:36:35.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 141: (Callback 101) 2018-12-12 00:36:35.543 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:36:35.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 141: callback 101 2018-12-12 00:36:35.547 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=101, payload=65 00 00 03 2018-12-12 00:36:35.549 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 101, Status = Transmission complete and ACK received(0) 2018-12-12 00:36:35.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 141: Advanced to WAIT_DATA 2018-12-12 00:36:35.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 141: Transaction not completed 2018-12-12 00:36:35.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:36:35.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:36:40.554 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 141: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:36:40.561 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 141: Transaction CANCELLED 2018-12-12 00:36:40.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:141 CANCELLED 2018-12-12 00:36:40.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:36:40.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 141: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:36:40.569 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (5) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@f501f4 2018-12-12 00:36:40.571 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 5) 2018-12-12 00:36:51.778 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised 2018-12-12 00:37:40.235 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:37:40.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:37:40.241 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:37:40.243 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:37:40.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:37:40.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 142 to queue - size 8 2018-12-12 00:37:40.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:37:40.253 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 66 E0 2018-12-12 00:37:40.256 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 66 E0 2018-12-12 00:37:40.259 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:37:40.260 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:37:40.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 142: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 102 2018-12-12 00:37:40.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:37:40.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:37:40.268 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:37:40.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 142: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 102 2018-12-12 00:37:40.271 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:37:40.272 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:37:40.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:37:40.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 142: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 102 2018-12-12 00:37:40.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:37:40.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 142: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 102 2018-12-12 00:37:40.284 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 66 00 00 02 8F 2018-12-12 00:37:40.286 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:37:40.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=102, payload=66 00 00 02 2018-12-12 00:37:40.289 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:37:40.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 142: Advanced to WAIT_REQUEST 2018-12-12 00:37:40.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 142: Transaction not completed 2018-12-12 00:37:40.298 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=102, payload=66 00 00 02 2018-12-12 00:37:40.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 142: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 102 2018-12-12 00:37:40.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:37:40.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 142: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 102 2018-12-12 00:37:40.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 142: (Callback 102) 2018-12-12 00:37:40.307 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:37:40.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 142: callback 102 2018-12-12 00:37:40.311 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=102, payload=66 00 00 02 2018-12-12 00:37:40.314 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 102, Status = Transmission complete and ACK received(0) 2018-12-12 00:37:40.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 142: Advanced to WAIT_DATA 2018-12-12 00:37:40.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 142: Transaction not completed 2018-12-12 00:37:40.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:37:40.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:37:45.319 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 142: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:37:45.327 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 142: Transaction CANCELLED 2018-12-12 00:37:45.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:142 CANCELLED 2018-12-12 00:37:45.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:37:45.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 142: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:37:45.336 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (6) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@aa629f 2018-12-12 00:37:45.339 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 6) 2018-12-12 00:39:31.049 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 2B 03 20 01 00 FB 2018-12-12 00:39:31.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 03 20 01 00 2018-12-12 00:39:31.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 03 20 01 00 2018-12-12 00:39:31.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:39:31.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE) 2018-12-12 00:39:31.062 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false 2018-12-12 00:39:31.063 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_BASIC, endpoint 0 2018-12-12 00:39:31.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported 2018-12-12 00:39:31.068 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_BASIC V1 BASIC_SET 2018-12-12 00:39:31.070 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 43: Basic report, value = 0 2018-12-12 00:39:31.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2018-12-12 00:39:31.074 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BASIC, value = 0 2018-12-12 00:39:31.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1. 2018-12-12 00:39:31.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6fc86. 2018-12-12 00:39:31.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:31.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:31.084 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:39:31.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:39:31.350 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 2B 0A 71 05 00 00 00 FF 07 00 00 00 46 2018-12-12 00:39:31.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 0A 71 05 00 00 00 FF 07 00 00 00 2018-12-12 00:39:31.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 0A 71 05 00 00 00 FF 07 00 00 00 2018-12-12 00:39:31.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:39:31.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE) 2018-12-12 00:39:31.363 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false 2018-12-12 00:39:31.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2018-12-12 00:39:31.367 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported 2018-12-12 00:39:31.369 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT 2018-12-12 00:39:31.372 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 43: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0 2018-12-12 00:39:31.374 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 43: Alarm Type = BURGLAR (0) 2018-12-12 00:39:31.376 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2018-12-12 00:39:31.378 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ALARM, value = 255 2018-12-12 00:39:31.380 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 43: Alarm converter processing NOTIFICATION 2018-12-12 00:39:31.385 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 43: Alarm converter NOTIFICATION event is 0, type OnOffType 2018-12-12 00:39:31.388 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:512:node43:alarm_tamper to OFF [OnOffType] 2018-12-12 00:39:31.390 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 43: Alarm converter processing NOTIFICATION 2018-12-12 00:39:31.392 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 43: Alarm converter NOTIFICATION event is 0, type OnOffType 2018-12-12 00:39:31.395 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:512:node43:alarm_motion to OFF [OnOffType] 2018-12-12 00:39:31.404 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1. 2018-12-12 00:39:31.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1da0b93. 2018-12-12 00:39:31.410 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:31.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:31.413 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:39:31.414 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:39:37.549 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 27 03 20 01 FF 08 2018-12-12 00:39:37.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=39, callback=0, payload=00 27 03 20 01 FF 2018-12-12 00:39:37.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=39, callback=0, payload=00 27 03 20 01 FF 2018-12-12 00:39:37.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:39:37.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 39: Application Command Request (ALIVE:DONE) 2018-12-12 00:39:37.560 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: resetResendCount initComplete=true isDead=false 2018-12-12 00:39:37.563 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Incoming command class COMMAND_CLASS_BASIC, endpoint 0 2018-12-12 00:39:37.565 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: SECURITY not supported 2018-12-12 00:39:37.567 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Received COMMAND_CLASS_BASIC V1 BASIC_SET 2018-12-12 00:39:37.569 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 39: Basic report, value = 255 2018-12-12 00:39:37.571 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 39: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2018-12-12 00:39:37.573 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 39: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BASIC, value = 255 2018-12-12 00:39:37.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 39: Commands processed 1. 2018-12-12 00:39:37.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 39: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@11006d2. 2018-12-12 00:39:37.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:37.582 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:37.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:39:37.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:39:37.878 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 27 0A 71 05 00 00 00 FF 07 08 00 00 42 2018-12-12 00:39:37.882 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=39, callback=0, payload=00 27 0A 71 05 00 00 00 FF 07 08 00 00 2018-12-12 00:39:37.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=39, callback=0, payload=00 27 0A 71 05 00 00 00 FF 07 08 00 00 2018-12-12 00:39:37.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2018-12-12 00:39:37.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 39: Application Command Request (ALIVE:DONE) 2018-12-12 00:39:37.889 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: resetResendCount initComplete=true isDead=false 2018-12-12 00:39:37.891 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Incoming command class COMMAND_CLASS_ALARM, endpoint 0 2018-12-12 00:39:37.893 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: SECURITY not supported 2018-12-12 00:39:37.894 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT 2018-12-12 00:39:37.896 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 39: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0 2018-12-12 00:39:37.897 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 39: Alarm Type = BURGLAR (0) 2018-12-12 00:39:37.904 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 39: Got an event from Z-Wave network: ZWaveAlarmValueEvent 2018-12-12 00:39:37.906 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 39: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ALARM, value = 255 2018-12-12 00:39:37.907 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 39: Alarm converter processing NOTIFICATION 2018-12-12 00:39:37.909 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 39: Alarm converter NOTIFICATION event is 8, type OnOffType 2018-12-12 00:39:37.911 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 39: Alarm converter processing NOTIFICATION 2018-12-12 00:39:37.913 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 39: Alarm converter NOTIFICATION event is 8, type OnOffType 2018-12-12 00:39:37.915 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 39: Updating channel state zwave:device:512:node39:alarm_motion to ON [OnOffType] 2018-12-12 00:39:37.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 39: Commands processed 1. 2018-12-12 00:39:37.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 39: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@78b453. 2018-12-12 00:39:37.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:37.926 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2018-12-12 00:39:37.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:39:37.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:39:45.327 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: ZWaveCommandClassTransactionPayload - send to node 2018-12-12 00:39:45.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported 2018-12-12 00:39:45.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2018-12-12 00:39:45.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c7bc05 2018-12-12 00:39:45.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue 2018-12-12 00:39:45.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 143 to queue - size 8 2018-12-12 00:39:45.345 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:39:45.348 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 02 72 04 25 67 E1 2018-12-12 00:39:45.350 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 09 00 13 32 02 72 04 25 67 E1 2018-12-12 00:39:45.353 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2018-12-12 00:39:45.355 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2018-12-12 00:39:45.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 143: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 103 2018-12-12 00:39:45.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:39:45.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2018-12-12 00:39:45.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 143: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 103 2018-12-12 00:39:45.362 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2018-12-12 00:39:45.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2018-12-12 00:39:45.364 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:39:45.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:39:45.365 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:39:45.368 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:39:45.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 143: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 103 2018-12-12 00:39:45.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:39:45.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 143: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 103 2018-12-12 00:39:45.375 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2018-12-12 00:39:45.377 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: sentData successfully placed on stack. 2018-12-12 00:39:45.379 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 143: Advanced to WAIT_REQUEST 2018-12-12 00:39:45.379 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 67 00 00 02 8E 2018-12-12 00:39:45.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 143: Transaction not completed 2018-12-12 00:39:45.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:39:45.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=103, payload=67 00 00 02 2018-12-12 00:39:45.384 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:39:45.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=103, payload=67 00 00 02 2018-12-12 00:39:45.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 143: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 103 2018-12-12 00:39:45.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2018-12-12 00:39:45.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 143: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 103 2018-12-12 00:39:45.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 143: (Callback 103) 2018-12-12 00:39:45.395 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2018-12-12 00:39:45.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 143: callback 103 2018-12-12 00:39:45.399 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=103, payload=67 00 00 02 2018-12-12 00:39:45.401 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 50: SendData Request. CallBack ID = 103, Status = Transmission complete and ACK received(0) 2018-12-12 00:39:45.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 143: Advanced to WAIT_DATA 2018-12-12 00:39:45.403 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: TID 143: Transaction not completed 2018-12-12 00:39:45.404 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2018-12-12 00:39:45.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2018-12-12 00:39:50.403 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 143: Timeout at state WAIT_DATA. 3 retries remaining. 2018-12-12 00:39:50.410 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 143: Transaction CANCELLED 2018-12-12 00:39:50.413 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:143 CANCELLED 2018-12-12 00:39:50.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2018-12-12 00:39:50.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 143: Transaction event listener: DONE: CANCELLED -> 2018-12-12 00:39:50.422 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: Node Init response (7) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@7c3e7d 2018-12-12 00:39:50.425 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 50: No data from device, but it was ACK'd. Possibly not supported? (Try 7)