I can’t get my Idlock 101 to work with opebhab 2.5. It worked fine in 2.4 but now i can’t get it to work. I can include it but it note secure.
I’ve trided to change the security code because i read that it worked fore some people with the 150 version. But i can’t get it to work.
Any suggestions?
Please describe what the problem is - what are you doing, what happens, and what doesn’t work. Providing a debug log will also be useful.
If you’ve not copied over the keys from your previous system, you’ll need to reset the device and include it again.
This is a new openHab installation.
I have reset the lock an updated the firmware to the latest version that I got from Idlock.
The inclusion works but not secure.
In paperui it lookes like it is online. But I get nothing from it.
And what did the debug log show? Anything useful? I can take a look if you want to provide it?
The only thing i found in the log file is
2020-06-06 17:47:59.734 [WARN ] [nal.converter.ZWaveDoorLockConverter] - NODE 35: Command class COMMAND_CLASS_DOOR_LOCK not found
2020-06-06 17:48:04.187 [WARN ] [nal.converter.ZWaveDoorLockConverter] - NODE 35: Command class COMMAND_CLASS_DOOR_LOCK not found
2020-06-06 17:48:10.480 [WARN ] [nal.converter.ZWaveDoorLockConverter] - NODE 35: Command class COMMAND_CLASS_DOOR_LOCK not found
This is the only entries i can find, can i be looking in the wrong log file. I think this is from when i tied to controll the lock
Are you sure that you’ve enabled debug logging? It seems a bit strange that the only log entries are at INFO level. There should be a huge amount of logging when you include the device.
I tried to include it again, this time ith debug logg enabled 
this is the logg the node is number 39
2020-06-06 18:11:32.332 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=3, callback=26, payload=1A 03 27 0A 04 40 03 5E 72 98 5A 80 73 70
2020-06-06 18:11:32.336 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - NODE 39: Adding slave.
2020-06-06 18:11:32.338 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeStart, new event IncludeSlaveFound
2020-06-06 18:11:32.348 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Including node.
2020-06-06 18:11:32.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-06-06 18:11:32.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-06-06 18:11:33.932 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 1A 05 27 00 8A
2020-06-06 18:11:33.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=26, payload=1A 05 27 00
2020-06-06 18:11:33.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=26, payload=1A 05 27 00
2020-06-06 18:11:33.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-06-06 18:11:33.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-06-06 18:11:33.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2020-06-06 18:11:33.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1003: (Callback 28)
2020-06-06 18:11:33.947 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - NO callback match! (28 <> 26)
2020-06-06 18:11:33.949 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=5, callback=26, payload=1A 05 27 00
2020-06-06 18:11:33.951 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - NODE 39: Add Node: Protocol done.
2020-06-06 18:11:33.953 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSlaveFound, new event IncludeProtocolDone
2020-06-06 18:11:33.955 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-06-06 18:11:33.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 1004 to queue - size 1
2020-06-06 18:11:33.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-06-06 18:11:33.964 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 1D A8
2020-06-06 18:11:33.967 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 1D A8
2020-06-06 18:11:33.969 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-06-06 18:11:33.971 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-06-06 18:11:33.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-06 18:11:33.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1004: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29
2020-06-06 18:11:33.976 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion protocol completed.
2020-06-06 18:11:33.980 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_NO_OPERATION
2020-06-06 18:11:33.983 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created
2020-06-06 18:11:33.986 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Version = 1, version set. Enabling extra functionality.
2020-06-06 18:11:33.989 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes.
2020-06-06 18:11:33.993 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_BASIC
2020-06-06 18:11:33.996 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_BASIC, endpoint 0 created
2020-06-06 18:11:33.999 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes.
2020-06-06 18:11:34.002 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO
2020-06-06 18:11:34.005 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created
2020-06-06 18:11:34.008 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO.
2020-06-06 18:11:34.010 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes.
2020-06-06 18:11:34.012 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC
2020-06-06 18:11:34.014 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created
2020-06-06 18:11:34.016 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC.
2020-06-06 18:11:34.018 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes.
2020-06-06 18:11:34.020 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_SECURITY
2020-06-06 18:11:34.023 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_SECURITY, endpoint 0 created
2020-06-06 18:11:34.024 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 1D 06 27 00 8E
2020-06-06 18:11:34.025 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion is adding command class COMMAND_CLASS_SECURITY.
2020-06-06 18:11:34.027 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 39: Updated networkKey
2020-06-06 18:11:34.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=29, payload=1D 06 27 00
2020-06-06 18:11:34.029 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 39: setupNetworkKey useSchemeZero=false
2020-06-06 18:11:34.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_SECURITY to the list of supported command classes.
2020-06-06 18:11:34.035 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY
2020-06-06 18:11:34.037 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created
2020-06-06 18:11:34.039 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY.
2020-06-06 18:11:34.041 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes.
2020-06-06 18:11:34.042 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_BATTERY
2020-06-06 18:11:34.044 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_BATTERY, endpoint 0 created
2020-06-06 18:11:34.045 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion is adding command class COMMAND_CLASS_BATTERY.
2020-06-06 18:11:34.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_BATTERY to the list of supported command classes.
2020-06-06 18:11:34.047 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_POWERLEVEL
2020-06-06 18:11:34.049 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_POWERLEVEL, endpoint 0 created
2020-06-06 18:11:34.050 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion is adding command class COMMAND_CLASS_POWERLEVEL.
2020-06-06 18:11:34.051 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_POWERLEVEL to the list of supported command classes.
2020-06-06 18:11:34.052 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_CONFIGURATION
2020-06-06 18:11:34.053 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_CONFIGURATION, endpoint 0 created
2020-06-06 18:11:34.055 [DEBUG] [al.protocol.ZWaveInclusionController] - NODE 39: Inclusion is adding command class COMMAND_CLASS_CONFIGURATION.
2020-06-06 18:11:34.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_CONFIGURATION to the list of supported command classes.
2020-06-06 18:11:34.057 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 39: ZWaveController include node
2020-06-06 18:11:34.058 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 39: Starting initialisation from INCLUSION_START
2020-06-06 18:11:34.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-06 18:11:34.061 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 39: Node advancer: Node just included (3629875)
2020-06-06 18:11:34.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1004: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29
2020-06-06 18:11:34.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-06-06 18:11:34.062 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 39: Node advancer - advancing to IDENTIFY_NODE
2020-06-06 18:11:34.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=29, payload=1D 06 27 00
2020-06-06 18:11:34.063 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 39: Node advancer: Initialisation starting from inclusion
2020-06-06 18:11:34.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1d1f27d
2020-06-06 18:11:34.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1004: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29
2020-06-06 18:11:34.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2
2020-06-06 18:11:34.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1004: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29
2020-06-06 18:11:34.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1003: (Callback 28)
2020-06-06 18:11:34.069 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - NO callback match! (28 <> 29)
2020-06-06 18:11:34.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1004: (Callback 29)
2020-06-06 18:11:34.071 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-06-06 18:11:34.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1004: callback 29
2020-06-06 18:11:34.073 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=29, payload=1D 06 27 00
2020-06-06 18:11:34.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 1005 to queue - size 1
2020-06-06 18:11:34.075 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2020-06-06 18:11:34.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false.
2020-06-06 18:11:34.078 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 39: Device discovered
2020-06-06 18:11:34.082 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:fef413b3:node39' to inbox.
2020-06-06 18:11:34.086 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeProtocolDone, new event IncludeDone
2020-06-06 18:11:34.087 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 00 18 12 56 01 32 02 A1 64 00 03 9D 5C 00 0B 00 03 9D 98 01 2E AB
2020-06-06 18:11:34.087 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-06-06 18:11:34.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 1006 to queue - size 2
2020-06-06 18:11:34.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false.
2020-06-06 18:11:34.091 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion
2020-06-06 18:11:34.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 12 56 01 32 02 A1 64 00 03 9D 5C 00 0B 00 03 9D 98 01 2E
2020-06-06 18:11:34.093 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done
2020-06-06 18:11:34.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1004: Advanced to DONE
2020-06-06 18:11:34.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 122ms
2020-06-06 18:11:34.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 1004: Transaction completed
2020-06-06 18:11:34.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:1004 DONE
2020-06-06 18:11:34.099 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 12 56 01 32 02 A1 64 00 03 9D 5C 00 0B 00 03 9D 98 01 2E
2020-06-06 18:11:34.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-06-06 18:11:34.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:DONE)
2020-06-06 18:11:34.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false
2020-06-06 18:11:34.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Decapsulating COMMAND_CLASS_CRC_16_ENCAP
2020-06-06 18:11:34.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-06-06 18:11:34.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2020-06-06 18:11:34.106 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 24: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-06-06 18:11:34.107 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 24: Meter: Type=Electric(1), Scale=V(4), Value=236.892
2020-06-06 18:11:34.108 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-06-06 18:11:34.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=236.892
2020-06-06 18:11:34.110 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 17: TID 1003: Timeout at state WAIT_DATA. 3 retries remaining.
2020-06-06 18:11:34.111 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Updating channel state zwave:device:fef413b3:node24:meter_voltage to 236.892 [DecimalType]
2020-06-06 18:11:34.112 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1003: Transaction CANCELLED
2020-06-06 18:11:34.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.
2020-06-06 18:11:34.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f8729.
2020-06-06 18:11:34.114 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-06-06 18:11:34.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 17: notifyTransactionResponse TID:1003 CANCELLED
2020-06-06 18:11:34.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1003: Transaction event listener: DONE: CANCELLED ->
2020-06-06 18:11:34.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-06-06 18:11:34.118 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Node Init response (3) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@15e50f0
2020-06-06 18:11:34.120 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: No data from device, but it was ACK'd. Possibly not supported? (Try 3)
2020-06-06 18:11:34.120 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 27 9D
2020-06-06 18:11:34.122 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 27 9D
2020-06-06 18:11:34.124 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-06-06 18:11:34.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1005: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-06-06 18:11:34.127 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-06-06 18:11:34.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-06-06 18:11:34.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-06 18:11:34.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-06-06 18:11:34.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-06 18:11:34.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1005: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-06-06 18:11:34.132 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 53 DC 01 04 40 03 7F
2020-06-06 18:11:34.133 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-06-06 18:11:34.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-06-06 18:11:34.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=53 DC 01 04 40 03
2020-06-06 18:11:34.135 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-06-06 18:11:34.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=53 DC 01 04 40 03
2020-06-06 18:11:34.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1005: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-06-06 18:11:34.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-06-06 18:11:34.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1005: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-06-06 18:11:34.142 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=53 DC 01 04 40 03
2020-06-06 18:11:34.143 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: ProtocolInfo
2020-06-06 18:11:34.144 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Listening = false
2020-06-06 18:11:34.145 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Routing = true
2020-06-06 18:11:34.146 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Beaming = true
2020-06-06 18:11:34.148 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Version = 4
2020-06-06 18:11:34.149 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: FLIRS = true
2020-06-06 18:11:34.150 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Security = false
2020-06-06 18:11:34.151 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Max Baud = 40000
2020-06-06 18:11:34.152 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Basic = BASIC_TYPE_ROUTING_SLAVE
2020-06-06 18:11:34.153 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Generic = GENERIC_TYPE_ENTRY_CONTROL
2020-06-06 18:11:34.154 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 39: Specific = SPECIFIC_TYPE_SECURE_KEYPAD_DOOR_LOCK
2020-06-06 18:11:34.156 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_NO_OPERATION
2020-06-06 18:11:34.158 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created
2020-06-06 18:11:34.160 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Version = 1, version set. Enabling extra functionality.
2020-06-06 18:11:34.162 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes.
2020-06-06 18:11:34.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Creating new instance of command class COMMAND_CLASS_BASIC
2020-06-06 18:11:34.166 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 39: Command class COMMAND_CLASS_BASIC, endpoint 0 created
2020-06-06 18:11:34.168 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 39: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes.
2020-06-06 18:11:34.170 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1005: Transaction COMPLETED
2020-06-06 18:11:34.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 45ms
2020-06-06 18:11:34.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 1005: Transaction completed
2020-06-06 18:11:34.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:1005 DONE
2020-06-06 18:11:34.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-06-06 18:11:34.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1005: Transaction event listener: DONE: DONE ->
2020-06-06 18:11:34.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-06-06 18:11:34.179 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 39: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1161f77
2020-06-06 18:11:34.182 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5
2020-06-06 18:11:34.182 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 39: Node Init transaction completed with response COMPLETE
2020-06-06 18:11:34.184 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5
2020-06-06 18:11:34.186 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-06-06 18:11:34.188 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-06-06 18:11:34.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1006: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-06-06 18:11:34.190 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-06 18:11:34.192 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-06 18:11:34.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1006: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-06-06 18:11:34.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-06-06 18:11:34.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
Is this the only thing in the log? This is only 1 second so it’s hard really to see what’s happening - it shows the inclusion, but not the configuration which normally comes next. The next part is where security is configured.
If you can provide the full log it will be more useful. If you can post it somewhere like dropbox rather than trying to paste it into the forum that’s probably best.
It looks like the device is securely included, and in general the security is working fine. The initialisation looks like it’s probably completed, although I’m not 100% sure, but the last messages are it downloading the user codes.
I don’t see any commands to lock/unlock the door - what exactly isn’t working? Can you provide a short log that shows the problem? eg opening the door, or changing the codes…
This time it was securely included. This was literally the 15th time i tried. And i can get some information from it. But only thing i’m getting is the lock state. In openhab 2.4 i got more information from the lock, like how it was opened(code, tag etc).
here is one more log file where I open and close the door
https://sprend.com/sv/download?C=4ed5d2132ed14f7c8a735ff18c4d9a8b
Ok, that good. Unfortunately I can’t commend on what went wrong before that without logs that I guess you don’t have. At least it’s working.
Possibly there are more channels required. in the database. What device (exactly) do you have? Can you tell me the database reference from the properties?
IDL-101 Z-Wave Lock
dbReference 384
That entry has not changed in 2 years.
https://www.cd-jackson.com/index.php/zwave/zwave-device-database/zwave-device-list/devicesummary/384
From what I can tell, there’s no additional information in the commands in this log.
No i can’t see anything either. When I “updated” the firmware I noticed that the name of the lock had changed. I belive that I had the firmware for the newer model of the lock and therefor had more information available. But at lest its working now
Thanks for the help