search

SphtKr / homebridge-zway

Homebridge plugin for (better) HomeKit support of RaZBerry and Z-Way Server
ISC License
85 stars 38 forks source link

Lock status unknown but works #103

Open Pltnorwich opened 7 years ago

Pltnorwich commented 7 years ago

Hi All,

Ive setup my Raspberry Pi B+ running zway server via UZB1 with a licence, then homebridge for my thermostat. The thermostat shows up no problems and updates but the lock shows in an unknown state. img_1099

As you can see the lock is trying to find its status (sending/ requesting info) I can tap the lock so icon changes too closed, tap it again to unlocked position - icon open and the door unlocks. In the zway server the lock status is shown correctly when opened and closed... img_5106 img_9699

The lock I'm using is a Danalock 125 BTZE. If i ask siri to unlock the door - she will do it straight away, just the zway homebridge plugin does not seem to send the lock status to the Home app correctly.

My json file seems ok but not only lists 4 items, not sure if something else needs to be added to help.

Homebridge with away server son file

{ "bridge": { "name": "Homebridge", "username": "XXXX", "port": 51XX, "pin": "XXXX" },

"description": "Thermostat",

"accessories": [{
    "accessory": "TADO",
    "name": "Tado",
    "homeID": "XXXX",
    "username": "XXXX",
    "password": "XXXX"
}],

"platforms": [{
    "platform": "ZWayServer",
    "url": "http://192.168.1.235:8083/",
    "login": "XXXXXX",
    "password": "XXXXX"

}]

} Hope you can help and ill try give you more info if needed.

Thanks

Phil

Fingerz commented 7 years ago

I'm having the exact same issue with this door lock. I'm hoping a solution can be found.

ToddGreenfield commented 7 years ago

Hey all- I am having the exact same issue. I emailed info@z-wave.me the below info but have not heard anything back yet. @Pltnorwich I am curious if you go to the zwave server page and do a refresh of the browser, does it show an unknown status? Neither the lock/unlock are highlighed? Mine does not show status one way or another when I do this, causing me to believe it is a problem with the zwave and not the plugin (but what do I know)... here is what I sent to z-wave-

I recently did a fresh re-install with my RPI/Razberry and now have a problem with my Schlage lock. I am currently running a Razberry z-way (ZW0500) with FW Ver 2.3 (UI 1.6.0), on an RPI3 running Jessie-lite (downloaded on 3/24/2017).

Problem device is Schlage Connect model BE469NX with FW Ver MAIN_7.1.

Detail of problem is Z-Wave Smart Home does not correctly maintain or display the lock state/status, neither the locked/unlocked buttons are selected. I also see security encapsulation error messages in log file (attached). Inclusion (and exclusion) worked fine and the interview was 100% completed. If I manually change lock state the log reflects the change. If I click lock or unlock in the zway UI the lock activates and the UI changes to the correct state, however when I refresh the webpage or close the browser and reopen neither button is selected. This also results in an unknown status being communicated to my home automation system (Homebridge using homebridge-zway running on the same RPI).

Quick history – As noted above, I just rebuilt my RPI this week. The same lock was working 100% when I installed a number of months ago with the exact same HW. I believe I was running razberry zway FW Ver 2.2, but prior to my rebuild I had upgraded the FW to 2.3, and I believe the lock still worked fine (although I had not excluded/included on the newer FW and don’t really recall testing the lock – I assumed it worked).

z-way-server.log attached captured while clicking unlock then lock in zway UI, and then with manual unlocking/locking.

All other light switches and zwave devices seem to be working properly.

Pltnorwich commented 7 years ago

Hi @ToddGreenfield , in my photos above from the zway server the lock says closed and the icon to the right is depressed to ( to locked 1) when I activate the unlock from the Home app on iPhone and then refresh the zway browser the lock says unlocked and the unlock icon depressed in the next photo.

To me it seem the link between zway and homebridge as status is always correct the zway end

ToddGreenfield commented 7 years ago

@Pltnorwich I just did some quick troubleshooting. I noticed you are in the "expert" screen and I was looking at the basic view. My basic view does not show any status as shown below. image When I view through expert mode, I do see correct status of Open/Closed as shown below. image

So, are you able to see a status in the basic view? Also, what does your logfile show when you operate the lock by zwave or homebridge? Mine shows this which is why I assumed it was a zwave thing--- but now that the expert mode shows correct I am not sure...

[2017-03-26 12:21:00.482] [D] [zway] SENT ACK [2017-03-26 12:21:00.482] [D] [zway] SETDATA devices.52.data.lastReceived = 0 (0x00000000) [2017-03-26 12:21:00.482] [I] [zway] Node 52:0 CC Security: Received a secure message [2017-03-26 12:21:00.482] [E] [zway] Packet CC::SECURITY_MESSAGE_ENCAPSULATION is too short: required at least 21 bytes, got 2 [2017-03-26 12:21:00.482] [E] [zway] Error returned from _zway_cc_call_handler(zway, command, controller->id, 0, data[4], &data[5]): Wrong packet from Z-Wave network or Discovery got bad data (stick communication failed) (-9) [2017-03-26 12:21:00.594] [D] [zway] RECEIVED: ( 01 08 00 04 10 34 02 98 81 CC ) [2017-03-26 12:21:00.594] [D] [zway] SENT ACK

Pltnorwich commented 7 years ago

@ToddGreenfield running zway in standard view shows the same as you - neither button selected or highlighted for a time whilst operating.

[2017-04-02 18:18:15.452] [I] [zway] Node 11:0 CC Security: Received a secure message [2017-04-02 18:18:15.452] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.firstPart = ** [2017-04-02 18:18:15.453] [I] [zway] Node 11:0 CC Security: passing decrypted packet to application level: [ 62 03 ff 11 05 fe fe ] [2017-04-02 18:18:15.453] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.mode = 255 (0x000000ff) [2017-04-02 18:18:15.454] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.outsideMode = 1 (0x00000001) [2017-04-02 18:18:15.454] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.insideMode = 1 (0x00000001) [2017-04-02 18:18:15.454] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.condition = 5 (0x00000005) [2017-04-02 18:18:15.454] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockMinutes = 254 (0x000000fe) [2017-04-02 18:18:15.454] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockSeconds = 254 (0x000000fe) [2017-04-02 18:18:35.735] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:49:44.998] [I] [core] --- ZWayVDev_zway_11-0-98 performCommand processing: {"0":"close","1":{}} [2017-04-02 18:49:45.001] [I] [zway] Adding job: Door Lock Operation Set [2017-04-02 18:49:45.003] [I] [zway] Adding job: Door Lock Operation Get [2017-04-02 18:49:45.007] [I] [zway] Node 11:0 CC Security: sending Nonce Get [2017-04-02 18:49:45.009] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:45.010] [I] [zway] Adding job: Nonce Get [2017-04-02 18:49:45.011] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216209 (0x006e1c51) [2017-04-02 18:49:45.023] [D] [zway] SENDING (cb 0x9d): ( 01 09 00 13 0B 02 98 40 05 9D AC ) [2017-04-02 18:49:45.026] [D] [zway] RECEIVED ACK [2017-04-02 18:49:45.031] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:45.035] [D] [zway] SENT ACK [2017-04-02 18:49:45.036] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:46.312] [D] [zway] RECEIVED: ( 01 07 00 13 9D 00 00 80 F6 ) [2017-04-02 18:49:46.313] [D] [zway] SENT ACK [2017-04-02 18:49:46.314] [I] [zway] Job 0x13 (Nonce Get): Delivered [2017-04-02 18:49:46.316] [D] [zway] SendData Response with callback 0x9d received: received by recipient [2017-04-02 18:49:46.317] [D] [zway] SETDATA devices.11.data.lastSend = 7216335 (0x006e1ccf) [2017-04-02 18:49:46.319] [D] [zway] Job 0x13 (Nonce Get): success [2017-04-02 18:49:46.320] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:46.321] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216335 (0x006e1ccf) [2017-04-02 18:49:46.322] [I] [zway] Removing job: Nonce Get [2017-04-02 18:49:46.331] [D] [zway] RECEIVED: ( 01 10 00 04 00 0B 0A 98 80 19 4F D9 CA 61 9B 9D 3A EA ) [2017-04-02 18:49:46.333] [D] [zway] SENT ACK [2017-04-02 18:49:46.334] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:46.335] [I] [zway] Node 11:0 CC Security: received Nonce Report [2017-04-02 18:49:46.336] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonce = ** [2017-04-02 18:49:46.338] [I] [zway] Node 11:0 CC Security: Sending a secure message [2017-04-02 18:49:46.339] [I] [zway] Node 11:0 CC Security: sending encrypted packet: Door Lock Operation Set [2017-04-02 18:49:46.340] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.toFollow = False [2017-04-02 18:49:46.341] [D] [zway] SETDATA devices.11.data.lastNonceGet = Empty [2017-04-02 18:49:46.342] [I] [zway] Adding job: Security, Door Lock Operation Set [2017-04-02 18:49:46.345] [I] [zway] Node 11:0 CC Security: sending Nonce Get [2017-04-02 18:49:46.346] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:46.347] [I] [zway] Adding job: Nonce Get [2017-04-02 18:49:46.348] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216337 (0x006e1cd1) [2017-04-02 18:49:46.380] [D] [zway] SENDING (cb 0x9e): ( 01 1E 00 13 0B 17 98 81 E8 16 C7 CA BA 23 8A E0 60 8B CF 36 19 F2 17 A4 60 B6 34 AC FF 05 9E 97 ) [2017-04-02 18:49:46.385] [D] [zway] RECEIVED ACK [2017-04-02 18:49:46.391] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:46.393] [D] [zway] SENT ACK [2017-04-02 18:49:46.394] [I] [zway] Job 0x13 (Security, Door Lock Operation Set): Response received - transfered to encapsulated jobs [2017-04-02 18:49:46.394] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:46.395] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:46.415] [D] [zway] RECEIVED: ( 01 07 00 13 9E 00 00 03 76 ) [2017-04-02 18:49:46.416] [D] [zway] SENT ACK [2017-04-02 18:49:46.417] [I] [zway] Job 0x13 (Security, Door Lock Operation Set): Callback received - transfered to encapsulated jobs [2017-04-02 18:49:46.418] [I] [zway] Job 0x13 (Door Lock Operation Set): Delivered [2017-04-02 18:49:46.420] [D] [zway] SendData Response with callback 0x9e received: received by recipient [2017-04-02 18:49:46.421] [D] [zway] Job 0x13 (Door Lock Operation Set): success [2017-04-02 18:49:46.421] [I] [zway] Removing job: Door Lock Operation Set [2017-04-02 18:49:46.422] [I] [zway] Job 0x13 (Security, Door Lock Operation Set): Delivered [2017-04-02 18:49:46.424] [D] [zway] SendData Response with callback 0x9e received: received by recipient [2017-04-02 18:49:46.425] [D] [zway] SETDATA devices.11.data.lastSend = 7216344 (0x006e1cd8) [2017-04-02 18:49:46.426] [D] [zway] Job 0x13 (Security, Door Lock Operation Set): success [2017-04-02 18:49:46.426] [I] [zway] Removing job: Security, Door Lock Operation Set [2017-04-02 18:49:46.479] [D] [zway] SENDING (cb 0x9f): ( 01 09 00 13 0B 02 98 40 05 9F AE ) [2017-04-02 18:49:46.481] [D] [zway] RECEIVED ACK [2017-04-02 18:49:46.487] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:46.488] [D] [zway] SENT ACK [2017-04-02 18:49:46.489] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:46.507] [D] [zway] RECEIVED: ( 01 07 00 13 9F 00 00 03 77 ) [2017-04-02 18:49:46.508] [D] [zway] SENT ACK [2017-04-02 18:49:46.509] [I] [zway] Job 0x13 (Nonce Get): Delivered [2017-04-02 18:49:46.511] [D] [zway] SendData Response with callback 0x9f received: received by recipient [2017-04-02 18:49:46.511] [D] [zway] SETDATA devices.11.data.lastSend = 7216353 (0x006e1ce1) [2017-04-02 18:49:46.512] [D] [zway] Job 0x13 (Nonce Get): success [2017-04-02 18:49:46.513] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:46.514] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216353 (0x006e1ce1) [2017-04-02 18:49:46.515] [I] [zway] Removing job: Nonce Get [2017-04-02 18:49:46.528] [D] [zway] RECEIVED: ( 01 10 00 04 00 0B 0A 98 80 E9 0A 47 5E C2 5D 6A 9E 63 ) [2017-04-02 18:49:46.529] [D] [zway] SENT ACK [2017-04-02 18:49:46.531] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:46.532] [I] [zway] Node 11:0 CC Security: received Nonce Report [2017-04-02 18:49:46.532] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonce = ** [2017-04-02 18:49:46.533] [I] [zway] Node 11:0 CC Security: Sending a secure message [2017-04-02 18:49:46.534] [I] [zway] Node 11:0 CC Security: sending encrypted packet: Door Lock Operation Get [2017-04-02 18:49:46.535] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.toFollow = False [2017-04-02 18:49:46.536] [D] [zway] SETDATA devices.11.data.lastNonceGet = Empty [2017-04-02 18:49:46.537] [I] [zway] Adding job: Security, Door Lock Operation Get [2017-04-02 18:49:46.579] [D] [zway] SENDING (cb 0xa0): ( 01 1D 00 13 0B 16 98 81 D1 F2 30 53 CB 53 5A 62 04 DE 54 E9 31 1F 7B 23 6C 9B 8B F1 05 A0 2C ) [2017-04-02 18:49:46.585] [D] [zway] RECEIVED ACK [2017-04-02 18:49:46.589] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:46.590] [D] [zway] SENT ACK [2017-04-02 18:49:46.592] [I] [zway] Job 0x13 (Security, Door Lock Operation Get): Response received - transfered to encapsulated jobs [2017-04-02 18:49:46.594] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:46.595] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:46.614] [D] [zway] RECEIVED: ( 01 07 00 13 A0 00 00 03 48 ) [2017-04-02 18:49:46.616] [D] [zway] SENT ACK [2017-04-02 18:49:46.617] [I] [zway] Job 0x13 (Security, Door Lock Operation Get): Callback received - transfered to encapsulated jobs [2017-04-02 18:49:46.619] [I] [zway] Job 0x13 (Door Lock Operation Get): Delivered [2017-04-02 18:49:46.620] [D] [zway] SendData Response with callback 0xa0 received: received by recipient [2017-04-02 18:49:46.622] [D] [zway] Job 0x13 (Door Lock Operation Get): success [2017-04-02 18:49:46.623] [I] [zway] Removing job: Door Lock Operation Get [2017-04-02 18:49:46.625] [I] [zway] Job 0x13 (Security, Door Lock Operation Get): Delivered [2017-04-02 18:49:46.627] [D] [zway] SendData Response with callback 0xa0 received: received by recipient [2017-04-02 18:49:46.628] [D] [zway] SETDATA devices.11.data.lastSend = 7216362 (0x006e1cea) [2017-04-02 18:49:46.630] [D] [zway] Job 0x13 (Security, Door Lock Operation Get): success [2017-04-02 18:49:46.631] [I] [zway] Removing job: Security, Door Lock Operation Get [2017-04-02 18:49:46.650] [D] [zway] RECEIVED: ( 01 08 00 04 00 0B 02 98 40 22 ) [2017-04-02 18:49:46.651] [D] [zway] SENT ACK [2017-04-02 18:49:46.653] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:46.655] [I] [zway] Node 11:0 CC Security: sending Nonce Report [2017-04-02 18:49:46.656] [I] [zway] Adding job: Nonce Report [2017-04-02 18:49:46.689] [D] [zway] SENDING (cb 0xa1): ( 01 11 00 13 0B 0A 98 80 EB 10 90 BD 66 97 CF 0A 05 A1 A2 ) [2017-04-02 18:49:46.692] [D] [zway] RECEIVED ACK [2017-04-02 18:49:46.699] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:46.700] [D] [zway] SENT ACK [2017-04-02 18:49:46.702] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:46.720] [D] [zway] RECEIVED: ( 01 07 00 13 A1 00 00 03 49 ) [2017-04-02 18:49:46.721] [D] [zway] SENT ACK [2017-04-02 18:49:46.723] [I] [zway] Job 0x13 (Nonce Report): Delivered [2017-04-02 18:49:46.725] [D] [zway] SendData Response with callback 0xa1 received: received by recipient [2017-04-02 18:49:46.726] [D] [zway] SETDATA devices.11.data.lastSend = 7216371 (0x006e1cf3) [2017-04-02 18:49:46.728] [D] [zway] Job 0x13 (Nonce Report): success [2017-04-02 18:49:46.729] [I] [zway] Removing job: Nonce Report [2017-04-02 18:49:46.743] [D] [zway] RECEIVED: ( 01 21 00 04 00 0B 1B 98 81 5D 4F A2 B7 E4 ED 5C 8A 1F 40 BB 05 F2 8D C0 48 EB AB AB D9 C5 0C A9 BF F3 03 ) [2017-04-02 18:49:46.745] [D] [zway] SENT ACK [2017-04-02 18:49:46.747] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:46.748] [I] [zway] Node 11:0 CC Security: Received a secure message [2017-04-02 18:49:46.750] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.firstPart = ** [2017-04-02 18:49:46.751] [I] [zway] Node 11:0 CC Security: passing decrypted packet to application level: [ 62 03 ff 11 05 fe fe ] [2017-04-02 18:49:46.753] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.mode = 255 (0x000000ff) [2017-04-02 18:49:46.755] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.outsideMode = 1 (0x00000001) [2017-04-02 18:49:46.757] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.insideMode = 1 (0x00000001) [2017-04-02 18:49:46.758] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.condition = 5 (0x00000005) [2017-04-02 18:49:46.760] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockMinutes = 254 (0x000000fe) [2017-04-02 18:49:46.766] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockSeconds = 254 (0x000000fe) [2017-04-02 18:49:49.943] [I] [core] --- ZWayVDev_zway_11-0-98 performCommand processing: {"0":"open","1":{}} [2017-04-02 18:49:49.945] [I] [zway] Adding job: Door Lock Operation Set [2017-04-02 18:49:49.946] [I] [zway] Adding job: Door Lock Operation Get [2017-04-02 18:49:49.950] [I] [zway] Node 11:0 CC Security: sending Nonce Get [2017-04-02 18:49:49.951] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:49.952] [I] [zway] Adding job: Nonce Get [2017-04-02 18:49:49.953] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216683 (0x006e1e2b) [2017-04-02 18:49:49.965] [D] [zway] SENDING (cb 0xa2): ( 01 09 00 13 0B 02 98 40 05 A2 93 ) [2017-04-02 18:49:49.977] [D] [zway] RECEIVED ACK [2017-04-02 18:49:49.979] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:49.980] [D] [zway] SENT ACK [2017-04-02 18:49:49.981] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:49.994] [D] [zway] RECEIVED: ( 01 07 00 13 A2 00 00 02 4B ) [2017-04-02 18:49:49.995] [D] [zway] SENT ACK [2017-04-02 18:49:49.997] [I] [zway] Job 0x13 (Nonce Get): Delivered [2017-04-02 18:49:49.999] [D] [zway] SendData Response with callback 0xa2 received: received by recipient [2017-04-02 18:49:50.004] [D] [zway] SETDATA devices.11.data.lastSend = 7216687 (0x006e1e2f) [2017-04-02 18:49:50.005] [D] [zway] Job 0x13 (Nonce Get): success [2017-04-02 18:49:50.007] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:50.009] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216687 (0x006e1e2f) [2017-04-02 18:49:50.010] [I] [zway] Removing job: Nonce Get [2017-04-02 18:49:50.019] [D] [zway] RECEIVED: ( 01 10 00 04 00 0B 0A 98 80 6D F5 59 9A 0F 04 54 94 62 ) [2017-04-02 18:49:50.021] [D] [zway] SENT ACK [2017-04-02 18:49:50.022] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:50.029] [I] [zway] Node 11:0 CC Security: received Nonce Report [2017-04-02 18:49:50.030] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonce = ** [2017-04-02 18:49:50.032] [I] [zway] Node 11:0 CC Security: Sending a secure message [2017-04-02 18:49:50.033] [I] [zway] Node 11:0 CC Security: sending encrypted packet: Door Lock Operation Set [2017-04-02 18:49:50.035] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.toFollow = False [2017-04-02 18:49:50.036] [D] [zway] SETDATA devices.11.data.lastNonceGet = Empty [2017-04-02 18:49:50.038] [I] [zway] Adding job: Security, Door Lock Operation Set [2017-04-02 18:49:50.049] [I] [zway] Node 11:0 CC Security: sending Nonce Get [2017-04-02 18:49:50.050] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:50.052] [I] [zway] Adding job: Nonce Get [2017-04-02 18:49:50.053] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216689 (0x006e1e31) [2017-04-02 18:49:50.076] [D] [zway] SENDING (cb 0xa3): ( 01 1E 00 13 0B 17 98 81 62 5D 94 CA 17 CE AA 0D 5B DE F2 F7 6D 5C 5B 01 6D 85 8C 79 5F 05 A3 E7 ) [2017-04-02 18:49:50.079] [D] [zway] RECEIVED ACK [2017-04-02 18:49:50.086] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:50.086] [D] [zway] SENT ACK [2017-04-02 18:49:50.086] [I] [zway] Job 0x13 (Security, Door Lock Operation Set): Response received - transfered to encapsulated jobs [2017-04-02 18:49:50.086] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:50.087] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:50.111] [D] [zway] RECEIVED: ( 01 07 00 13 A3 00 00 03 4B ) [2017-04-02 18:49:50.112] [D] [zway] SENT ACK [2017-04-02 18:49:50.114] [I] [zway] Job 0x13 (Security, Door Lock Operation Set): Callback received - transfered to encapsulated jobs [2017-04-02 18:49:50.115] [I] [zway] Job 0x13 (Door Lock Operation Set): Delivered [2017-04-02 18:49:50.117] [D] [zway] SendData Response with callback 0xa3 received: received by recipient [2017-04-02 18:49:50.118] [D] [zway] Job 0x13 (Door Lock Operation Set): success [2017-04-02 18:49:50.119] [I] [zway] Removing job: Door Lock Operation Set [2017-04-02 18:49:50.121] [I] [zway] Job 0x13 (Security, Door Lock Operation Set): Delivered [2017-04-02 18:49:50.123] [D] [zway] SendData Response with callback 0xa3 received: received by recipient [2017-04-02 18:49:50.125] [D] [zway] SETDATA devices.11.data.lastSend = 7216697 (0x006e1e39) [2017-04-02 18:49:50.126] [D] [zway] Job 0x13 (Security, Door Lock Operation Set): success [2017-04-02 18:49:50.127] [I] [zway] Removing job: Security, Door Lock Operation Set [2017-04-02 18:49:50.181] [D] [zway] SENDING (cb 0xa4): ( 01 09 00 13 0B 02 98 40 05 A4 95 ) [2017-04-02 18:49:50.185] [D] [zway] RECEIVED ACK [2017-04-02 18:49:50.190] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:50.191] [D] [zway] SENT ACK [2017-04-02 18:49:50.192] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:50.210] [D] [zway] RECEIVED: ( 01 07 00 13 A4 00 00 03 4C ) [2017-04-02 18:49:50.211] [D] [zway] SENT ACK [2017-04-02 18:49:50.213] [I] [zway] Job 0x13 (Nonce Get): Delivered [2017-04-02 18:49:50.214] [D] [zway] SendData Response with callback 0xa4 received: received by recipient [2017-04-02 18:49:50.216] [D] [zway] SETDATA devices.11.data.lastSend = 7216706 (0x006e1e42) [2017-04-02 18:49:50.217] [D] [zway] Job 0x13 (Nonce Get): success [2017-04-02 18:49:50.219] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonceAckWait = ** [2017-04-02 18:49:50.221] [D] [zway] SETDATA devices.11.data.lastNonceGet = 7216706 (0x006e1e42) [2017-04-02 18:49:50.222] [I] [zway] Removing job: Nonce Get [2017-04-02 18:49:50.227] [D] [zway] RECEIVED: ( 01 10 00 04 00 0B 0A 98 80 F5 48 32 38 4B D6 E6 EC D2 ) [2017-04-02 18:49:50.228] [D] [zway] SENT ACK [2017-04-02 18:49:50.230] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:50.232] [I] [zway] Node 11:0 CC Security: received Nonce Report [2017-04-02 18:49:50.234] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.rNonce = ** [2017-04-02 18:49:50.235] [I] [zway] Node 11:0 CC Security: Sending a secure message [2017-04-02 18:49:50.236] [I] [zway] Node 11:0 CC Security: sending encrypted packet: Door Lock Operation Get [2017-04-02 18:49:50.238] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.toFollow = False [2017-04-02 18:49:50.240] [D] [zway] SETDATA devices.11.data.lastNonceGet = Empty [2017-04-02 18:49:50.241] [I] [zway] Adding job: Security, Door Lock Operation Get [2017-04-02 18:49:50.284] [D] [zway] SENDING (cb 0xa5): ( 01 1D 00 13 0B 16 98 81 8B FD 94 61 BD 68 60 41 02 B7 BA F5 32 63 1D AC BF 51 F5 BF 05 A5 9C ) [2017-04-02 18:49:50.289] [D] [zway] RECEIVED ACK [2017-04-02 18:49:50.295] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:50.297] [D] [zway] SENT ACK [2017-04-02 18:49:50.298] [I] [zway] Job 0x13 (Security, Door Lock Operation Get): Response received - transfered to encapsulated jobs [2017-04-02 18:49:50.299] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:50.301] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:50.319] [D] [zway] RECEIVED: ( 01 07 00 13 A5 00 00 03 4D ) [2017-04-02 18:49:50.321] [D] [zway] SENT ACK [2017-04-02 18:49:50.322] [I] [zway] Job 0x13 (Security, Door Lock Operation Get): Callback received - transfered to encapsulated jobs [2017-04-02 18:49:50.324] [I] [zway] Job 0x13 (Door Lock Operation Get): Delivered [2017-04-02 18:49:50.325] [D] [zway] SendData Response with callback 0xa5 received: received by recipient [2017-04-02 18:49:50.327] [D] [zway] Job 0x13 (Door Lock Operation Get): success [2017-04-02 18:49:50.328] [I] [zway] Removing job: Door Lock Operation Get [2017-04-02 18:49:50.330] [I] [zway] Job 0x13 (Security, Door Lock Operation Get): Delivered [2017-04-02 18:49:50.331] [D] [zway] SendData Response with callback 0xa5 received: received by recipient [2017-04-02 18:49:50.333] [D] [zway] SETDATA devices.11.data.lastSend = 7216715 (0x006e1e4b) [2017-04-02 18:49:50.335] [D] [zway] Job 0x13 (Security, Door Lock Operation Get): success [2017-04-02 18:49:50.336] [I] [zway] Removing job: Security, Door Lock Operation Get [2017-04-02 18:49:50.356] [D] [zway] RECEIVED: ( 01 08 00 04 00 0B 02 98 40 22 ) [2017-04-02 18:49:50.357] [D] [zway] SENT ACK [2017-04-02 18:49:50.359] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:50.361] [I] [zway] Node 11:0 CC Security: sending Nonce Report [2017-04-02 18:49:50.363] [I] [zway] Adding job: Nonce Report [2017-04-02 18:49:50.396] [D] [zway] SENDING (cb 0xa6): ( 01 11 00 13 0B 0A 98 80 97 1D 00 16 90 35 5B 9D 05 A6 B8 ) [2017-04-02 18:49:50.399] [D] [zway] RECEIVED ACK [2017-04-02 18:49:50.405] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:50.406] [D] [zway] SENT ACK [2017-04-02 18:49:50.408] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:50.427] [D] [zway] RECEIVED: ( 01 07 00 13 A6 00 00 03 4E ) [2017-04-02 18:49:50.429] [D] [zway] SENT ACK [2017-04-02 18:49:50.430] [I] [zway] Job 0x13 (Nonce Report): Delivered [2017-04-02 18:49:50.432] [D] [zway] SendData Response with callback 0xa6 received: received by recipient [2017-04-02 18:49:50.434] [D] [zway] SETDATA devices.11.data.lastSend = 7216724 (0x006e1e54) [2017-04-02 18:49:50.435] [D] [zway] Job 0x13 (Nonce Report): success [2017-04-02 18:49:50.437] [I] [zway] Removing job: Nonce Report [2017-04-02 18:49:50.452] [D] [zway] RECEIVED: ( 01 21 00 04 00 0B 1B 98 81 42 D7 F0 FE 97 4B D9 1D 88 8E 6D FE 13 73 64 16 97 1D AA DC C5 88 7E BE 4F E9 ) [2017-04-02 18:49:50.453] [D] [zway] SENT ACK [2017-04-02 18:49:50.455] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:50.457] [I] [zway] Node 11:0 CC Security: Received a secure message [2017-04-02 18:49:50.458] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.firstPart = ** [2017-04-02 18:49:50.460] [I] [zway] Node 11:0 CC Security: passing decrypted packet to application level: [ 62 03 ff 11 05 fe fe ] [2017-04-02 18:49:50.462] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.mode = 255 (0x000000ff) [2017-04-02 18:49:50.463] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.outsideMode = 1 (0x00000001) [2017-04-02 18:49:50.465] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.insideMode = 1 (0x00000001) [2017-04-02 18:49:50.466] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.condition = 5 (0x00000005) [2017-04-02 18:49:50.468] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockMinutes = 254 (0x000000fe) [2017-04-02 18:49:50.469] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockSeconds = 254 (0x000000fe) [2017-04-02 18:49:55.174] [D] [zway] RECEIVED: ( 01 08 00 04 00 0B 02 98 40 22 ) [2017-04-02 18:49:55.182] [D] [zway] SENT ACK [2017-04-02 18:49:55.184] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:55.185] [I] [zway] Node 11:0 CC Security: sending Nonce Report [2017-04-02 18:49:55.186] [I] [zway] Adding job: Nonce Report [2017-04-02 18:49:55.188] [D] [zway] SENDING (cb 0xa7): ( 01 11 00 13 0B 0A 98 80 27 69 84 65 D2 AB 73 45 05 A7 A6 ) [2017-04-02 18:49:55.192] [D] [zway] RECEIVED ACK [2017-04-02 18:49:55.198] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:49:55.200] [D] [zway] SENT ACK [2017-04-02 18:49:55.201] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:49:55.220] [D] [zway] RECEIVED: ( 01 07 00 13 A7 00 00 03 4F ) [2017-04-02 18:49:55.221] [D] [zway] SENT ACK [2017-04-02 18:49:55.222] [I] [zway] Job 0x13 (Nonce Report): Delivered [2017-04-02 18:49:55.224] [D] [zway] SendData Response with callback 0xa7 received: received by recipient [2017-04-02 18:49:55.225] [D] [zway] SETDATA devices.11.data.lastSend = 7217186 (0x006e2022) [2017-04-02 18:49:55.226] [D] [zway] Job 0x13 (Nonce Report): success [2017-04-02 18:49:55.226] [I] [zway] Removing job: Nonce Report [2017-04-02 18:49:55.250] [D] [zway] RECEIVED: ( 01 21 00 04 00 0B 1B 98 81 19 2A 17 27 94 20 FF 14 7D EC D6 8A A1 21 6C FC 27 6A 9D 08 4F 91 AA 27 20 F9 ) [2017-04-02 18:49:55.252] [D] [zway] SENT ACK [2017-04-02 18:49:55.253] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:49:55.254] [I] [zway] Node 11:0 CC Security: Received a secure message [2017-04-02 18:49:55.255] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.firstPart = ** [2017-04-02 18:49:55.256] [I] [zway] Node 11:0 CC Security: passing decrypted packet to application level: [ 62 03 00 11 02 fe fe ] [2017-04-02 18:49:55.257] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.mode = 0 (0x00000000) [2017-04-02 18:49:55.258] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.outsideMode = 1 (0x00000001) [2017-04-02 18:49:55.259] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.insideMode = 1 (0x00000001) [2017-04-02 18:49:55.260] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.condition = 2 (0x00000002) [2017-04-02 18:49:55.261] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockMinutes = 254 (0x000000fe) [2017-04-02 18:49:55.262] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockSeconds = 254 (0x000000fe) [2017-04-02 18:50:06.717] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:06.821] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:06.833] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:06.917] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:07.022] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:07.034] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:07.128] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:10.399] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:10.513] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:10.524] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:10.608] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:10.722] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:10.733] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:10.827] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:15.614] [D] [zway] Job 0x13: deleted from queue [2017-04-02 18:50:20.266] [D] [zway] RECEIVED: ( 01 08 00 04 00 0B 02 98 40 22 ) [2017-04-02 18:50:20.267] [D] [zway] SENT ACK [2017-04-02 18:50:20.269] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:50:20.271] [I] [zway] Node 11:0 CC Security: sending Nonce Report [2017-04-02 18:50:20.272] [I] [zway] Adding job: Nonce Report [2017-04-02 18:50:20.274] [D] [zway] SENDING (cb 0xa8): ( 01 11 00 13 0B 0A 98 80 9F 85 86 FA 36 E6 01 9D 05 A8 63 ) [2017-04-02 18:50:20.278] [D] [zway] RECEIVED ACK [2017-04-02 18:50:20.284] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2017-04-02 18:50:20.285] [D] [zway] SENT ACK [2017-04-02 18:50:20.287] [D] [zway] Delivered to Z-Wave stack [2017-04-02 18:50:20.305] [D] [zway] RECEIVED: ( 01 07 00 13 A8 00 00 02 41 ) [2017-04-02 18:50:20.306] [D] [zway] SENT ACK [2017-04-02 18:50:20.307] [I] [zway] Job 0x13 (Nonce Report): Delivered [2017-04-02 18:50:20.309] [D] [zway] SendData Response with callback 0xa8 received: received by recipient [2017-04-02 18:50:20.310] [D] [zway] SETDATA devices.11.data.lastSend = 7219615 (0x006e299f) [2017-04-02 18:50:20.311] [D] [zway] Job 0x13 (Nonce Report): success [2017-04-02 18:50:20.311] [I] [zway] Removing job: Nonce Report [2017-04-02 18:50:20.328] [D] [zway] RECEIVED: ( 01 21 00 04 00 0B 1B 98 81 A5 FA 8A 42 EA A2 58 A4 AA EB 2D 65 41 75 F6 CC 9F 77 2F FB 93 B4 B1 FD A9 09 ) [2017-04-02 18:50:20.329] [D] [zway] SENT ACK [2017-04-02 18:50:20.330] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000) [2017-04-02 18:50:20.331] [I] [zway] Node 11:0 CC Security: Received a secure message [2017-04-02 18:50:20.332] [D] [zway] SETDATA devices.11.instances.0.commandClasses.152.data.firstPart = ** [2017-04-02 18:50:20.333] [I] [zway] Node 11:0 CC Security: passing decrypted packet to application level: [ 62 03 ff 11 05 fe fe ] [2017-04-02 18:50:20.335] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.mode = 255 (0x000000ff) [2017-04-02 18:50:20.336] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.outsideMode = 1 (0x00000001) [2017-04-02 18:50:20.337] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.insideMode = 1 (0x00000001) [2017-04-02 18:50:20.338] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.condition = 5 (0x00000005) [2017-04-02 18:50:20.339] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockMinutes = 254 (0x000000fe) [2017-04-02 18:50:20.340] [D] [zway] SETDATA devices.11.instances.0.commandClasses.98.data.lockSeconds = 254 (0x000000fe) [2017-04-02 18:50:40.590] [D] [zway] Job 0x13: deleted from queue

I do not see any errors which you had in yours, my log was from the start of me unlocking until it relocked, about 30 seconds of log, command sent from iPhone.

DJay-X commented 7 years ago

@Pltnorwich Thanks Phil for bringing this to the attention. I'm facing absolutely the same problem with nearly exact the same configuration since the first day on. Danalock V125/V2 BTZE and Raspberry PI Modell 2B. All software on the latest versions homebridge@0.4.16, node@7.7.3, npm@4.4.1, homebridge-zway@0.5.0 Everything works great with Siri open/close but in the app regardless of Apple Home App or Elgato Eve the status is never shown correct. I have no solution but good to hear that I am not the only one. Thanks in advance to all help to investigate.

ToddGreenfield commented 7 years ago

@Pltnorwich I have been doing some more digging and still think this is a zway issue not an issue with the plugin. I dug through the plugin code to see what it is looking for and see it is hitting the zway url + /ZAutomation/api/v1/devices/ + vdev.id, looking for the "level" parameter. Which in my case would be: http://192.168.1.91:8083/ZAutomation/api/v1/devices/ZWayVDev_zway_52-0-98 When I hit this via a browser is shows a null ("") for level, so the plugin sees that as an unknown status. Would that be correct @SphtKr ? image

Leads me to believe it is a problem with the zwave UI... What version of zway FW and UI are you all running?

