[SOLVED] Kwikset Z-Wave 914 failing to create COMMAND_CLASS_DOOR_LOCK

[QuagmireMan (Chris S)]

Has anyone came to a solution on this? I have tried everything I could think of to try and resolve this issue, outside of the hard reset of my z-wave controller.

I have tried to factory reset the lock then exclude I have also tried it the other way around.

I updated to 2.5.8-1 and have reset my pi several times.

It gets included securely, but then no commands or no data (battery level) comes back from the lock

2020-09-03 19:06:42.009 [WARN ] [nal.converter.ZWaveDoorLockConverter] - NODE 41: Command class COMMAND_CLASS_DOOR_LOCK not found

2020-09-03 19:06:42.011 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: No messages returned from converter

network_c40d782d__node_41.xml (3.9 KB)

Any ideas or suggestions would be appreciated.

[chris (Chris Jackson)]

@QuagmireMan

Any ideas or suggestions would be appreciated.

My guess is that the device has not included securely, so this command class, which REQUIRES security, is not available. I appreciate that you’ve tried to get it securely included, but from the XML file, it doesn’t look like this is the case.

[QuagmireMan (Chris S)]

There have been times where I missed the window and it was not securely included and was indicated in Habmin. According to Habmin it is securely included now.

[chris (Chris Jackson)]

Looking at the XML, it doesn’t look correct though. It looks to me like it was unsuccessful - there is no security running, and the secure command classes are unknown, which just looks like the inclusion (secure key exchange) failed. You could try to reinitialise the device (there is an option in the configuration to do this) - that will provide more information if you provide the (debug) logs.

[QuagmireMan (Chris S)]

I Filtered on the term “zwave”

Let me know if you want this unfiltered or attached differently

2020-09-04 07:47:25.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-09-04 07:47:25.905 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1023eac

2020-09-04 07:47:25.907 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node Init transaction completed with response COMPLETE

2020-09-04 07:47:25.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:47:25.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:47:25.908 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to ENDPOINTS

2020-09-04 07:47:25.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:25.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:47:25.914 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: ENDPOINTS - MultiInstance not supported.

2020-09-04 07:47:25.915 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to UPDATE_DATABASE

2020-09-04 07:47:25.916 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:25.919 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE

2020-09-04 07:47:25.919 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: VERSION to ONLINE: Node initialising: ENDPOINTS

2020-09-04 07:47:25.929 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE - check properties

2020-09-04 07:47:25.930 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE - property modelId == SmartCode 888

2020-09-04 07:47:25.931 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE - property vendor == Black & Decker

2020-09-04 07:47:25.933 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE - property defaultAssociations == 1,2

2020-09-04 07:47:25.932 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: ENDPOINTS to ONLINE: Node initialising: UPDATE_DATABASE

2020-09-04 07:47:25.934 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE - property manufacturerId == 0090

2020-09-04 07:47:25.935 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE - property manufacturerRef == 0003:0541

2020-09-04 07:47:25.935 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: UPDATE_DATABASE - property dbReference == 864

2020-09-04 07:47:25.937 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to STATIC_VALUES

2020-09-04 07:47:25.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:25.941 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC

2020-09-04 07:47:25.942 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Found 1 instances of COMMAND_CLASS_MANUFACTURER_SPECIFIC for endpoint 0

2020-09-04 07:47:25.943 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_NO_OPERATION

2020-09-04 07:47:25.945 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_VERSION

2020-09-04 07:47:25.946 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_ZWAVEPLUS_INFO

2020-09-04 07:47:25.947 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Found 1 instances of COMMAND_CLASS_ZWAVEPLUS_INFO for endpoint 0

2020-09-04 07:47:25.948 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: UPDATE_DATABASE to ONLINE: Node initialising: STATIC_VALUES

2020-09-04 07:47:25.951 [DEBUG] [ass.impl.CommandClassZwaveplusInfoV1] - Creating command message ZWAVEPLUS_INFO_GET version 1

2020-09-04 07:47:25.952 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 41: SECURITY not supported

2020-09-04 07:47:25.953 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 41: Command Class COMMAND_CLASS_ZWAVEPLUS_INFO is NOT required to be secured

2020-09-04 07:47:25.954 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: ZWaveCommandClassTransactionPayload - send to node

2020-09-04 07:47:25.955 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 41: SECURITY not supported

2020-09-04 07:47:25.956 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 41: Command Class COMMAND_CLASS_ZWAVEPLUS_INFO is NOT required to be secured

2020-09-04 07:47:25.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@130709a

2020-09-04 07:47:25.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: Bump transaction 410 priority from Config to Immediate

2020-09-04 07:47:25.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: Adding to device queue

2020-09-04 07:47:25.964 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: Added 410 to queue - size 1

2020-09-04 07:47:25.964 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:47:25.967 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 41: Sending REQUEST Message = 01 09 00 13 29 02 5E 01 25 10 A4 

2020-09-04 07:47:25.968 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-09-04 07:47:25.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 410: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 16

2020-09-04 07:47:25.970 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-09-04 07:47:25.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-09-04 07:47:25.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-09-04 07:47:25.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 410: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 16

2020-09-04 07:47:25.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-09-04 07:47:25.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:47:25.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-09-04 07:47:25.977 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-09-04 07:47:25.978 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-09-04 07:47:25.980 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-09-04 07:47:25.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 410: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 16

2020-09-04 07:47:25.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-09-04 07:47:25.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 410: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 16

2020-09-04 07:47:25.983 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-09-04 07:47:25.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 410: Advanced to WAIT_REQUEST

2020-09-04 07:47:25.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: TID 410: Transaction not completed

2020-09-04 07:47:25.988 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:47:25.989 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-09-04 07:47:25.997 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 10 00 00 02 F9 

2020-09-04 07:47:25.999 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=16, payload=10 00 00 02 

2020-09-04 07:47:26.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=16, payload=10 00 00 02 

2020-09-04 07:47:26.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 410: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 16

2020-09-04 07:47:26.003 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-09-04 07:47:26.004 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 410: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 16

2020-09-04 07:47:26.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 410: (Callback 16)

2020-09-04 07:47:26.006 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-09-04 07:47:26.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 410: callback 16

2020-09-04 07:47:26.008 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=16, payload=10 00 00 02 

2020-09-04 07:47:26.012 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 29 09 5E 02 01 07 00 03 00 03 00 8E 

2020-09-04 07:47:26.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 410: Advanced to WAIT_DATA

2020-09-04 07:47:26.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=41, callback=0, payload=00 29 09 5E 02 01 07 00 03 00 03 00 

2020-09-04 07:47:26.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: TID 410: Transaction not completed

2020-09-04 07:47:26.016 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=41, callback=0, payload=00 29 09 5E 02 01 07 00 03 00 03 00 

2020-09-04 07:47:26.017 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:47:26.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: Application Command Request (ALIVE:STATIC_VALUES)

2020-09-04 07:47:26.020 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 41: Incoming command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0

2020-09-04 07:47:26.021 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 41: SECURITY not supported

2020-09-04 07:47:26.022 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 41: Received COMMAND_CLASS_ZWAVEPLUS_INFO V1 ZWAVEPLUS_INFO_REPORT

2020-09-04 07:47:26.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: Commands processed 1.

2020-09-04 07:47:26.025 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d03d48.

2020-09-04 07:47:26.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d03d48.

2020-09-04 07:47:26.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 41: notifyTransactionResponse TID:410 DONE

2020-09-04 07:47:26.029 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-09-04 07:47:26.029 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 410: Transaction event listener: DONE: DONE -> 

2020-09-04 07:47:26.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-09-04 07:47:26.030 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1b1c28e

2020-09-04 07:47:26.031 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node Init transaction completed with response COMPLETE

2020-09-04 07:47:26.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:47:26.032 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to ASSOCIATIONS

2020-09-04 07:47:26.033 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:47:26.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:47:26.036 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to SET_WAKEUP

2020-09-04 07:47:26.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.041 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: STATIC_VALUES to ONLINE: Node initialising: ASSOCIATIONS

2020-09-04 07:47:26.047 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: ASSOCIATIONS to ONLINE: Node initialising: SET_WAKEUP

2020-09-04 07:47:26.043 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to SET_ASSOCIATION

2020-09-04 07:47:26.049 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.055 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: SET_WAKEUP to ONLINE: Node initialising: SET_ASSOCIATION

2020-09-04 07:47:26.056 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: SET_ASSOCIATION - ASSOCIATION class not supported

2020-09-04 07:47:26.057 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to SET_LIFELINE

2020-09-04 07:47:26.059 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.064 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: SET_ASSOCIATION to ONLINE: Node initialising: SET_LIFELINE

2020-09-04 07:47:26.065 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: SET_LIFELINE - ASSOCIATION class not supported

2020-09-04 07:47:26.066 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to GET_CONFIGURATION

2020-09-04 07:47:26.067 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: GET_CONFIGURATION - CONFIGURATION class not supported

