Zigbee2mqtt: Danalock V3 on 1.7.x

Created on 14 Nov 2019  Â·  26Comments  Â·  Source: Koenkk/zigbee2mqtt

Bug Report

What happened

Commands to danalock time out, state does not update. Like this:

zigbee2mqtt:info  2019-11-14T19:51:51: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to 'lock' failed: 'Error: Timeout - 28358 - 1 - 5 - 257 - 11 after 15000ms'","meta":{"friendly_name":"lock"}}'

I could not even repair the device on 1.7.0 and 1.7.1:

zigbee2mqtt:info  2019-11-14T19:58:23: Starting interview of '0x000b57fffe59cf4a'
zigbee2mqtt:info  2019-11-14T19:58:23: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x000b57fffe59cf4a"}}'
zigbee2mqtt:error 2019-11-14T19:58:45: Failed to interview '0x000b57fffe59cf4a', device has not successfully been paired
zigbee2mqtt:info  2019-11-14T19:58:45: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_failed","meta":{"friendly_name":"0x000b57fffe59cf4a"}}'

Reflashing the stick/removing zigbee2mqtt state/config/db files and starting over did not work either.

I'm going to debug this further during the weekend.

What did you expect to happen

Commands do not time out, state updates. Just like on 1.6

How to reproduce it (minimal and precise)

  1. Pair danalock v3 on 1.6.0
  2. Update to 1.7.0
  3. Try to change the state of the lock

Debug Info

zigbee2mqtt version: 1.7.1
CC253X firmware version: CC2531_DEFAULT_20190608

picture martonbtoth

Most helpful comment

danalock-zigbee2mqtt.zip
This should do it, thanks for taking a look guys! :+1:

picture martonbtoth on 25 Nov 2019
👍2

All 26 comments

When executing the commands, does the lock physically change state?

Koenkk picture Koenkk on 15 Nov 2019

If I pair it on 1.6.0 and upgrade to 1.7 then yes. I am able to open/close the lock that way.

martonbtoth picture martonbtoth on 16 Nov 2019

Same problem here.
CC2531 with 

Coordinator firmware version: '{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}}

Danalock V3 used to work before.

Scratch installation of 1.7.1 with no devices paired at the beginning.

Nov 16 12:17:40 rpi3-knx npm[26085]: zigbee2mqtt:info  2019-11-16T11:17:40: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x000b57fffe59d134"}}'
Nov 16 12:17:40 rpi3-knx npm[26085]: zigbee2mqtt:info  2019-11-16T11:17:40: Starting interview of '0x000b57fffe59d134'
Nov 16 12:17:40 rpi3-knx npm[26085]: zigbee2mqtt:info  2019-11-16T11:17:40: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x000b57fffe59d134"}}'
Nov 16 12:18:00 rpi3-knx npm[26085]: zigbee2mqtt:error 2019-11-16T11:18:00: Failed to interview '0x000b57fffe59d134', device has not successfully been paired
Nov 16 12:18:00 rpi3-knx npm[26085]: zigbee2mqtt:info  2019-11-16T11:18:00: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_failed","meta":{"friendly_name":"0x000b57fffe59d134"}}'
nanosonde picture nanosonde on 16 Nov 2019

Herdsman debug logs: 

  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +19ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,12,69,202,43,200,52,209,89,254,255,87,11,0,0,0,129] +29s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,43,200,52,209,89,254,255,87,11,0,0,0,129] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 12 - 2 - 5 - 202 - [+�4�Y��W
                                                                                    ] - 129 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - tcDeviceInd - {"nwkaddr":51243,"extaddr":"0x000b57fffe59d134","parentaddr":0} +29s
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' joined +29s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +6ms
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' accepted by handler +3ms
  zigbee-herdsman:controller:log New device '0x000b57fffe59d134' joined +0ms
  zigbee-herdsman:controller:log Creating device '0x000b57fffe59d134' +0ms
zigbee2mqtt:info  2019-11-16T11:24:43: Device '0x000b57fffe59d134' joined
zigbee2mqtt:info  2019-11-16T11:24:43: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x000b57fffe59d134"}}'
  zigbee-herdsman:controller:log Interview '0x000b57fffe59d134' start +17ms
zigbee2mqtt:info  2019-11-16T11:24:43: Starting interview of '0x000b57fffe59d134'
zigbee2mqtt:info  2019-11-16T11:24:43: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x000b57fffe59d134"}}'
  zigbee-herdsman:controller:device Interview - start device '0x000b57fffe59d134' +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":51243,"nwkaddrofinterest":51243} +57s
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,43,200,43,200,35] +57s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] +46ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [] - 102 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} +57s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,12,69,202,43,200,52,209,89,254,255,87,11,0,0,0,129] +4s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,43,200,52,209,89,254,255,87,11,0,0,0,129] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 12 - 2 - 5 - 202 - [+�4�Y��W
                                                                                    ] - 129 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - tcDeviceInd - {"nwkaddr":51243,"extaddr":"0x000b57fffe59d134","parentaddr":0} +4s
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' joined +4s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +2ms
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' accepted by handler +1ms
  zigbee-herdsman:controller:log Not interviewing '0x000b57fffe59d134', completed 'false', in progress 'true' +1ms
  zigbee-herdsman:controller:device Interview - first node descriptor request failed for '0x000b57fffe59d134', retrying... +10s
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":51243,"nwkaddrofinterest":51243} +10s
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,43,200,43,200,35] +10s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102] +6s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [] - 102 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0} +10s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +2ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,12,69,202,43,200,52,209,89,254,255,87,11,0,0,0,129] +4s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,43,200,52,209,89,254,255,87,11,0,0,0,129] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 12 - 2 - 5 - 202 - [+�4�Y��W
                                                                                    ] - 129 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - tcDeviceInd - {"nwkaddr":51243,"extaddr":"0x000b57fffe59d134","parentaddr":0} +10s
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' joined +10s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +2ms
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' accepted by handler +1ms
  zigbee-herdsman:controller:log Not interviewing '0x000b57fffe59d134', completed 'false', in progress 'true' +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,13,69,193,43,200,43,200,52,209,89,254,255,87,11,0,128,232] +44ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,13,69,193,43,200,43,200,52,209,89,254,255,87,11,0,128,232] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 13 - 2 - 5 - 193 - [+�+�4�Y��W
                                                                                      �] - 232 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - endDeviceAnnceInd - {"srcaddr":51243,"nwkaddr":51243,"ieeeaddr":"0x000b57fffe59d134","capabilities":128} +47ms
  zigbee-herdsman:controller:log Device announce '0x000b57fffe59d134' +46ms
zigbee2mqtt:debug 2019-11-16T11:24:57: Device '0x000b57fffe59d134' announced itself
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +5ms
  zigbee-herdsman:controller:device Interview - failed for device '0x000b57fffe59d134' with error 'Error: AREQ - ZDO - nodeDescRsp after 10000ms
  zigbee-herdsman:controller:device     at Timeout.object.timer.setTimeout [as _onTimeout] (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
  zigbee-herdsman:controller:device     at ontimeout (timers.js:436:11)
  zigbee-herdsman:controller:device     at tryOnTimeout (timers.js:300:5)
  zigbee-herdsman:controller:device     at listOnTimeout (timers.js:263:5)
  zigbee-herdsman:controller:device     at Timer.processTimers (timers.js:223:10)' +10s
  zigbee-herdsman:controller:error Interview failed for '0x000b57fffe59d134 with error 'Error: AREQ - ZDO - nodeDescRsp after 10000ms' +0ms
zigbee2mqtt:error 2019-11-16T11:25:03: Failed to interview '0x000b57fffe59d134', device has not successfully been paired
zigbee2mqtt:info  2019-11-16T11:25:03: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_failed","meta":{"friendly_name":"0x000b57fffe59d134"}}'
nanosonde picture nanosonde on 16 Nov 2019

As I dont have this device myself I need some help of you.

Can you do the following for both Zigbee2mqtt 1.6 and 1.7.1 when pairing the device:

Koenkk picture Koenkk on 17 Nov 2019

I have attached the logs you requested.
Also I was able to pair the lock with 1.7.1 once and I could also record that :)

danalock-pairing-logs.zip

martonbtoth picture martonbtoth on 22 Nov 2019

@martonbtoth
Sorry, the content in the ZIP file is not of much use as you have not startet Z2M as @Koenkk requested: DEBUG=* npm start. At least the log files do not contain any DEBUG level messages.

Furthermore, the archive does not include any captured Zigbee packets from Wireshark for example.
You need sniffing HW for this: e.g. another CC2531 stick.

nanosonde picture nanosonde on 23 Nov 2019

Ahh sorry, I zipped the wrong folder. I'm going to upload the captured packets and the correct logs tomorrow when I get home.

martonbtoth picture martonbtoth on 23 Nov 2019
👍1

danalock-zigbee2mqtt.zip
This should do it, thanks for taking a look guys! :+1:

martonbtoth picture martonbtoth on 25 Nov 2019
👍2

Could you try replacing the content of node_modules/zigbee-herdsman/dist/controller/model/devices.js with https://gist.github.com/Koenkk/3aeb003a91415f2b65049a1b59aec3e5 and try to pair the device again.

Please provide the log when running with DEBUG=zigbee-herdsman:controller* npm start

Koenkk picture Koenkk on 26 Nov 2019

I just had to RMA my Danalock today (its motor has failed 🙄).
@nanosonde could you please take a look?

martonbtoth picture martonbtoth on 26 Nov 2019

Ok, it worked. When hitting the pairing button first time, the device sent a "leave network" message.
After pressing it again, it successfully paired.
However, I saw "node descriptor request" failed two times before it was able to interview the device properly.

> [email protected] start /opt/zigbee2mqtt-1.7.1
> node index.js

zigbee2mqtt:info  2019-11-27T09:53:53: Logging to directory: '/opt/zigbee2mqtt-1.7.1/data/log/2019-11-27.10-53-52'
zigbee2mqtt:debug 2019-11-27T09:53:53: Loaded state from file /opt/zigbee2mqtt-1.7.1/data/state.json
zigbee2mqtt:info  2019-11-27T09:53:53: Starting zigbee2mqtt version 1.7.1 (commit #b459c35)
zigbee2mqtt:info  2019-11-27T09:53:53: Starting zigbee-herdsman...
zigbee2mqtt:debug 2019-11-27T09:53:53: Using zigbee-herdsman with settings: '{"network":{"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11],"networkKey":"HIDDEN"},"databasePath":"/opt/zigbee2mqtt-1.7.1/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt-1.7.1/data/database.db.backup","backupPath":"/opt/zigbee2mqtt-1.7.1/data/coordinator_backup.json","serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/ttyACM0"}}'
  zigbee-herdsman:controller:log Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13],"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/ttyACM0"},"databasePath":"/opt/zigbee2mqtt-1.7.1/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt-1.7.1/data/database.db.backup","backupPath":"/opt/zigbee2mqtt-1.7.1/data/coordinator_backup.json"}' +0ms
  zigbee-herdsman:controller:log Started with result 'resumed' +1s
  zigbee-herdsman:controller:log Injected database: true, adapter: true +0ms
  zigbee-herdsman:controller:log No coordinator in database, querying... +2ms
zigbee2mqtt:info  2019-11-27T09:53:55: zigbee-herdsman started
zigbee2mqtt:info  2019-11-27T09:53:55: Coordinator firmware version: '{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}}'
zigbee2mqtt:debug 2019-11-27T09:53:55: Zigbee network parameters: {"panID":6754,"extendedPanID":"0xdddddddddddddddd","channel":11}
zigbee2mqtt:info  2019-11-27T09:53:55: Currently 0 devices are joined:
zigbee2mqtt:warn  2019-11-27T09:53:55: `permit_join` set to  `true` in configuration.yaml.
zigbee2mqtt:warn  2019-11-27T09:53:55: Allowing new devices to join.
zigbee2mqtt:warn  2019-11-27T09:53:55: Set `permit_join` to `false` once you joined all devices.
zigbee2mqtt:info  2019-11-27T09:53:55: Zigbee: allowing new devices to join.
  zigbee-herdsman:controller:log Permit joining +187ms
zigbee2mqtt:info  2019-11-27T09:53:55: Connecting to MQTT server at mqtt://192.168.10.2
zigbee2mqtt:info  2019-11-27T09:53:55: Connected to MQTT server
zigbee2mqtt:info  2019-11-27T09:53:55: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload 'online'
zigbee2mqtt:info  2019-11-27T09:53:55: MQTT publish: topic 'zigbee2mqtt/bridge/config', payload '{"version":"1.7.1","commit":"b459c35","coordinator":{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}},"log_level":"debug","permit_join":true}'
  zigbee-herdsman:controller:log Device leave '0x000b57fffe59d134' +34s
zigbee2mqtt:warn  2019-11-27T09:54:28: Device '0x000b57fffe59d134' left the network
zigbee2mqtt:info  2019-11-27T09:54:28: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_removed","message":"left_network","meta":{"friendly_name":"0x000b57fffe59d134"}}'
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' joined +29s
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' accepted by handler +2ms
  zigbee-herdsman:controller:log New device '0x000b57fffe59d134' joined +1ms
  zigbee-herdsman:controller:log Creating device '0x000b57fffe59d134' +0ms
zigbee2mqtt:info  2019-11-27T09:54:58: Device '0x000b57fffe59d134' joined
zigbee2mqtt:info  2019-11-27T09:54:58: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x000b57fffe59d134"}}'
  zigbee-herdsman:controller:log Interview '0x000b57fffe59d134' start +52ms
zigbee2mqtt:info  2019-11-27T09:54:58: Starting interview of '0x000b57fffe59d134'
zigbee2mqtt:info  2019-11-27T09:54:58: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x000b57fffe59d134"}}'
  zigbee-herdsman:controller:device Interview - start device '0x000b57fffe59d134' +0ms
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' joined +9s
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' accepted by handler +1ms
  zigbee-herdsman:controller:log Not interviewing '0x000b57fffe59d134', completed 'false', in progress 'true' +1ms
  zigbee-herdsman:controller:device Interview - 1 node descriptor request failed for '0x000b57fffe59d134', retrying... +10s
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' joined +4s
  zigbee-herdsman:controller:log Device '0x000b57fffe59d134' accepted by handler +1ms
  zigbee-herdsman:controller:log Not interviewing '0x000b57fffe59d134', completed 'false', in progress 'true' +0ms
  zigbee-herdsman:controller:log Device announce '0x000b57fffe59d134' +251ms
zigbee2mqtt:debug 2019-11-27T09:55:11: Device '0x000b57fffe59d134' announced itself
  zigbee-herdsman:controller:device Interview - 2 node descriptor request failed for '0x000b57fffe59d134', retrying... +10s
  zigbee-herdsman:controller:device Interview - got node descriptor for device '0x000b57fffe59d134' +463ms
  zigbee-herdsman:controller:device Interview - got active endpoints for device '0x000b57fffe59d134' +498ms
  zigbee-herdsman:controller:device Interview - got simple descriptor for endpoint '1' device '0x000b57fffe59d134' +504ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true},"transactionSequenceNumber":2,"manufacturerCode":null,"commandIdentifier":1},"Payload":[{"attrId":5,"status":0,"dataType":66,"attrData":"V3-BTZB"},{"attrId":4,"status":0,"dataType":66,"attrData":"Danalock"},{"attrId":7,"status":0,"dataType":48,"attrData":3}],"Cluster":{"ID":0,"attributes":{"zclVersion":{"ID":0,"type":32,"name":"zclVersion"},"appVersion":{"ID":1,"type":32,"name":"appVersion"},"stackVersion":{"ID":2,"type":32,"name":"stackVersion"},"hwVersion":{"ID":3,"type":32,"name":"hwVersion"},"manufacturerName":{"ID":4,"type":66,"name":"manufacturerName"},"modelId":{"ID":5,"type":66,"name":"modelId"},"dateCode":{"ID":6,"type":66,"name":"dateCode"},"powerSource":{"ID":7,"type":48,"name":"powerSource"},"appProfileVersion":{"ID":8,"type":48,"name":"appProfileVersion"},"swBuildId":{"ID":16384,"type":66,"name":"swBuildId"},"locationDesc":{"ID":16,"type":66,"name":"locationDesc"},"physicalEnv":{"ID":17,"type":48,"name":"physicalEnv"},"deviceEnabled":{"ID":18,"type":16,"name":"deviceEnabled"},"alarmMask":{"ID":19,"type":24,"name":"alarmMask"},"disableLocalConfig":{"ID":20,"type":24,"name":"disableLocalConfig"}},"name":"genBasic","commands":{"resetFactDefault":{"ID":0,"parameters":[],"name":"resetFactDefault"}},"commandsResponse":{}}},"networkAddress":6263,"endpoint":1,"linkquality":44,"groupID":0}' +9s
zigbee2mqtt:debug 2019-11-27T09:55:20: Received Zigbee message from '0x000b57fffe59d134', type 'readResponse', cluster 'genBasic', data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' from endpoint 1 with groupID 0
zigbee2mqtt:debug 2019-11-27T09:55:20: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}'
  zigbee-herdsman:controller:device Interview - got 'modelId,manufacturerName,powerSource' for device '0x000b57fffe59d134' +537ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true},"transactionSequenceNumber":3,"manufacturerCode":null,"commandIdentifier":1},"Payload":[{"attrId":0,"status":0,"dataType":32,"attrData":2},{"attrId":1,"status":0,"dataType":32,"attrData":1},{"attrId":2,"status":0,"dataType":32,"attrData":1}],"Cluster":{"ID":0,"attributes":{"zclVersion":{"ID":0,"type":32,"name":"zclVersion"},"appVersion":{"ID":1,"type":32,"name":"appVersion"},"stackVersion":{"ID":2,"type":32,"name":"stackVersion"},"hwVersion":{"ID":3,"type":32,"name":"hwVersion"},"manufacturerName":{"ID":4,"type":66,"name":"manufacturerName"},"modelId":{"ID":5,"type":66,"name":"modelId"},"dateCode":{"ID":6,"type":66,"name":"dateCode"},"powerSource":{"ID":7,"type":48,"name":"powerSource"},"appProfileVersion":{"ID":8,"type":48,"name":"appProfileVersion"},"swBuildId":{"ID":16384,"type":66,"name":"swBuildId"},"locationDesc":{"ID":16,"type":66,"name":"locationDesc"},"physicalEnv":{"ID":17,"type":48,"name":"physicalEnv"},"deviceEnabled":{"ID":18,"type":16,"name":"deviceEnabled"},"alarmMask":{"ID":19,"type":24,"name":"alarmMask"},"disableLocalConfig":{"ID":20,"type":24,"name":"disableLocalConfig"}},"name":"genBasic","commands":{"resetFactDefault":{"ID":0,"parameters":[],"name":"resetFactDefault"}},"commandsResponse":{}}},"networkAddress":6263,"endpoint":1,"linkquality":44,"groupID":0}' +992ms
zigbee2mqtt:debug 2019-11-27T09:55:21: Received Zigbee message from '0x000b57fffe59d134', type 'readResponse', cluster 'genBasic', data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' from endpoint 1 with groupID 0
zigbee2mqtt:debug 2019-11-27T09:55:21: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"zclVersion":2,"appVersion":1,"stackVersion":1}'
  zigbee-herdsman:controller:device Interview - got 'zclVersion,appVersion,stackVersion' for device '0x000b57fffe59d134' +985ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true},"transactionSequenceNumber":4,"manufacturerCode":null,"commandIdentifier":1},"Payload":[{"attrId":3,"status":0,"dataType":32,"attrData":4},{"attrId":6,"status":0,"dataType":66,"attrData":"19042019\u0000\u0000\u0000\u0000\u0000\u0000\u0000\u0000"},{"attrId":16384,"status":134}],"Cluster":{"ID":0,"attributes":{"zclVersion":{"ID":0,"type":32,"name":"zclVersion"},"appVersion":{"ID":1,"type":32,"name":"appVersion"},"stackVersion":{"ID":2,"type":32,"name":"stackVersion"},"hwVersion":{"ID":3,"type":32,"name":"hwVersion"},"manufacturerName":{"ID":4,"type":66,"name":"manufacturerName"},"modelId":{"ID":5,"type":66,"name":"modelId"},"dateCode":{"ID":6,"type":66,"name":"dateCode"},"powerSource":{"ID":7,"type":48,"name":"powerSource"},"appProfileVersion":{"ID":8,"type":48,"name":"appProfileVersion"},"swBuildId":{"ID":16384,"type":66,"name":"swBuildId"},"locationDesc":{"ID":16,"type":66,"name":"locationDesc"},"physicalEnv":{"ID":17,"type":48,"name":"physicalEnv"},"deviceEnabled":{"ID":18,"type":16,"name":"deviceEnabled"},"alarmMask":{"ID":19,"type":24,"name":"alarmMask"},"disableLocalConfig":{"ID":20,"type":24,"name":"disableLocalConfig"}},"name":"genBasic","commands":{"resetFactDefault":{"ID":0,"parameters":[],"name":"resetFactDefault"}},"commandsResponse":{}}},"networkAddress":6263,"endpoint":1,"linkquality":44,"groupID":0}' +1s
zigbee2mqtt:debug 2019-11-27T09:55:22: Received Zigbee message from '0x000b57fffe59d134', type 'readResponse', cluster 'genBasic', data '{"hwVersion":4,"dateCode":"19042019\u0000\u0000\u0000\u0000\u0000\u0000\u0000\u0000"}' from endpoint 1 with groupID 0
zigbee2mqtt:debug 2019-11-27T09:55:22: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"hwVersion":4,"dateCode":"19042019\u0000\u0000\u0000\u0000\u0000\u0000\u0000\u0000"}'
  zigbee-herdsman:controller:device Interview - got 'hwVersion,dateCode,swBuildId' for device '0x000b57fffe59d134' +1s
  zigbee-herdsman:controller:device Interview - completed for device '0x000b57fffe59d134' +5ms
  zigbee-herdsman:controller:log Succesfully interviewed '0x000b57fffe59d134' +19ms
zigbee2mqtt:info  2019-11-27T09:55:22: Successfully interviewed '0x000b57fffe59d134', device has successfully been paired
zigbee2mqtt:info  2019-11-27T09:55:22: Device '0x000b57fffe59d134' is supported, identified as: Danalock BT/ZB smartlock (V3-BTZB)
zigbee2mqtt:info  2019-11-27T09:55:22: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_successful","meta":{"friendly_name":"0x000b57fffe59d134","model":"V3-BTZB","vendor":"Danalock","description":"BT/ZB smartlock","supported":true}}'
zigbee2mqtt:info  2019-11-27T09:55:22: Configuring '0x000b57fffe59d134'
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false},"transactionSequenceNumber":5,"manufacturerCode":null,"commandIdentifier":7},"Payload":[{"status":0}],"Cluster":{"ID":257,"attributes":{"lockState":{"ID":0,"type":48,"name":"lockState"},"lockType":{"ID":38,"type":25,"name":"lockType"},"actuatorEnabled":{"ID":2,"type":16,"name":"actuatorEnabled"},"doorState":{"ID":3,"type":48,"name":"doorState"},"doorOpenEvents":{"ID":4,"type":35,"name":"doorOpenEvents"},"doorClosedEvents":{"ID":5,"type":35,"name":"doorClosedEvents"},"openPeriod":{"ID":6,"type":33,"name":"openPeriod"},"numOfLockRecordsSupported":{"ID":16,"type":33,"name":"numOfLockRecordsSupported"},"numOfTotalUsersSupported":{"ID":17,"type":33,"name":"numOfTotalUsersSupported"},"numOfPinUsersSupported":{"ID":18,"type":33,"name":"numOfPinUsersSupported"},"numOfRfidUsersSupported":{"ID":19,"type":33,"name":"numOfRfidUsersSupported"},"numOfWeekDaySchedulesSupportedPerUser":{"ID":20,"type":32,"name":"numOfWeekDaySchedulesSupportedPerUser"},"numOfYearDaySchedulesSupportedPerUser":{"ID":21,"type":32,"name":"numOfYearDaySchedulesSupportedPerUser"},"numOfHolidayScheduledsSupported":{"ID":22,"type":32,"name":"numOfHolidayScheduledsSupported"},"maxPinLen":{"ID":23,"type":32,"name":"maxPinLen"},"minPinLen":{"ID":24,"type":32,"name":"minPinLen"},"maxRfidLen":{"ID":25,"type":32,"name":"maxRfidLen"},"minRfidLen":{"ID":26,"type":32,"name":"minRfidLen"},"enableLogging":{"ID":32,"type":16,"name":"enableLogging"},"language":{"ID":33,"type":66,"name":"language"},"ledSettings":{"ID":34,"type":32,"name":"ledSettings"},"autoRelockTime":{"ID":35,"type":35,"name":"autoRelockTime"},"soundVolume":{"ID":36,"type":32,"name":"soundVolume"},"operatingMode":{"ID":37,"type":35,"name":"operatingMode"},"defaultConfigurationRegister":{"ID":39,"type":25,"name":"defaultConfigurationRegister"},"enableLocalProgramming":{"ID":40,"type":16,"name":"enableLocalProgramming"},"enableOneTouchLocking":{"ID":41,"type":16,"name":"enableOneTouchLocking"},"enableInsideStatusLed":{"ID":42,"type":16,"name":"enableInsideStatusLed"},"enablePrivacyModeButton":{"ID":43,"type":16,"name":"enablePrivacyModeButton"},"wrongCodeEntryLimit":{"ID":48,"type":32,"name":"wrongCodeEntryLimit"},"userCodeTemporaryDisableTime":{"ID":49,"type":32,"name":"userCodeTemporaryDisableTime"},"sendPinOta":{"ID":50,"type":16,"name":"sendPinOta"},"requirePinForRfOperation":{"ID":51,"type":16,"name":"requirePinForRfOperation"},"zigbeeSecurityLevel":{"ID":52,"type":32,"name":"zigbeeSecurityLevel"},"alarmMask":{"ID":64,"type":25,"name":"alarmMask"},"keypadOperationEventMask":{"ID":65,"type":25,"name":"keypadOperationEventMask"},"rfOperationEventMask":{"ID":66,"type":25,"name":"rfOperationEventMask"},"manualOperationEventMask":{"ID":67,"type":25,"name":"manualOperationEventMask"},"rfidOperationEventMask":{"ID":68,"type":25,"name":"rfidOperationEventMask"},"keypadProgrammingEventMask":{"ID":69,"type":25,"name":"keypadProgrammingEventMask"},"rfProgrammingEventMask":{"ID":70,"type":25,"name":"rfProgrammingEventMask"},"rfidProgrammingEventMask":{"ID":71,"type":25,"name":"rfidProgrammingEventMask"}},"name":"closuresDoorLock","commands":{"lockDoor":{"ID":0,"parameters":[{"name":"pincodevalue","type":66}],"name":"lockDoor"},"unlockDoor":{"ID":1,"parameters":[{"name":"pincodevalue","type":66}],"name":"unlockDoor"},"toggleDoor":{"ID":2,"parameters":[{"name":"pincodevalue","type":66}],"name":"toggleDoor"},"unlockWithTimeout":{"ID":3,"parameters":[{"name":"timeout","type":33},{"name":"pincodevalue","type":66}],"name":"unlockWithTimeout"},"getLogRecord":{"ID":4,"parameters":[{"name":"logindex","type":33}],"name":"getLogRecord"},"setPinCode":{"ID":5,"parameters":[{"name":"userid","type":33},{"name":"userstatus","type":32},{"name":"usertype","type":32},{"name":"pincodevalue","type":66}],"name":"setPinCode"},"getPinCode":{"ID":6,"parameters":[{"name":"userid","type":33}],"name":"getPinCode"},"clearPinCode":{"ID":7,"parameters":[{"name":"userid","type":33}],"name":"clearPinCode"},"clearAllPinCodes":{"ID":8,"parameters":[],"name":"clearAllPinCodes"},"setUserStatus":{"ID":9,"parameters":[{"name":"userid","type":33},{"name":"userstatus","type":32}],"name":"setUserStatus"},"getUserStatus":{"ID":10,"parameters":[{"name":"userid","type":33}],"name":"getUserStatus"},"setWeekDaySchedule":{"ID":11,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33},{"name":"daysmask","type":32},{"name":"starthour","type":32},{"name":"startminute","type":32},{"name":"endhour","type":32},{"name":"endminute","type":32}],"name":"setWeekDaySchedule"},"getWeekDaySchedule":{"ID":12,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33}],"name":"getWeekDaySchedule"},"clearWeekDaySchedule":{"ID":13,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33}],"name":"clearWeekDaySchedule"},"setYearDaySchedule":{"ID":14,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33},{"name":"zigbeelocalstarttime","type":35},{"name":"zigbeelocalendtime","type":35}],"name":"setYearDaySchedule"},"getYearDaySchedule":{"ID":15,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33}],"name":"getYearDaySchedule"},"clearYearDaySchedule":{"ID":16,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33}],"name":"clearYearDaySchedule"},"setHolidaySchedule":{"ID":17,"parameters":[{"name":"holidayscheduleid","type":32},{"name":"zigbeelocalstarttime","type":35},{"name":"zigbeelocalendtime","type":35},{"name":"opermodelduringholiday","type":32}],"name":"setHolidaySchedule"},"getHolidaySchedule":{"ID":18,"parameters":[{"name":"holidayscheduleid","type":32}],"name":"getHolidaySchedule"},"clearHolidaySchedule":{"ID":19,"parameters":[{"name":"holidayscheduleid","type":32}],"name":"clearHolidaySchedule"},"setUserType":{"ID":20,"parameters":[{"name":"userid","type":33},{"name":"usertype","type":32}],"name":"setUserType"},"getUserType":{"ID":21,"parameters":[{"name":"userid","type":33}],"name":"getUserType"},"setRfidCode":{"ID":22,"parameters":[{"name":"userid","type":33},{"name":"userstatus","type":32},{"name":"usertype","type":32},{"name":"pincodevalue","type":66}],"name":"setRfidCode"},"getRfidCode":{"ID":23,"parameters":[{"name":"userid","type":33}],"name":"getRfidCode"},"clearRfidCode":{"ID":24,"parameters":[{"name":"userid","type":33}],"name":"clearRfidCode"},"clearAllRfidCodes":{"ID":25,"parameters":[],"name":"clearAllRfidCodes"}},"commandsResponse":{"lockDoorRsp":{"ID":0,"parameters":[{"name":"status","type":32}],"name":"lockDoorRsp"},"unlockDoorRsp":{"ID":1,"parameters":[{"name":"status","type":32}],"name":"unlockDoorRsp"},"toggleDoorRsp":{"ID":2,"parameters":[{"name":"status","type":32}],"name":"toggleDoorRsp"},"unlockWithTimeoutRsp":{"ID":3,"parameters":[{"name":"status","type":32}],"name":"unlockWithTimeoutRsp"},"getLogRecordRsp":{"ID":4,"parameters":[{"name":"logentryid","type":33},{"name":"timestamp","type":35},{"name":"eventtype","type":32},{"name":"source","type":32},{"name":"eventidalarmcode","type":32},{"name":"userid","type":33},{"name":"pincodevalue","type":66}],"name":"getLogRecordRsp"},"setPinCodeRsp":{"ID":5,"parameters":[{"name":"status","type":32}],"name":"setPinCodeRsp"},"getPinCodeRsp":{"ID":6,"parameters":[{"name":"userid","type":33},{"name":"userstatus","type":32},{"name":"usertype","type":32},{"name":"pincodevalue","type":66}],"name":"getPinCodeRsp"},"clearPinCodeRsp":{"ID":7,"parameters":[{"name":"status","type":32}],"name":"clearPinCodeRsp"},"clearAllPinCodesRsp":{"ID":8,"parameters":[{"name":"status","type":32}],"name":"clearAllPinCodesRsp"},"setUserStatusRsp":{"ID":9,"parameters":[{"name":"status","type":32}],"name":"setUserStatusRsp"},"getUserStatusRsp":{"ID":10,"parameters":[{"name":"userid","type":33},{"name":"userstatus","type":32}],"name":"getUserStatusRsp"},"setWeekDayScheduleRsp":{"ID":11,"parameters":[{"name":"status","type":32}],"name":"setWeekDayScheduleRsp"},"getWeekDayScheduleRsp":{"ID":12,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33},{"name":"status","type":32},{"name":"daysmask","type":32},{"name":"starthour","type":32},{"name":"startminute","type":32},{"name":"endhour","type":32},{"name":"endminute","type":32}],"name":"getWeekDayScheduleRsp"},"clearWeekDayScheduleRsp":{"ID":13,"parameters":[{"name":"status","type":32}],"name":"clearWeekDayScheduleRsp"},"setYearDayScheduleRsp":{"ID":14,"parameters":[{"name":"status","type":32}],"name":"setYearDayScheduleRsp"},"getYearDayScheduleRsp":{"ID":15,"parameters":[{"name":"scheduleid","type":32},{"name":"userid","type":33},{"name":"status","type":32},{"name":"zigbeelocalstarttime","type":35},{"name":"zigbeelocalendtime","type":35}],"name":"getYearDayScheduleRsp"},"clearYearDayScheduleRsp":{"ID":16,"parameters":[{"name":"status","type":32}],"name":"clearYearDayScheduleRsp"},"setHolidayScheduleRsp":{"ID":17,"parameters":[{"name":"status","type":32}],"name":"setHolidayScheduleRsp"},"getHolidayScheduleRsp":{"ID":18,"parameters":[{"name":"holidayscheduleid","type":32},{"name":"status","type":32},{"name":"zigbeelocalstarttime","type":35},{"name":"zigbeelocalendtime","type":35},{"name":"opermodelduringholiday","type":32}],"name":"getHolidayScheduleRsp"},"clearHolidayScheduleRsp":{"ID":19,"parameters":[{"name":"status","type":32}],"name":"clearHolidayScheduleRsp"},"setUserTypeRsp":{"ID":20,"parameters":[{"name":"status","type":32}],"name":"setUserTypeRsp"},"getUserTypeRsp":{"ID":21,"parameters":[{"name":"userid","type":33},{"name":"usertype","type":32}],"name":"getUserTypeRsp"},"setRfidCodeRsp":{"ID":22,"parameters":[{"name":"status","type":32}],"name":"setRfidCodeRsp"},"getRfidCodeRsp":{"ID":23,"parameters":[{"name":"userid","type":33},{"name":"userstatus","type":32},{"name":"usertype","type":32},{"name":"pincodevalue","type":66}],"name":"getRfidCodeRsp"},"clearRfidCodeRsp":{"ID":24,"parameters":[{"name":"status","type":32}],"name":"clearRfidCodeRsp"},"clearAllRfidCodesRsp":{"ID":25,"parameters":[{"name":"status","type":32}],"name":"clearAllRfidCodesRsp"},"operationEventNotification":{"ID":32,"parameters":[{"name":"opereventsrc","type":32},{"name":"opereventcode","type":32},{"name":"userid","type":33},{"name":"pin","type":32},{"name":"zigbeelocaltime","type":35},{"name":"data","type":32}],"name":"operationEventNotification"},"programmingEventNotification":{"ID":33,"parameters":[{"name":"programeventsrc","type":32},{"name":"programeventcode","type":32},{"name":"userid","type":33},{"name":"pin","type":32},{"name":"usertype","type":32},{"name":"userstatus","type":32},{"name":"zigbeelocaltime","type":35},{"name":"data","type":32}],"name":"programmingEventNotification"}}}},"networkAddress":6263,"endpoint":1,"linkquality":44,"groupID":0}' +985ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false},"transactionSequenceNumber":6,"manufacturerCode":null,"commandIdentifier":7},"Payload":[{"status":0}],"Cluster":{"ID":1,"attributes":{"mainsVoltage":{"ID":0,"type":33,"name":"mainsVoltage"},"mainsFrequency":{"ID":1,"type":32,"name":"mainsFrequency"},"mainsAlarmMask":{"ID":16,"type":24,"name":"mainsAlarmMask"},"mainsVoltMinThres":{"ID":17,"type":33,"name":"mainsVoltMinThres"},"mainsVoltMaxThres":{"ID":18,"type":33,"name":"mainsVoltMaxThres"},"mainsVoltageDwellTripPoint":{"ID":19,"type":33,"name":"mainsVoltageDwellTripPoint"},"batteryVoltage":{"ID":32,"type":32,"name":"batteryVoltage"},"batteryPercentageRemaining":{"ID":33,"type":32,"name":"batteryPercentageRemaining"},"batteryManufacturer":{"ID":48,"type":66,"name":"batteryManufacturer"},"batterySize":{"ID":49,"type":48,"name":"batterySize"},"batteryAHrRating":{"ID":50,"type":33,"name":"batteryAHrRating"},"batteryQuantity":{"ID":51,"type":32,"name":"batteryQuantity"},"batteryRatedVoltage":{"ID":52,"type":32,"name":"batteryRatedVoltage"},"batteryAlarmMask":{"ID":53,"type":24,"name":"batteryAlarmMask"},"batteryVoltMinThres":{"ID":54,"type":32,"name":"batteryVoltMinThres"},"batteryVoltThres1":{"ID":55,"type":32,"name":"batteryVoltThres1"},"batteryVoltThres2":{"ID":56,"type":32,"name":"batteryVoltThres2"},"batteryVoltThres3":{"ID":57,"type":32,"name":"batteryVoltThres3"},"batteryPercentMinThres":{"ID":58,"type":32,"name":"batteryPercentMinThres"},"batteryPercentThres1":{"ID":59,"type":32,"name":"batteryPercentThres1"},"batteryPercentThres2":{"ID":60,"type":32,"name":"batteryPercentThres2"},"batteryPercentThres3":{"ID":61,"type":32,"name":"batteryPercentThres3"},"batteryAlarmState":{"ID":62,"type":27,"name":"batteryAlarmState"}},"name":"genPowerCfg","commands":{},"commandsResponse":{}}},"networkAddress":6263,"endpoint":1,"linkquality":44,"groupID":0}' +2s
zigbee2mqtt:info  2019-11-27T09:55:24: Succesfully configured '0x000b57fffe59d134'
nanosonde picture nanosonde on 27 Nov 2019

It is paired, but I do not get any messages when I lock/unlock the danalock manually as it used to be with in older versions. Could it be that the reporting feature is not working properly anymore?

I have set "reporting" to true.

nanosonde picture nanosonde on 27 Nov 2019

@nanosonde I think this is a different issue in zigbee2mqtt 1.7.1 which should have been fixed in the latest dev branch. The latest dev branch also contains the changes needed to successfully interview this device. So the changes to devices.js I provided are not needed anymore. For now:

  • Update to latest dev
  • Remove the device from the network. (https://www.zigbee2mqtt.io/information/mqtt_topics_and_message_structure.html#zigbee2mqttbridgeconfigremove)
  • Pair the device
Koenkk picture Koenkk on 27 Nov 2019

@Koenkk I did the steps in the order you described.

Nov 27 20:30:25 rpi3-knx systemd[1]: Started zigbee2mqtt.
Nov 27 20:30:26 rpi3-knx npm[12000]: > [email protected] start /opt/zigbee2mqtt-1.7.1
Nov 27 20:30:26 rpi3-knx npm[12000]: > node index.js
Nov 27 20:30:28 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:28: Logging to directory: '/opt/zigbee2mqtt-1.7.1/data/log/2019-11-27.20-30-28'
Nov 27 20:30:28 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:30:28: Loaded state from file /opt/zigbee2mqtt-1.7.1/data/state.json
Nov 27 20:30:28 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:28: Starting zigbee2mqtt version 1.7.1+dev (commit #c2be957)
Nov 27 20:30:28 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:28: Starting zigbee-herdsman...
Nov 27 20:30:28 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:30:28: Using zigbee-herdsman with settings: '{"network":{"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11],"networkKey":"HIDDEN"},"databasePath":"/opt/zigbee2mqtt-1.7.1/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt-1.7.1/data/database.db.backup","backupPath":"/opt/zigbee2mqtt-1.7.1/data/coordinator_backup.json","serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/ttyACM0"}}'
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: zigbee-herdsman started
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: Coordinator firmware version: '{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}}'
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:30:30: Zigbee network parameters: {"panID":6754,"extendedPanID":"0xdddddddddddddddd","channel":11}
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: Currently 0 devices are joined:
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:warn  2019-11-27 20:30:30: `permit_join` set to  `true` in configuration.yaml.
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:warn  2019-11-27 20:30:30: Allowing new devices to join.
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:warn  2019-11-27 20:30:30: Set `permit_join` to `false` once you joined all devices.
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: Zigbee: allowing new devices to join.
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: Connecting to MQTT server at mqtt://192.168.10.2
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: Connected to MQTT server
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload 'online'
Nov 27 20:30:30 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:30:30: MQTT publish: topic 'zigbee2mqtt/bridge/config', payload '{"version":"1.7.1+dev","commit":"c2be957","coordinator":{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}},"log_level":"debug","permit_join":true}'
Nov 27 20:31:00 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:00: Device 'danalock' joined
Nov 27 20:31:00 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:00: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"danalock"}}'
Nov 27 20:31:00 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:00: Starting interview of 'danalock'
Nov 27 20:31:00 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:00: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"danalock"}}'
Nov 27 20:31:13 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:31:13: Device 'danalock' announced itself
Nov 27 20:31:23 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:31:23: Received Zigbee message from 'danalock', type 'readResponse', cluster 'genBasic', data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' from endpoint 1 with groupID 0
Nov 27 20:31:23 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:31:23: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}'
Nov 27 20:31:24 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:31:24: Received Zigbee message from 'danalock', type 'readResponse', cluster 'genBasic', data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' from endpoint 1 with groupID 0
Nov 27 20:31:24 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:31:24: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"zclVersion":2,"appVersion":1,"stackVersion":1}'
Nov 27 20:31:25 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:31:25: Received Zigbee message from 'danalock', type 'readResponse', cluster 'genBasic', data '{"hwVersion":4,"dateCode":"19042019\u0000\u0000\u0000\u0000\u0000\u0000\u0000\u0000"}' from endpoint 1 with groupID 0
Nov 27 20:31:25 rpi3-knx npm[12000]: zigbee2mqtt:debug 2019-11-27 20:31:25: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"hwVersion":4,"dateCode":"19042019\u0000\u0000\u0000\u0000\u0000\u0000\u0000\u0000"}'
Nov 27 20:31:25 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:25: Successfully interviewed 'danalock', device has successfully been paired
Nov 27 20:31:25 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:25: Device 'danalock' is supported, identified as: Danalock BT/ZB smartlock (V3-BTZB)
Nov 27 20:31:25 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:25: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_successful","meta":{"friendly_name":"danalock","model":"V3-BTZB","vendor":"Danalock","description":"BT/ZB smartlock","supported":true}}'
Nov 27 20:31:25 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:25: Configuring 'danalock'
Nov 27 20:31:27 rpi3-knx npm[12000]: zigbee2mqtt:info  2019-11-27 20:31:27: Succesfully configured 'danalock'

It paired successfully, but I do not receive any LOCK/UNLOCK status updates.

nanosonde picture nanosonde on 27 Nov 2019

Strange, could you sniff the traffic while turning on/off the lock? (https://www.zigbee2mqtt.io/how_tos/how_to_sniff_zigbee_traffic.html)

Koenkk picture Koenkk on 27 Nov 2019

danalock_pairing_configure_reporting_lock_unlock.zip

@Koenkk
From the traffic I can see that configure reporting was successful.
But when locking/unlocking the lock, it seems that it wants to send data ("data request"), but nobody cares about it.

The capture file also contains the complete pairing.

nanosonde picture nanosonde on 27 Nov 2019

Thanks, I cannot find the problem yet, could you provide the same log when pairing it with zigbee2mqtt 1.6 (also lock/unlock the lock after this). In this way we can compare.

Koenkk picture Koenkk on 28 Nov 2019

I do have a similar problems with the lock. I was able to pair the device on second try.
(Starting zigbee2mqtt version 1.7.1+dev (commit #f767b41955335d9ed56f3f0310059b25f7d80b26))

1st try:
debug 2019-11-27 23:00:05: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' from endpoint 1 with groupID 0 debug 2019-11-27sa 23:00:05: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' info 2019-11-27 23:00:05: MQTT publish: topic 'homeassistant/lock/0x000b57fffe59d827/lock/config', payload '{"command_topic":"zigbee2mqtt/0x000b57fffe59d827/set","value_template":"{{ value_json.state }}","state_topic":"zigbee2mqtt/0x000b57fffe59d827","json_attributes_topic":"zigbee2mqtt/0x000b57fffe59d827","name":"0x000b57fffe59d827_lock","unique_id":"0x000b57fffe59d827_lock_zigbee2mqtt","device":{"identifiers":["zigbee2mqtt_0x000b57fffe59d827"],"name":"0x000b57fffe59d827","sw_version":"Zigbee2mqtt 1.7.1+dev","model":"BT/ZB smartlock (V3-BTZB)","manufacturer":"Danalock"},"availability_topic":"zigbee2mqtt/bridge/state"}' info 2019-11-27 23:00:05: MQTT publish: topic 'homeassistant/sensor/0x000b57fffe59d827/linkquality/config', payload '{"unit_of_measurement":"-","value_template":"{{ value_json.linkquality }}","state_topic":"zigbee2mqtt/0x000b57fffe59d827","json_attributes_topic":"zigbee2mqtt/0x000b57fffe59d827","name":"0x000b57fffe59d827_linkquality","unique_id":"0x000b57fffe59d827_linkquality_zigbee2mqtt","device":{"identifiers":["zigbee2mqtt_0x000b57fffe59d827"],"name":"0x000b57fffe59d827","sw_version":"Zigbee2mqtt 1.7.1+dev","model":"BT/ZB smartlock (V3-BTZB)","manufacturer":"Danalock"},"availability_topic":"zigbee2mqtt/bridge/state"}' debug 2019-11-27 23:00:05: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' from endpoint 1 with groupID 0 debug 2019-11-27 23:00:05: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' debug 2019-11-27 23:00:06: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' from endpoint 1 with groupID 0 debug 2019-11-27 23:00:06: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' error 2019-11-27 23:00:06: Failed to interview '0x000b57fffe59d827', device has not successfully been paired info 2019-11-27 23:00:06: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_failed","meta":{"friendly_name":"0x000b57fffe59d827"}}' info 2019-11-27 23:00:06: Configuring '0x000b57fffe59d827' error 2019-11-27 23:00:07: Failed to configure '0x000b57fffe59d827', attempt 1 (Error: Data request failed with error: 'MAC transaction expired' (240) at ZStackAdapter.<anonymous> (/app/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:571:27) at Generator.next (<anonymous>) at fulfilled (/app/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:5:58))

2nd try:
info 2019-11-27 23:26:27: Device '0x000b57fffe59d827' joined info 2019-11-27 23:26:27: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x000b57fffe59d827"}}' info 2019-11-27 23:26:27: Starting interview of '0x000b57fffe59d827' info 2019-11-27 23:26:27: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x000b57fffe59d827"}}' debug 2019-11-27 23:26:40: Device '0x000b57fffe59d827' announced itself debug 2019-11-27 23:26:49: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' from endpoint 1 with groupID 0 debug 2019-11-27 23:26:49: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"modelId":"V3-BTZB","manufacturerName":"Danalock","powerSource":3}' debug 2019-11-27 23:26:51: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' from endpoint 1 with groupID 0 debug 2019-11-27 23:26:51: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' debug 2019-11-27 23:26:51: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' from endpoint 1 with groupID 0 debug 2019-11-27 23:26:51: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' debug 2019-11-27 23:26:52: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' from endpoint 1 with groupID 0 debug 2019-11-27 23:26:52: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' debug 2019-11-27 23:26:52: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' from endpoint 1 with groupID 0 debug 2019-11-27 23:26:52: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' debug 2019-11-27 23:26:54: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"hwVersion":4,"dateCode":"03092018\u0000\u0000\u0000\u0000\u0000\u0000\u0000\u0000"}' from endpoint 1 with groupID 0 debug 2019-11-27 23:26:54: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"hwVersion":4,"dateCode":"03092018\u0000\u0000\u0000\u0000\u0000\u0000\u0000\u0000"}' info 2019-11-27 23:26:54: Successfully interviewed '0x000b57fffe59d827', device has successfully been paired info 2019-11-27 23:26:54: Device '0x000b57fffe59d827' is supported, identified as: Danalock BT/ZB smartlock (V3-BTZB) info 2019-11-27 23:26:54: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_successful","meta":{"friendly_name":"0x000b57fffe59d827","model":"V3-BTZB","vendor":"Danalock","description":"BT/ZB smartlock","supported":true}}' info 2019-11-27 23:26:54: Configuring '0x000b57fffe59d827' debug 2019-11-27 23:26:54: Received Zigbee message from '0x000b57fffe59d827', type 'readResponse', cluster 'genBasic', data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' from endpoint 1 with groupID 0 debug 2019-11-27 23:26:54: No converter available for 'V3-BTZB' with cluster 'genBasic' and type 'readResponse' and data '{"zclVersion":2,"appVersion":1,"stackVersion":1}' info 2019-11-27 23:26:57: Succesfully configured '0x000b57fffe59d827'
Then, if I tried to lock/unlock in HA I get following log (I think I was able to unlock it also few times but there was some random behaviour yesterday evening):
debug 2019-11-28 22:57:36: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'LOCK' debug 2019-11-28 22:57:36: Publishing 'set' 'state' to '0x000b57fffe59d827' debug 2019-11-28 22:57:40: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK' debug 2019-11-28 22:57:40: Publishing 'set' 'state' to '0x000b57fffe59d827' error 2019-11-28 22:57:42: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Data request failed with error: 'MAC transaction expired' (240)' info 2019-11-28 22:57:42: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Data request failed with error: 'MAC transaction expired' (240)'","meta":{"friendly_name":"0x000b57fffe59d827"}}' debug 2019-11-28 22:57:51: Saving state to file /share/zigbee2mqtt/state.json error 2019-11-28 22:57:57: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 43 - 257 - 11 after 15000ms' info 2019-11-28 22:57:57: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 43 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}'