ToddGreenfield commented 7 years ago

@Pltnorwich @DJay79 - Try and upgrade to zway 2.3.1. Looks like that at least fixes the locking status problem, and confirming this is not a homebridge-zway plugin issue. Now I have a different problem in that if I use homebridge (or the smarthome UI for that matter) to open my lock, when the auto-lock feature kicks in, it apparently does not tell the smarthome UI (which in turn doesnt allow the zway plugin to read the update). Also, the lock seems to have disappeared from the expert UI under Control-Locks so I cant see status there. @SphtKr I think you can close out this issue. I am taking my woes over to the zwave/smarthome issues (I feel dumb but just stumbled on the zwave repo on github - https://github.com/Z-Wave-Me/zwave-smarthome). Thanks for all the work on this plugin BTW!

Pltnorwich commented 7 years ago

I'll update mine to 2.3.1 later today and let you know how my install goes, see if I have the same issue of the lock disappearing in expert-ui after update.

DJay-X commented 7 years ago

@Pltnorwich Thanks for pointing to update zway to 2.3.1. Unfortunately didn't change anything with Danalock V125. I can still change with Siri and it works great to open and close the lock but the status does not change on the z-wave side.

Fingerz commented 7 years ago

@DJay79 In my case the update to Z-Way 2.3.1 has corrected the issue with status of my Danalock V125. I'll need to do some more testing tonight to be sure, but I'm now correctly getting the lock/unlock status push notifications when the lock is used manually or via Z-Wave.

ToddGreenfield commented 7 years ago

@DJay79 You may need to restart homebridge, (or maybe reboot). @Fingerz Do you see your lock on the expert UI page?

Fingerz commented 7 years ago

Nope, it doesn't show up in the Expert UI in the Locks status section. This is a regression...but that doesn't prevent it from working with Homebridge as far as I can tell.

Pltnorwich commented 7 years ago

I've just updated away to 2.3.1, the lock status works a treat now in the Home app, it shows on the zway server page when unlock and lock (auto lock) via iPhone. As noted only downside so far is its disappeared off the expert page. but is fully functioning on smartphone-ui

SphtKr commented 7 years ago

You guys make my job so easy sometimes! @ToddGreenfield I had not observed the null value in the level, I'm not sure if that's supposed to be allowed by the Z-Way API for that kind of device or not, but my plugin code would not have expected it and it probably returned an unexpected/illegal value to HomeKit. If anyone sees this value again in newer Z-Way versions reopen this issue and I'll put in a trap for that value!

(P.S.: My lousy V100 Danalock was never good at reporting status...but maybe this was part of my issue and I hadn't noticed it!)

Pltnorwich commented 7 years ago

@ToddGreenfield lost me about the null value haha, a bit new to this. but if any more info is needed, just ask ( may need some direction) but happy to share info.

ToddGreenfield commented 7 years ago

@SphtKr With the recent zway update to 2.3.1 it appears to have fixed that piece, and when I query the device via browser now is shows "close" for level. So for now the status report is ok. Thanks again-

DJay-X commented 7 years ago

@ToddGreenfield Unfortunately for me nothing changed with update to 2.3.1. For example the lock closes at 2300 by a timer from the home app. But in the Home or Eve App the switch is still on open. So I checked on z-wave UI and there it was also shown as still open. When I close the lock there (of course lock is still closed) the icon turned to close and also in the Home and Eve App. :/ So for me is nothing wrong with the fantastic plugin from @SphtKr
I think I'll life with that. For me its more important that I can use the lock at all with this plugin. And this works.