2020-09-04 07:47:26.073 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: SET_LIFELINE to ONLINE: Node initialising: GET_CONFIGURATION

2020-09-04 07:47:26.074 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to STATIC_END

2020-09-04 07:47:26.075 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.076 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Updating node properties.

2020-09-04 07:47:26.077 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Updating node properties. MAN=144

2020-09-04 07:47:26.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Updating node properties. MAN=144. SET. Was 144

2020-09-04 07:47:26.080 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Properties synchronised

2020-09-04 07:47:26.081 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising Thing Node...

2020-09-04 07:47:26.083 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising cmd channel zwave:device:4422ee5c:node41:lock_door for OnOffType

2020-09-04 07:47:26.084 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising state channel zwave:device:4422ee5c:node41:lock_door for OnOffType

2020-09-04 07:47:26.086 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising cmd channel zwave:device:4422ee5c:node41:alarm_access for OnOffType

2020-09-04 07:47:26.087 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising state channel zwave:device:4422ee5c:node41:alarm_access for OnOffType

2020-09-04 07:47:26.089 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising cmd channel zwave:device:4422ee5c:node41:alarm_power for OnOffType

2020-09-04 07:47:26.089 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising state channel zwave:device:4422ee5c:node41:alarm_power for OnOffType

2020-09-04 07:47:26.091 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising cmd channel zwave:device:4422ee5c:node41:alarm_raw for StringType

2020-09-04 07:47:26.092 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising state channel zwave:device:4422ee5c:node41:alarm_raw for StringType

2020-09-04 07:47:26.094 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising cmd channel zwave:device:4422ee5c:node41:battery-level for PercentType

2020-09-04 07:47:26.095 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Initialising state channel zwave:device:4422ee5c:node41:battery-level for PercentType

2020-09-04 07:47:26.096 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Polling initialised at 1800 seconds - start in 909000 milliseconds.

2020-09-04 07:47:26.097 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 41: Serialise aborted as static stages not complete

2020-09-04 07:47:26.099 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to DYNAMIC_VALUES

2020-09-04 07:47:26.100 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.101 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC

2020-09-04 07:47:26.102 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_NO_OPERATION

2020-09-04 07:47:26.103 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_VERSION

2020-09-04 07:47:26.104 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ZWAVEPLUS_INFO

2020-09-04 07:47:26.105 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer: Initialisation complete!

2020-09-04 07:47:26.106 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to DYNAMIC_END

2020-09-04 07:47:26.118 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.119 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 41: Serializing to file /var/lib/openhab2/zwave/network_c40d782d__node_41.xml

2020-09-04 07:47:26.135 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 41: Node advancer - advancing to DONE

2020-09-04 07:47:26.137 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 41: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2020-09-04 07:47:26.143 [hingStatusInfoChangedEvent] - 'zwave:device:4422ee5c:node41' changed from ONLINE: Node initialising: GET_CONFIGURATION to ONLINE

2020-09-04 07:47:26.144 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 41: Serializing to file /var/lib/openhab2/zwave/network_c40d782d__node_41.xml

2020-09-04 07:47:35.860 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 21 74 00 00 11 16 00 00 91 

2020-09-04 07:47:35.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 16 00 00 

2020-09-04 07:47:35.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 16 00 00 

2020-09-04 07:47:35.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:47:35.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)

2020-09-04 07:47:35.872 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false

2020-09-04 07:47:35.874 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:47:35.875 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY not supported

2020-09-04 07:47:35.877 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:47:35.880 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=4.374

2020-09-04 07:47:35.882 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:47:35.884 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=4.374

2020-09-04 07:47:35.888 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4422ee5c:node18:meter_watts to 4.374 [DecimalType]

2020-09-04 07:47:35.895 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.

2020-09-04 07:47:35.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@17f8861.

2020-09-04 07:47:35.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:47:35.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:47:35.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:47:35.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:47:54.493 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 13 0A 32 02 21 74 00 00 00 00 00 00 97 

2020-09-04 07:47:54.499 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:47:54.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:47:54.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:47:54.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)

2020-09-04 07:47:54.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false

2020-09-04 07:47:54.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:47:54.511 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported

2020-09-04 07:47:54.513 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:47:54.516 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 19: Meter: Type=Electric(1), Scale=W(2), Value=0E+1

2020-09-04 07:47:54.518 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:47:54.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1

2020-09-04 07:47:54.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4422ee5c:node19:meter_watts to 0 [DecimalType]

2020-09-04 07:47:54.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.

2020-09-04 07:47:54.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@cd5fdc.