At this point (today) it didn't work. So I restart both, the lock (removed batteries) and HA (reboot)
After this the lock is responding to LOCK and UNLOCK commands from HA but there are some errors. Also the lock state in HA is Unlocked all the time in HA.
zigbee2mqtt:debug 2019-11-28 23:15:19: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'LOCK' zigbee2mqtt:debug 2019-11-28 23:15:19: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:error 2019-11-28 23:15:34: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 44 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:15:34: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 44 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:debug 2019-11-28 23:16:03: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK' zigbee2mqtt:debug 2019-11-28 23:16:03: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:error 2019-11-28 23:16:18: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 45 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:16:18: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 45 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:debug 2019-11-28 23:17:51: Saving state to file /share/zigbee2mqtt/state.json zigbee2mqtt:debug 2019-11-28 23:18:55: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK' zigbee2mqtt:debug 2019-11-28 23:18:55: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:error 2019-11-28 23:19:10: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 46 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:19:10: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 46 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:debug 2019-11-28 23:22:51: Saving state to file /share/zigbee2mqtt/state.json zigbee2mqtt:debug 2019-11-28 23:27:51: Saving state to file /share/zigbee2mqtt/state.json zigbee2mqtt:debug 2019-11-28 23:32:51: Saving state to file /share/zigbee2mqtt/state.json zigbee2mqtt:debug 2019-11-28 23:35:29: Received Zigbee message from '0x00158d00041d0f0f', type 'attributeReport', cluster 'genBasic', data '{"65281":{"1":3025,"3":26,"4":5032,"5":23,"6":[1,33554436],"10":0,"100":0}}' from endpoint 1 with groupID 0 zigbee2mqtt:info 2019-11-28 23:35:29: MQTT publish: topic 'zigbee2mqtt/0x00158d00041d0f0f', payload '{"battery":100,"voltage":3025,"contact":true,"linkquality":78}' zigbee2mqtt:debug 2019-11-28 23:37:51: Saving state to file /share/zigbee2mqtt/state.json zigbee2mqtt:debug 2019-11-28 23:39:53: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'LOCK' zigbee2mqtt:debug 2019-11-28 23:39:53: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:error 2019-11-28 23:40:08: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 47 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:40:08: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 47 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:debug 2019-11-28 23:40:18: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'LOCK' zigbee2mqtt:debug 2019-11-28 23:40:18: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:debug 2019-11-28 23:40:33: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK' zigbee2mqtt:debug 2019-11-28 23:40:33: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:error 2019-11-28 23:40:33: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 48 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:40:33: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 48 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:error 2019-11-28 23:40:48: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 49 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:40:48: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 49 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:debug 2019-11-28 23:40:56: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'LOCK' zigbee2mqtt:debug 2019-11-28 23:40:56: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:debug 2019-11-28 23:41:07: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK' zigbee2mqtt:debug 2019-11-28 23:41:07: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:error 2019-11-28 23:41:11: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 50 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:41:11: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 50 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:error 2019-11-28 23:41:26: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 51 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:41:26: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 51 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:debug 2019-11-28 23:41:27: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'LOCK' zigbee2mqtt:debug 2019-11-28 23:41:27: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:debug 2019-11-28 23:41:34: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK' zigbee2mqtt:debug 2019-11-28 23:41:34: Publishing 'set' 'state' to '0x000b57fffe59d827' zigbee2mqtt:error 2019-11-28 23:41:42: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 52 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:41:42: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 52 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:error 2019-11-28 23:41:57: Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 53 - 257 - 11 after 15000ms' zigbee2mqtt:info 2019-11-28 23:41:57: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"zigbee_publish_error","message":"Publish 'set' 'state' to '0x000b57fffe59d827' failed: 'Error: Timeout - 18250 - 1 - 53 - 257 - 11 after 15000ms'","meta":{"friendly_name":"0x000b57fffe59d827"}}' zigbee2mqtt:debug 2019-11-28 23:42:51: Saving state to file /share/zigbee2mqtt/state.json

image

jysaloma picture jysaloma on 28 Nov 2019

@Koenkk I did not find the time so far for capturing the Zigbee traffic.

However, I found this document.

On page 3 it says: "Reporting The lock supports reporting of attributes when the recipient is in the binding table."

Do I explicitly bind it to the coordinator?

nanosonde picture nanosonde on 2 Dec 2019

@Koenkk that above issue that I had with timeouts seems to be fixed by changing the similar line than you suggested here for Danalock.

After the change I could see the messages fine in the log but the lock didn't turn so I had to also remove the batteries from the lock to make it functioning.

here is the latest log for working lock:

debug 2019-12-03 23:59:41: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK'
debug 2019-12-03 23:59:41: Publishing 'set' 'state' to '0x000b57fffe59d827'
info 2019-12-03 23:59:45: MQTT publish: topic 'zigbee2mqtt/0x000b57fffe59d827', payload '{"state":"UNLOCK"}'
debug 2019-12-03 23:59:47: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'LOCK'
debug 2019-12-03 23:59:47: Publishing 'set' 'state' to '0x000b57fffe59d827'
info 2019-12-03 23:59:49: MQTT publish: topic 'zigbee2mqtt/0x000b57fffe59d827', payload '{"state":"LOCK"}'
debug 2019-12-04 00:00:00: Received MQTT message on 'zigbee2mqtt/bridge/config/devices/get' with data ''
info 2019-12-04 00:00:00: MQTT publish: topic 'zigbee2mqtt/bridge/config/devices', payload '[{"ieeeAddr":"0x00124b0014b885a6","type":"Coordinator","networkAddress":0,"friendly_name":"Coordinator","softwareBuildID":"zStack12","dateCode":"20190608","lastSeen":1575410400305},{"ieeeAddr":"0x00158d00041d0f0f","type":"EndDevice","networkAddress":63512,"model":"MCCGQ11LM","friendly_name":"0x00158d00041d0f0f","manufacturerID":4151,"manufacturerName":"LUMI","powerSource":"Battery","modelID":"lumi.sensor_magnet.aq2","hardwareVersion":2,"softwareBuildID":"3000-0001","dateCode":"20161128","lastSeen":null},{"ieeeAddr":"0x00158d00042340a1","type":"EndDevice","networkAddress":46188,"model":"MCCGQ11LM","friendly_name":"0x00158d00042340a1","manufacturerID":4151,"manufacturerName":"LUMI","powerSource":"Battery","modelID":"lumi.sensor_magnet.aq2","hardwareVersion":2,"softwareBuildID":"3000-0001","dateCode":"20161128","lastSeen":null},{"ieeeAddr":"0x000b57fffe59d827","type":"EndDevice","networkAddress":18250,"model":"V3-BTZB","friendly_name":"0x000b57fffe59d827","manufacturerID":4444,"manufacturerName":"Danalock","powerSource":"Battery","modelID":"V3-BTZB","hardwareVersion":4,"dateCode":"03092018u0000u0000u0000u0000u0000u0000u0000u0000","lastSeen":1575410389098}]'
debug 2019-12-04 00:00:00: Received MQTT message on 'zigbee2mqtt/bridge/config/devices' with data '[{"ieeeAddr":"0x00124b0014b885a6","type":"Coordinator","networkAddress":0,"friendly_name":"Coordinator","softwareBuildID":"zStack12","dateCode":"20190608","lastSeen":1575410400305},{"ieeeAddr":"0x00158d00041d0f0f","type":"EndDevice","networkAddress":63512,"model":"MCCGQ11LM","friendly_name":"0x00158d00041d0f0f","manufacturerID":4151,"manufacturerName":"LUMI","powerSource":"Battery","modelID":"lumi.sensor_magnet.aq2","hardwareVersion":2,"softwareBuildID":"3000-0001","dateCode":"20161128","lastSeen":null},{"ieeeAddr":"0x00158d00042340a1","type":"EndDevice","networkAddress":46188,"model":"MCCGQ11LM","friendly_name":"0x00158d00042340a1","manufacturerID":4151,"manufacturerName":"LUMI","powerSource":"Battery","modelID":"lumi.sensor_magnet.aq2","hardwareVersion":2,"softwareBuildID":"3000-0001","dateCode":"20161128","lastSeen":null},{"ieeeAddr":"0x000b57fffe59d827","type":"EndDevice","networkAddress":18250,"model":"V3-BTZB","friendly_name":"0x000b57fffe59d827","manufacturerID":4444,"manufacturerName":"Danalock","powerSource":"Battery","modelID":"V3-BTZB","hardwareVersion":4,"dateCode":"03092018u0000u0000u0000u0000u0000u0000u0000u0000","lastSeen":1575410389098}]'
info 2019-12-04 00:00:00: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"devices","message":[{"ieeeAddr":"0x00124b0014b885a6","type":"Coordinator","networkAddress":0,"friendly_name":"Coordinator","softwareBuildID":"zStack12","dateCode":"20190608","lastSeen":1575410400328},{"ieeeAddr":"0x00158d00041d0f0f","type":"EndDevice","networkAddress":63512,"model":"MCCGQ11LM","friendly_name":"0x00158d00041d0f0f","manufacturerID":4151,"manufacturerName":"LUMI","powerSource":"Battery","modelID":"lumi.sensor_magnet.aq2","hardwareVersion":2,"softwareBuildID":"3000-0001","dateCode":"20161128","lastSeen":null},{"ieeeAddr":"0x00158d00042340a1","type":"EndDevice","networkAddress":46188,"model":"MCCGQ11LM","friendly_name":"0x00158d00042340a1","manufacturerID":4151,"manufacturerName":"LUMI","powerSource":"Battery","modelID":"lumi.sensor_magnet.aq2","hardwareVersion":2,"softwareBuildID":"3000-0001","dateCode":"20161128","lastSeen":null},{"ieeeAddr":"0x000b57fffe59d827","type":"EndDevice","networkAddress":18250,"model":"V3-BTZB","friendly_name":"0x000b57fffe59d827","manufacturerID":4444,"manufacturerName":"Danalock","powerSource":"Battery","modelID":"V3-BTZB","hardwareVersion":4,"dateCode":"03092018u0000u0000u0000u0000u0000u0000u0000u0000","lastSeen":1575410389098}]}'
debug 2019-12-04 00:00:00: Received MQTT message on 'zigbee2mqtt/bridge/networkmap' with data 'raw'
info 2019-12-04 00:00:00: Starting network scan (includeRoutes 'false')
debug 2019-12-04 00:00:00: LQI succeeded for 'Coordinator'
info 2019-12-04 00:00:00: Network scan finished
info 2019-12-04 00:00:00: MQTT publish: topic 'zigbee2mqtt/bridge/networkmap/raw', payload '{"nodes":[{"ieeeAddr":"0x00124b0014b885a6","friendlyName":"Coordinator","type":"Coordinator","networkAddress":0,"failed":[],"lastSeen":null},{"ieeeAddr":"0x00158d00041d0f0f","friendlyName":"0x00158d00041d0f0f","type":"EndDevice","networkAddress":63512,"manufacturerName":"LUMI","modelID":"lumi.sensor_magnet.aq2","lastSeen":null},{"ieeeAddr":"0x00158d00042340a1","friendlyName":"0x00158d00042340a1","type":"EndDevice","networkAddress":46188,"manufacturerName":"LUMI","modelID":"lumi.sensor_magnet.aq2","lastSeen":null},{"ieeeAddr":"0x000b57fffe59d827","friendlyName":"0x000b57fffe59d827","type":"EndDevice","networkAddress":18250,"manufacturerName":"Danalock","modelID":"V3-BTZB","lastSeen":1575410389098}],"links":[{"source":{"ieeeAddr":"0x00158d00041d0f0f","networkAddress":63512},"target":{"ieeeAddr":"0x00124b0014b885a6","networkAddress":0},"linkquality":89,"depth":1,"routes":[],"sourceIeeeAddr":"0x00158d00041d0f0f","targetIeeeAddr":"0x00124b0014b885a6","sourceNwkAddr":63512,"lqi":89,"relationship":1},{"source":{"ieeeAddr":"0x00158d00042340a1","networkAddress":46188},"target":{"ieeeAddr":"0x00124b0014b885a6","networkAddress":0},"linkquality":97,"depth":1,"routes":[],"sourceIeeeAddr":"0x00158d00042340a1","targetIeeeAddr":"0x00124b0014b885a6","sourceNwkAddr":46188,"lqi":97,"relationship":1},{"source":{"ieeeAddr":"0x000b57fffe59d827","networkAddress":18250},"target":{"ieeeAddr":"0x00124b0014b885a6","networkAddress":0},"linkquality":45,"depth":1,"routes":[],"sourceIeeeAddr":"0x000b57fffe59d827","targetIeeeAddr":"0x00124b0014b885a6","sourceNwkAddr":18250,"lqi":45,"relationship":1}]}'
debug 2019-12-04 00:00:33: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK'
debug 2019-12-04 00:00:33: Publishing 'set' 'state' to '0x000b57fffe59d827'
info 2019-12-04 00:00:39: MQTT publish: topic 'zigbee2mqtt/0x000b57fffe59d827', payload '{"state":"UNLOCK"}'
debug 2019-12-04 00:02:23: Received MQTT message on 'zigbee2mqtt/0x000b57fffe59d827/set' with data 'UNLOCK'
debug 2019-12-04 00:02:23: Publishing 'set' 'state' to '0x000b57fffe59d827'
info 2019-12-04 00:02:25: MQTT publish: topic 'zigbee2mqtt/0x000b57fffe59d827', payload '{"state":"UNLOCK"}'

Is that line that I changed something that should be added to code too for Danalock?

jysaloma picture jysaloma on 3 Dec 2019

@nanosonde nice catch, it was indeed not bound, please try with:

{
    zigbeeModel: ['V3-BTZB'],
    model: 'V3-BTZB',
    vendor: 'Danalock',
    description: 'BT/ZB smartlock',
    supports: 'lock/unlock, battery',
    fromZigbee: [fz.lock, fz.lock_operation_event, fz.battery_200],
    toZigbee: [tz.generic_lock],
    meta: {options: {disableDefaultResponse: true}, configureKey: 5},
    configure: async (device, coordinatorEndpoint) => {
        const endpoint = device.getEndpoint(1);
        await bind(endpoint, coordinatorEndpoint, ['closuresDoorLock', 'genPowerCfg']);
        await configureReporting.lockState(endpoint);
        await configureReporting.batteryPercentageRemaining(endpoint);
    },
},

@jysaloma I've added it to the section above

Let me know if this definition work properly then I will update it.

Koenkk picture Koenkk on 4 Dec 2019

@nanosonde nice catch, it was indeed not bound, please try with:
Let me know if this definition work properly then I will update it.

@Koenkk

Yes! That was the reason!
It is working now as expected. :-)

nanosonde picture nanosonde on 5 Dec 2019

@nanosonde so with that config (https://github.com/Koenkk/zigbee2mqtt/issues/2339#issuecomment-561812531) the device fully works?

Koenkk picture Koenkk on 6 Dec 2019

@nanosonde so with that config (#2339 (comment)) the device fully works?

Yes, as far as I can tell.

nanosonde picture nanosonde on 6 Dec 2019

Thanks, will be available in next zigbee2mqtt release.

Koenkk picture Koenkk on 7 Dec 2019
Was this page helpful?
0 / 5 - 0 ratings

Related issues

RefineryX picture RefineryX  Â·  4Comments
Courty40 picture Courty40  Â·  4Comments
rm2kdev picture rm2kdev  Â·  3Comments
jwilling picture jwilling  Â·  4Comments
sylarevan picture sylarevan  Â·  5Comments