DoorLockLogging command class and KwikSet locks

jensmoes · Post by **jensmoes** » 04 Jun 2015 17:59

I recently got the new Kwikset 916 lock which is quite nice, but I am having trouble figuring out the DoorLockLogging CC.
The CC works fine but I never get any log entries. This is all I ever get:

Code: Select all

{"invalidateTime":1433428988,"updateTime":1433428989,"type":"empty","value":null,"supported":{"invalidateTime":1433428988,"updateTime":1433428989,"type":"bool","value":true},"version":{"invalidateTime":1433428991,"updateTime":1433429010,"type":"int","value":1},"security":{"invalidateTime":1433428988,"updateTime":1433428990,"type":"bool","value":true},"interviewDone":{"invalidateTime":1433428988,"updateTime":1433429069,"type":"bool","value":true},"interviewCounter":{"invalidateTime":1433428988,"updateTime":1433429055,"type":"int","value":9},"maxRecords":{"invalidateTime":1433428988,"updateTime":1433429069,"type":"int","value":10}}

I would have expected it to also hold some elements from 1-10 like other people on this forum has posted.
Unlike other posts here I am not able to see the results of Get() in the logs as they are encrypted as you can see in this log:

Code: Select all

2015-06-04 07:48:12.584] [I] [zway] Adding job: Door Lock Logging Get
[2015-06-04 07:48:12.609] [I] [zway] Node 22:0 CC Security: sending Nonce Get
[2015-06-04 07:48:12.610] [D] [zway] SETDATA devices.22.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2015-06-04 07:48:12.612] [I] [zway] Adding job: Nonce Get
[2015-06-04 07:48:12.614] [D] [zway] SETDATA devices.22.data.lastNonceGet = 11892607 (0x00b5777f)
[2015-06-04 07:48:12.626] [D] [zway] SENDING (cb 0x53): ( 01 09 00 13 16 02 98 40 05 53 7F )
[2015-06-04 07:48:12.628] [D] [zway] RECEIVED ACK
[2015-06-04 07:48:12.631] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-06-04 07:48:12.633] [D] [zway] SENT ACK
[2015-06-04 07:48:12.638] [D] [zway] Delivered to Z-Wave stack
[2015-06-04 07:48:13.798] [D] [zway] RECEIVED: ( 01 05 00 13 53 00 BA )
[2015-06-04 07:48:13.799] [D] [zway] SENT ACK
[2015-06-04 07:48:13.801] [I] [zway] Job 0x13 (Nonce Get): Delivered
[2015-06-04 07:48:13.802] [D] [zway] SETDATA devices.22.data.lastPacketInfo.delivered = True
[2015-06-04 07:48:13.804] [D] [zway] SETDATA devices.22.data.lastPacketInfo.packetLength = 5 (0x00000005)
[2015-06-04 07:48:13.805] [D] [zway] SETDATA devices.22.data.lastPacketInfo.deliveryTime = 1161 (0x00000489)
[2015-06-04 07:48:13.807] [D] [zway] SETDATA devices.22.data.lastPacketInfo = **********
[2015-06-04 07:48:13.809] [D] [zway] SendData Response with callback 0x53 received: received by recipient
[2015-06-04 07:48:13.818] [D] [zway] SETDATA devices.22.data.lastSend = 11892723 (0x00b577f3)
[2015-06-04 07:48:13.820] [D] [zway] Job 0x13 (Nonce Get): success
[2015-06-04 07:48:13.821] [D] [zway] SETDATA devices.22.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2015-06-04 07:48:13.822] [I] [zway] Removing job: Nonce Get
[2015-06-04 07:48:13.823] [D] [zway] RECEIVED: ( 01 10 00 04 00 16 0A 98 80 CB 0D 96 AB C1 0A 83 19 45 )
[2015-06-04 07:48:13.824] [D] [zway] SENT ACK
[2015-06-04 07:48:13.825] [D] [zway] SETDATA devices.22.data.lastReceived = 0 (0x00000000)
[2015-06-04 07:48:13.827] [I] [zway] Node 22:0 CC Security: received Nonce Report
[2015-06-04 07:48:13.828] [D] [zway] SETDATA devices.22.instances.0.commandClasses.152.data.rNonce = **********
[2015-06-04 07:48:13.829] [I] [zway] Removing job: Door Lock Logging Get
[2015-06-04 07:48:13.830] [I] [zway] Node 22:0 CC Security: Sending a secure message
[2015-06-04 07:48:13.831] [I] [zway] Node 22:0 CC Security: sending encrypted packet: Door Lock Logging Get
[2015-06-04 07:48:13.832] [D] [zway] SETDATA devices.22.instances.0.commandClasses.152.data.toFollow = False
[2015-06-04 07:48:13.834] [D] [zway] SETDATA devices.22.data.lastNonceGet = Empty
[2015-06-04 07:48:13.835] [I] [zway] Adding job: Security, Door Lock Logging Get
[2015-06-04 07:48:13.879] [D] [zway] SENDING (cb 0x54): ( 01 1E 00 13 16 17 98 81 C3 CB A1 11 5D 2F D5 55 F3 4B DC ED CB 33 1A 28 E3 CB 13 70 10 05 54 E9 )
[2015-06-04 07:48:13.884] [D] [zway] RECEIVED ACK
[2015-06-04 07:48:13.887] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-06-04 07:48:13.888] [D] [zway] SENT ACK
[2015-06-04 07:48:13.889] [I] [zway] Job 0x13 (Security, Door Lock Logging Get): Response received - transfered to encapsulated jobs
[2015-06-04 07:48:13.890] [D] [zway] Delivered to Z-Wave stack
[2015-06-04 07:48:13.892] [D] [zway] Delivered to Z-Wave stack
[2015-06-04 07:48:15.059] [D] [zway] RECEIVED: ( 01 05 00 13 54 00 BD )
[2015-06-04 07:48:15.060] [D] [zway] SENT ACK
[2015-06-04 07:48:15.062] [I] [zway] Job 0x13 (Security, Door Lock Logging Get): Callback received - transfered to encapsulated jobs
[2015-06-04 07:48:15.064] [I] [zway] Job 0x13 (Door Lock Logging Get): Delivered
[2015-06-04 07:48:15.065] [D] [zway] SendData Response with callback 0x54 received: received by recipient
[2015-06-04 07:48:15.067] [I] [zway] Job 0x13 (Security, Door Lock Logging Get): Delivered
[2015-06-04 07:48:15.069] [D] [zway] SETDATA devices.22.data.lastPacketInfo.delivered = True
[2015-06-04 07:48:15.071] [D] [zway] SETDATA devices.22.data.lastPacketInfo.packetLength = 26 (0x0000001a)
[2015-06-04 07:48:15.073] [D] [zway] SETDATA devices.22.data.lastPacketInfo.deliveryTime = 1175 (0x00000497)
[2015-06-04 07:48:15.075] [D] [zway] SETDATA devices.22.data.lastPacketInfo = **********
[2015-06-04 07:48:15.076] [D] [zway] SendData Response with callback 0x54 received: received by recipient
[2015-06-04 07:48:15.078] [D] [zway] SETDATA devices.22.data.lastSend = 11892843 (0x00b5786b)
[2015-06-04 07:48:15.080] [D] [zway] Job 0x13 (Security, Door Lock Logging Get): success
[2015-06-04 07:48:15.082] [I] [zway] Removing job: Security, Door Lock Logging Get
[2015-06-04 07:48:15.473] [D] [zway] RECEIVED: ( 01 08 00 04 00 16 02 98 40 3F )
[2015-06-04 07:48:15.475] [D] [zway] SENT ACK
[2015-06-04 07:48:15.476] [D] [zway] SETDATA devices.22.data.lastReceived = 0 (0x00000000)
[2015-06-04 07:48:15.479] [I] [zway] Node 22:0 CC Security: sending Nonce Report
[2015-06-04 07:48:15.481] [I] [zway] Adding job: Nonce Report
[2015-06-04 07:48:15.483] [D] [zway] SENDING (cb 0x55): ( 01 11 00 13 16 0A 98 80 63 7B DB F1 90 1A CA 74 05 55 AF )
[2015-06-04 07:48:15.487] [D] [zway] RECEIVED ACK
[2015-06-04 07:48:15.490] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-06-04 07:48:15.492] [D] [zway] SENT ACK
[2015-06-04 07:48:15.493] [D] [zway] Delivered to Z-Wave stack
[2015-06-04 07:48:16.659] [D] [zway] RECEIVED: ( 01 05 00 13 55 00 BC )
[2015-06-04 07:48:16.660] [D] [zway] SENT ACK
[2015-06-04 07:48:16.662] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2015-06-04 07:48:16.664] [D] [zway] SETDATA devices.22.data.lastPacketInfo.delivered = True
[2015-06-04 07:48:16.665] [D] [zway] SETDATA devices.22.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2015-06-04 07:48:16.667] [D] [zway] SETDATA devices.22.data.lastPacketInfo.deliveryTime = 1168 (0x00000490)
[2015-06-04 07:48:16.669] [D] [zway] SETDATA devices.22.data.lastPacketInfo = **********
[2015-06-04 07:48:16.671] [D] [zway] SendData Response with callback 0x55 received: received by recipient
[2015-06-04 07:48:16.673] [D] [zway] SETDATA devices.22.data.lastSend = 11892992 (0x00b57900)
[2015-06-04 07:48:16.675] [D] [zway] Job 0x13 (Nonce Report): success
[2015-06-04 07:48:16.676] [I] [zway] Removing job: Nonce Report
[2015-06-04 07:48:16.691] [D] [zway] RECEIVED: ( 01 27 00 04 00 16 21 98 81 C8 C8 01 A1 FD 66 5F 01 F8 5C 09 A4 8B B0 B9 B9 46 17 A9 E2 6A 91 63 4E 54 50 22 F5 FD 55 51 43 )
[2015-06-04 07:48:16.692] [D] [zway] SENT ACK
[2015-06-04 07:48:16.694] [D] [zway] SETDATA devices.22.data.lastReceived = 0 (0x00000000)
[2015-06-04 07:48:16.696] [I] [zway] Node 22:0 CC Security: Received a secure message
[2015-06-04 07:48:16.697] [D] [zway] SETDATA devices.22.instances.0.commandClasses.152.data.firstPart = **********
[2015-06-04 07:48:16.699] [I] [zway] Node 22:0 CC Security: passing decrypted packet to application level: [ 4c 04 02 07 d0 01 02 0b 1b 12 08 00 00 ]

Does the logging need to be turned on by some hitherto unknown configuration parameter or is something broken?
I am curious to hear if anyone else here has seen similar behavior with the latest ZWay. I am using 2.0.1rc36 at the moment.