2020-09-04 07:47:54.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:47:54.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:47:54.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:47:54.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:48:05.860 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 21 74 00 00 11 C3 00 00 44 

2020-09-04 07:48:05.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 C3 00 00 

2020-09-04 07:48:05.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 C3 00 00 

2020-09-04 07:48:05.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:48:05.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)

2020-09-04 07:48:05.875 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false

2020-09-04 07:48:05.878 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:48:05.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY not supported

2020-09-04 07:48:05.882 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:48:05.885 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=4.547

2020-09-04 07:48:05.887 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:48:05.889 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=4.547

2020-09-04 07:48:05.892 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4422ee5c:node18:meter_watts to 4.547 [DecimalType]

2020-09-04 07:48:05.896 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.

2020-09-04 07:48:05.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1226f92.

2020-09-04 07:48:05.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:05.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:05.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:48:05.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:48:24.496 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 13 0A 32 02 21 74 00 00 00 00 00 00 97 

2020-09-04 07:48:24.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:48:24.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:48:24.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:48:24.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)

2020-09-04 07:48:24.521 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false

2020-09-04 07:48:24.525 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:48:24.527 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported

2020-09-04 07:48:24.531 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:48:24.533 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 19: Meter: Type=Electric(1), Scale=W(2), Value=0E+1

2020-09-04 07:48:24.536 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:48:24.538 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1

2020-09-04 07:48:24.542 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4422ee5c:node19:meter_watts to 0 [DecimalType]

2020-09-04 07:48:24.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.

2020-09-04 07:48:24.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@db36c3.

2020-09-04 07:48:24.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:24.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:24.554 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:48:24.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:48:35.861 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 21 74 00 00 11 3E 00 00 B9 

2020-09-04 07:48:35.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 3E 00 00 

2020-09-04 07:48:35.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 3E 00 00 

2020-09-04 07:48:35.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:48:35.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)

2020-09-04 07:48:35.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false

2020-09-04 07:48:35.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:48:35.882 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY not supported

2020-09-04 07:48:35.884 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:48:35.889 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=4.414

2020-09-04 07:48:35.892 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:48:35.895 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=4.414

2020-09-04 07:48:35.898 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4422ee5c:node18:meter_watts to 4.414 [DecimalType]

2020-09-04 07:48:35.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.

2020-09-04 07:48:35.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1aa8c39.

2020-09-04 07:48:35.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:35.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:35.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:48:35.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:48:54.493 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 13 0A 32 02 21 74 00 00 00 00 00 00 97 

2020-09-04 07:48:54.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:48:54.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:48:54.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:48:54.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)

2020-09-04 07:48:54.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false

2020-09-04 07:48:54.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:48:54.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported

2020-09-04 07:48:54.514 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:48:54.517 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 19: Meter: Type=Electric(1), Scale=W(2), Value=0E+1

2020-09-04 07:48:54.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:48:54.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1

2020-09-04 07:48:54.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4422ee5c:node19:meter_watts to 0 [DecimalType]

2020-09-04 07:48:54.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.

2020-09-04 07:48:54.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@15d0fef.

2020-09-04 07:48:54.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:54.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:48:54.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:48:54.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:49:05.860 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 21 74 00 00 11 36 00 00 B1 

2020-09-04 07:49:05.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 36 00 00 

2020-09-04 07:49:05.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 36 00 00 

2020-09-04 07:49:05.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:49:05.880 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)

2020-09-04 07:49:05.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false

2020-09-04 07:49:05.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:49:05.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY not supported

2020-09-04 07:49:05.890 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:49:05.894 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=4.406

2020-09-04 07:49:05.897 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:49:05.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=4.406

2020-09-04 07:49:05.903 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4422ee5c:node18:meter_watts to 4.406 [DecimalType]

2020-09-04 07:49:05.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.

2020-09-04 07:49:05.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e3b9a9.

2020-09-04 07:49:05.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:49:05.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:49:05.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:49:05.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:49:24.494 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 13 0A 32 02 21 74 00 00 00 00 00 00 97 

2020-09-04 07:49:24.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:49:24.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 74 00 00 00 00 00 00 

2020-09-04 07:49:24.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:49:24.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)

2020-09-04 07:49:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false

2020-09-04 07:49:24.513 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:49:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported

2020-09-04 07:49:24.518 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:49:24.521 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 19: Meter: Type=Electric(1), Scale=W(2), Value=0E+1

2020-09-04 07:49:24.523 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:49:24.526 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1

2020-09-04 07:49:24.528 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4422ee5c:node19:meter_watts to 0 [DecimalType]

2020-09-04 07:49:24.531 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.

2020-09-04 07:49:24.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@67f178.

2020-09-04 07:49:24.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:49:24.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:49:24.540 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:49:24.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-09-04 07:49:35.861 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 21 74 00 00 11 3E 00 00 B9 

2020-09-04 07:49:35.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 3E 00 00 

2020-09-04 07:49:35.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 74 00 00 11 3E 00 00 

2020-09-04 07:49:35.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-09-04 07:49:35.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)

2020-09-04 07:49:35.875 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false

2020-09-04 07:49:35.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0

2020-09-04 07:49:35.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY not supported

2020-09-04 07:49:35.881 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT

2020-09-04 07:49:35.884 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=4.414

2020-09-04 07:49:35.886 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent

2020-09-04 07:49:35.888 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=4.414

2020-09-04 07:49:35.891 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4422ee5c:node18:meter_watts to 4.414 [DecimalType]

2020-09-04 07:49:35.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.

2020-09-04 07:49:35.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1856809.

2020-09-04 07:49:35.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:49:35.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-09-04 07:49:35.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-09-04 07:49:35.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

[Bruce_Osborne]

@QuagmireMan

Let me know if you want this unfiltered

Chris has said only unfiltered logs are useful for troubleshoting.

Perhaps the log viewer could provide hints.

Z-Wave Log Viewer

[QuagmireMan (Chris S)]

The file was too large to post here.

https://www.filehosting.org/file/details/891701/openhab.zip

[QuagmireMan (Chris S)]

@QuagmireMan

is? I have tried everything I could think of to try and resolve this issue, outside of the hard reset of my z-wave controller.

I have tried to factory reset the lock then exclude I have also tried it the other way around.

I updated to 2.5.8-1 and have reset my pi several times.

It gets included securely, but then no commands or no data (battery level) comes back from the lock

I think I found the answer on the Home Assistant forums. Looks like my lock might have anti-theft enabled.

[INFO] Kwikset 910 with Zwave

[QuagmireMan (Chris S)]

Here is another link talking about how to disable the Anti-theft if you still have a Vivint panel… which I do not.

Trouble connecting kwikset zwave lock - HomeSeer Message Board

I am going to see if there is anyway I can issue a command from the Z-wave PC controller software from Silicon Labs to remove that Anti-theft setting. I will post back if I find a solution.

Update: Just confirmed with kwikset support that if Anti-Theft was enabled, unless it goes back to the original panel for a proper unpairing the lock will not work period. Lesson is be careful buying refurbished or used locks.

[Mr-JR (James R)]

FYI - I know this is an old thread but I just moved my zwave 914 lock from SmartThings to OH3 … Here is what happened:

1. I excluded the device from SmartThings (need to press the A button on the lock)
2. I scanned for new zwave devices and it "Included: it … took about 10 minutes to actually show up.
3. The device would not lock. So I searched and found this comment from @chris
4. The device zwave_secure = False
5. I moved the device to be right next to the controller (probably important)
6. Put the OH controller into Exclude mode and pressed the A button. Device goes Offline.
7. Rescanned the zwave network, device shows up and is now zwave_secure - True
8. Device works!

Thanks @chris

[daemonalec (Alec Kloss)]

I to am one of the cursed with some of these locks. One of them lost it’s inclusion spontaneously and I went through the inclusion process again, with several unsuccessful attempts before making it work. Some notes from my successful inclusion in case it helps anyone else.

1. z-stick and lock were within a few inches of each other.
2. lock had battery power supplied to it for several minutes before the inclusion attempt
3. lock was not reset since a prior attempt, but had been reset (several times) at some point this during my attempts
4. After putting the z-stick in inclusion mode, I watched the debug-level zwave log enumerate all the already-included devices BEFORE pushing the A button on the lock; this takes a few seconds for the devices I have. This message looks like this:

2023-01-14 09:42:18.368 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 17: Device discovery completed
2023-01-14 09:42:18.369 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 17: Device discovery resolved to thingType zwave:zooz_zen24_04_000

5. Once inclusion mode completed, the device appeared in the inbox, but was still unknown. I added it, went and got a cup of tea, and by the time I got back, it was identified and working. (!) Yay!

Step 4 above is what seemed different to me than my other (failed) attempts. Just writing this here in case it helps someone, or even future me the next time I have to do this!

[daemonalec (Alec Kloss)]

Hrm… tried like 10 times on another lock… don’t forget about the advice about fresh batteries. On the 11th try, I used the batteries that were in the lock that worked earlier today, and TADA, success.