I was a bit blurry eyed when I responded above. I see HABmin shows a green check mark for “Using Security” so I guess that means the inclusion was secure.
I have been spending a lot of time going through openhab.log for this device (NODE 27). There is a lot I don’t understand with Z-Wave but I found some interesting things. If I initiate a Lock Change (Lock or Unlock but Unlock in this case) I will see a burst that includes “alarm_raw to {“type”:“25”,“value”:“1”}” which means “Lock Un-Secured by Controller – Successful (Fully retracted)” Then the burst ends.
Then few seconds later another burst happens and includes “alarm_raw to {“type”:“21”,“value”:“0”}” which means " Lock Secured using Keyed cylinder or inside thumb-turn ". But nobody is at the lock to cause this and the lock remains Unlocked. One peculiarity is the “value” is 0 where as the documentation says for this message the “value” should be 1.
Also, a few lines before this I see:
ALARM report - 21 = 0 and
Alarm Type = WATER_QUALITY (21).
Why the heck is a lock reporting WATER_QUALITY ?
I have included the log below.
The first line at 2020-07-24 09:12:59.586 is the start of the sequence.
The alarm_raw type 25 is at time 09:13:02.559.
Then the WATER_QUALITY is at time 09:13:07.961. Over 5 seconds later.
Here is the log:
t
2020-07-24 09:12:59.586 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Command received zwave:device:fe40ab17:node27:lock_door --> OFF [OnOffType]
2020-07-24 09:12:59.588 [DEBUG] [mmandclass.ZWaveDoorLockCommandClass] - NODE 27: Creating new message for application command DOORLOCK_SET, value 0
2020-07-24 09:12:59.591 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY required on COMMAND_CLASS_DOOR_LOCK
2020-07-24 09:12:59.593 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_DOOR_LOCK is required to be secured
2020-07-24 09:12:59.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Bump transaction 3073 priority from Get to Immediate
2020-07-24 09:12:59.597 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2020-07-24 09:12:59.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 3073 to queue - size 8
2020-07-24 09:12:59.605 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: isNonceAvailable = null
2020-07-24 09:12:59.615 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 09 00 13 1B 02 98 40 25 5E 5F
2020-07-24 09:12:59.627 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling initialised at 1800 seconds - start in 1500 milliseconds.
2020-07-24 09:12:59.706 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:12:59.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3074: Transaction not completed
2020-07-24 09:13:00.922 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 94, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:00.924 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:00.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3074: Transaction not completed
2020-07-24 09:13:00.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:00.943 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:00.945 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-07-24 09:13:00.947 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY check internal
2020-07-24 09:13:00.949 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT
2020-07-24 09:13:00.951 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received start…
2020-07-24 09:13:00.954 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received start null
2020-07-24 09:13:00.956 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received ZWaveNonce [nonceBytes=(4C 35 9A 89 B2 4A 34 7C ), timer=1094889, valid=true]
2020-07-24 09:13:00.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:00.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@9442ce.
2020-07-24 09:13:00.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@9442ce.
2020-07-24 09:13:00.964 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3074 DONE
2020-07-24 09:13:00.967 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:00.984 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: SECURITY_TXD 62 01 00
2020-07-24 09:13:00.996 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 1E 00 13 1B 17 98 81 D1 50 30 82 C2 39 F5 7F 31 1C 48 75 4C 9D E9 78 49 AA F3 E1 49 25 5F 37
2020-07-24 09:13:01.034 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:01.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3073: Transaction not completed
2020-07-24 09:13:01.058 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 95, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:01.059 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:01.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Response processed after 63ms
2020-07-24 09:13:01.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3073: Transaction completed
2020-07-24 09:13:01.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3073 DONE
2020-07-24 09:13:01.070 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:01.127 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling…
2020-07-24 09:13:01.129 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:fe40ab17:node27:lock_door
2020-07-24 09:13:01.131 [DEBUG] [nal.converter.ZWaveDoorLockConverter] - NODE 27: Generating poll message for COMMAND_CLASS_DOOR_LOCK endpoint 0
2020-07-24 09:13:01.136 [DEBUG] [mmandclass.ZWaveDoorLockCommandClass] - NODE 27: Creating new message for application command DOORLOCK_GET
2020-07-24 09:13:01.138 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY required on COMMAND_CLASS_DOOR_LOCK
2020-07-24 09:13:01.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_DOOR_LOCK is required to be secured
2020-07-24 09:13:01.144 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:fe40ab17:node27:alarm_number
2020-07-24 09:13:01.146 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Generating poll message for COMMAND_CLASS_ALARM, endpoint 0, alarm null, event null
2020-07-24 09:13:01.148 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Creating new message for application command NOTIFICATION_GET V1
2020-07-24 09:13:01.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY required on COMMAND_CLASS_ALARM
2020-07-24 09:13:01.154 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_ALARM is required to be secured
2020-07-24 09:13:01.156 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:fe40ab17:node27:alarm_raw
2020-07-24 09:13:01.158 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Generating poll message for COMMAND_CLASS_ALARM, endpoint 0, alarm null, event null
2020-07-24 09:13:01.160 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Creating new message for application command NOTIFICATION_GET V1
2020-07-24 09:13:01.162 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY required on COMMAND_CLASS_ALARM
2020-07-24 09:13:01.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_ALARM is required to be secured
2020-07-24 09:13:01.166 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Bump transaction 3075 priority from Get to Immediate
2020-07-24 09:13:01.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2020-07-24 09:13:01.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 3075 to queue - size 8
2020-07-24 09:13:01.175 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: isNonceAvailable = null
2020-07-24 09:13:01.185 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 09 00 13 1B 02 98 40 25 60 61
2020-07-24 09:13:01.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Bump transaction 3077 priority from Config to Immediate
2020-07-24 09:13:01.193 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2020-07-24 09:13:01.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 3077 to queue - size 8
2020-07-24 09:13:01.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Bump transaction 3078 priority from Config to Immediate
2020-07-24 09:13:01.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2020-07-24 09:13:01.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Transaction already in queue - removed original
2020-07-24 09:13:01.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 3078 to queue - size 8
2020-07-24 09:13:01.228 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:01.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3076: Transaction not completed
2020-07-24 09:13:01.260 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 96, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:01.261 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:01.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3076: Transaction not completed
2020-07-24 09:13:01.272 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:01.275 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:01.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-07-24 09:13:01.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY check internal
2020-07-24 09:13:01.281 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT
2020-07-24 09:13:01.283 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received start…
2020-07-24 09:13:01.285 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received start null
2020-07-24 09:13:01.288 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received ZWaveNonce [nonceBytes=(47 35 79 94 AD 1E C4 94 ), timer=690100, valid=true]
2020-07-24 09:13:01.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:01.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e025bd.
2020-07-24 09:13:01.293 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e025bd.
2020-07-24 09:13:01.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3076 DONE
2020-07-24 09:13:01.298 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:01.311 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: SECURITY_TXD 62 02
2020-07-24 09:13:01.319 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 1D 00 13 1B 16 98 81 14 64 5C 16 60 1D 9C 7C E7 12 ED 47 AD 66 38 30 CA E8 8F 17 25 61 20
2020-07-24 09:13:01.351 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:01.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3075: Transaction not completed
2020-07-24 09:13:01.385 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 97, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:01.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:01.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3075: Transaction not completed
2020-07-24 09:13:01.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:01.968 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:01.970 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-07-24 09:13:01.972 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY check internal
2020-07-24 09:13:01.974 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2020-07-24 09:13:01.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added to secure queue - size 1
2020-07-24 09:13:01.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:01.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@be9426.
2020-07-24 09:13:01.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@be9426.
2020-07-24 09:13:02.003 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 11 00 13 1B 0A 98 80 58 FB 2E 6C 26 35 F6 AA 25 62 1D
2020-07-24 09:13:02.037 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:02.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3079: Transaction not completed
2020-07-24 09:13:02.067 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 98, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:02.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:02.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Response processed after 66ms
2020-07-24 09:13:02.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3079: Transaction completed
2020-07-24 09:13:02.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3079 DONE
2020-07-24 09:13:02.080 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:02.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:02.089 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:02.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Decapsulating COMMAND_CLASS_SECURITY
2020-07-24 09:13:02.095 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: SECURITY_RXD 22 02 00
2020-07-24 09:13:02.098 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_APPLICATION_STATUS, endpoint 0
2020-07-24 09:13:02.100 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_APPLICATION_STATUS V0 APPLICATION_REJECTED_REQUEST
2020-07-24 09:13:02.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:02.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c18a56.
2020-07-24 09:13:02.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c18a56.
2020-07-24 09:13:02.413 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:02.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:02.417 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-07-24 09:13:02.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY check internal
2020-07-24 09:13:02.421 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2020-07-24 09:13:02.425 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added to secure queue - size 1
2020-07-24 09:13:02.428 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:02.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14f2212.
2020-07-24 09:13:02.432 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14f2212.
2020-07-24 09:13:02.445 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 11 00 13 1B 0A 98 80 F9 EC F6 29 A2 32 7D 0A 25 63 9F
2020-07-24 09:13:02.477 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:02.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3080: Transaction not completed
2020-07-24 09:13:02.506 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 99, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:02.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:02.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Response processed after 65ms
2020-07-24 09:13:02.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3080: Transaction completed
2020-07-24 09:13:02.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3080 DONE
2020-07-24 09:13:02.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:02.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:02.531 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:02.533 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Decapsulating COMMAND_CLASS_SECURITY
2020-07-24 09:13:02.536 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: SECURITY_RXD 71 05 19 01
2020-07-24 09:13:02.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2020-07-24 09:13:02.540 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V1 NOTIFICATION_REPORT
2020-07-24 09:13:02.542 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: ALARM report - 25 = 1
2020-07-24 09:13:02.544 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = null (25)
2020-07-24 09:13:02.546 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2020-07-24 09:13:02.548 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=1
2020-07-24 09:13:02.550 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing ALARM
2020-07-24 09:13:02.553 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:fe40ab17:node27:alarm_number to 25 [DecimalType]
2020-07-24 09:13:02.557 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing ALARM
2020-07-24 09:13:02.559 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:fe40ab17:node27:alarm_raw to {“type”:“25”,“value”:“1”} [StringType]
2020-07-24 09:13:02.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:02.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10a3b4b.
2020-07-24 09:13:02.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10a3b4b.
2020-07-24 09:13:06.391 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 27: TID 3075: Timeout at state WAIT_DATA. 3 retries remaining.
2020-07-24 09:13:06.396 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:06.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3075 CANCELLED
2020-07-24 09:13:06.404 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: isNonceAvailable = null
2020-07-24 09:13:06.413 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 09 00 13 1B 02 98 40 25 64 65
2020-07-24 09:13:06.452 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:06.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3081: Transaction not completed
2020-07-24 09:13:07.714 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 100, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:07.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:07.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3081: Transaction not completed
2020-07-24 09:13:07.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:07.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:07.730 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-07-24 09:13:07.732 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY check internal
2020-07-24 09:13:07.734 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT
2020-07-24 09:13:07.736 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received start…
2020-07-24 09:13:07.737 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received start null
2020-07-24 09:13:07.740 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: NONCE Received ZWaveNonce [nonceBytes=(B6 25 04 9C 34 6E DE F7 ), timer=628538, valid=true]
2020-07-24 09:13:07.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:07.744 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14988e8.
2020-07-24 09:13:07.745 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14988e8.
2020-07-24 09:13:07.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3081 DONE
2020-07-24 09:13:07.749 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:07.763 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: SECURITY_TXD 71 04 15
2020-07-24 09:13:07.771 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 1E 00 13 1B 17 98 81 5A 03 CD AB 44 2A 1B 26 D8 39 75 B9 B6 94 43 67 5F 63 44 00 24 25 65 BC
2020-07-24 09:13:07.804 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:07.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3078: Transaction not completed
2020-07-24 09:13:07.832 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 101, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:07.834 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:07.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3078: Transaction not completed
2020-07-24 09:13:07.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:07.845 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:07.846 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2020-07-24 09:13:07.848 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY check internal
2020-07-24 09:13:07.849 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2020-07-24 09:13:07.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added to secure queue - size 1
2020-07-24 09:13:07.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:07.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@13222d.
2020-07-24 09:13:07.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@13222d.
2020-07-24 09:13:07.869 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 27: Sending REQUEST Message = 01 11 00 13 1B 0A 98 80 09 71 9A 50 E2 BF 97 58 25 66 97
2020-07-24 09:13:07.895 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: sentData successfully placed on stack.
2020-07-24 09:13:07.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3082: Transaction not completed
2020-07-24 09:13:07.928 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 27: SendData Request. CallBack ID = 102, Status = Transmission complete and ACK received(0)
2020-07-24 09:13:07.929 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:07.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Response processed after 62ms
2020-07-24 09:13:07.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: TID 3082: Transaction completed
2020-07-24 09:13:07.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3082 DONE
2020-07-24 09:13:07.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-24 09:13:07.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE)
2020-07-24 09:13:07.948 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false
2020-07-24 09:13:07.950 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Decapsulating COMMAND_CLASS_SECURITY
2020-07-24 09:13:07.953 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 27: SECURITY_RXD 71 05 15 00
2020-07-24 09:13:07.956 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2020-07-24 09:13:07.957 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_ALARM V1 NOTIFICATION_REPORT
2020-07-24 09:13:07.960 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: ALARM report - 21 = 0
2020-07-24 09:13:07.961 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 27: Alarm Type = WATER_QUALITY (21)
2020-07-24 09:13:07.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2020-07-24 09:13:07.965 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=0
2020-07-24 09:13:07.968 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing ALARM
2020-07-24 09:13:07.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:fe40ab17:node27:alarm_number to 21 [DecimalType]
2020-07-24 09:13:07.973 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 27: Alarm converter processing ALARM
2020-07-24 09:13:07.976 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:fe40ab17:node27:alarm_raw to {“type”:“21”,“value”:“0”} [StringType]
2020-07-24 09:13:07.980 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1.
2020-07-24 09:13:07.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@fcd50f.
2020-07-24 09:13:07.984 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@fcd50f.
2020-07-24 09:13:07.987 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: notifyTransactionResponse TID:3078 DONE
2020-07-24 09:13:07.998 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent