Waking up Aeon Labs ZW089-A Door Sensor

[bytedreamer]

I have a Aeon LabsGen 5 ZW089-A door sensor. It works well with my Razberry, except for one issue. The wakeup request from the device is not processing properly. I notice the following in the z-way logs. It appears that the zway lib is not handling wakeup requests properly because it is unsecure.

[2015-07-11 20:53:55.988] [D] [zway] RECEIVED: ( 01 08 00 04 00 12 02 84 07 60 )
[2015-07-11 20:53:55.989] [D] [zway] SENT ACK
[2015-07-11 20:53:55.989] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2015-07-11 20:53:55.989] [W] [zway] Got unsecure packet from device 18, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2015-07-11 21:02:09.460] [D] [zway] RECEIVED: ( 01 08 00 04 00 12 02 84 07 60 )
[2015-07-11 21:02:09.461] [D] [zway] SENT ACK
[2015-07-11 21:02:09.461] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2015-07-11 21:02:09.461] [W] [zway] Got unsecure packet from device 18, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.

[PoltoS]

This is a clear violation of protocol. It worked before, but a month ago we have enforced security policy to prevent potential spoofing of secure devices.

In brief the problem is that the device announces this CC only as secure, but tries to speak with us in unsecure more. We ignore this as potential attack. You are welcome to contact manufacturer, so we did we.

We know few other devices that have same issue (from other manufacturers) - this is escalated to Z-Wave Alliance, so we hope to stop this problem soon by improving certification tests.

If you don't need security, include the device without security. This will help.

[bytedreamer]

Thanks for the feedback

I tried including the device unsecured, however all the secure command classes are missing.

I hope the manufacture release a firmware update to properly send messages that are secure.

[bogr]

Hi,

I'm having exactly the same problems with a sensor, but the strange thing is that it works sometimes and not other times. For example I see the same tracings about unsecure package, each 5 minutes when the sensor tries to get awaken:

Code: Select all

[2016-01-06 00:13:35.817] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 )
[2016-01-06 00:13:35.817] [D] [zway] SENT ACK
[2016-01-06 00:13:35.817] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:13:35.817] [W] [zway] Got unsecure packet from device 2, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-06 00:18:35.815] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 )
[2016-01-06 00:18:35.816] [D] [zway] SENT ACK
[2016-01-06 00:18:35.816] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:18:35.816] [W] [zway] Got unsecure packet from device 2, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-06 00:23:35.816] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 )
[2016-01-06 00:23:35.816] [D] [zway] SENT ACK
[2016-01-06 00:23:35.816] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:23:35.816] [W] [zway] Got unsecure packet from device 2, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.

but then sudenly it manages to get awake:

Code: Select all

[2016-01-06 00:28:35.440] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 98 40 2B )
[2016-01-06 00:28:35.441] [D] [zway] SENT ACK
[2016-01-06 00:28:35.441] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:35.442] [I] [zway] Node 2:0 CC Security: sending Nonce Report
[2016-01-06 00:28:35.443] [I] [zway] Adding job: Nonce Report
[2016-01-06 00:28:35.443] [D] [zway] SENDING (cb 0x56): ( 01 11 00 13 02 0A 98 80 AC 7F CC 4E 3C A4 C5 91 05 56 23 )
[2016-01-06 00:28:35.450] [D] [zway] RECEIVED ACK
[2016-01-06 00:28:35.450] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-06 00:28:35.451] [D] [zway] SENT ACK
[2016-01-06 00:28:35.451] [D] [zway] Delivered to Z-Wave stack
[2016-01-06 00:28:35.470] [D] [zway] RECEIVED: ( 01 07 00 13 56 00 00 01 BC )
[2016-01-06 00:28:35.471] [D] [zway] SENT ACK
[2016-01-06 00:28:35.471] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-06 00:28:35.472] [D] [zway] SETDATA devices.2.data.lastPacketInfo.delivered = True
[2016-01-06 00:28:35.472] [D] [zway] SETDATA devices.2.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-06 00:28:35.473] [D] [zway] SETDATA devices.2.data.lastPacketInfo.deliveryTime = 19 (0x00000013)
[2016-01-06 00:28:35.473] [D] [zway] SETDATA devices.2.data.lastPacketInfo = **********
[2016-01-06 00:28:35.474] [D] [zway] SendData Response with callback 0x56 received: received by recipient
[2016-01-06 00:28:35.474] [D] [zway] SETDATA devices.2.data.lastSend = 1145582 (0x00117aee)
[2016-01-06 00:28:35.475] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-06 00:28:35.475] [I] [zway] Removing job: Nonce Report
[2016-01-06 00:28:35.491] [D] [zway] RECEIVED: ( 01 20 00 04 00 02 1A 98 81 3E 85 75 A3 FA 68 F6 82 79 D6 31 62 84 53 00 AC 30 AD 11 3C 8D 4D CC 28 

42 )
[2016-01-06 00:28:35.492] [D] [zway] SENT ACK
[2016-01-06 00:28:35.492] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:35.493] [I] [zway] Node 2:0 CC Security: Received a secure message
[2016-01-06 00:28:35.493] [D] [zway] SETDATA devices.2.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-06 00:28:35.494] [I] [zway] Node 2:0 CC Security: passing decrypted packet to application level: [ 31 05 01 22 00 e6 ]
[2016-01-06 00:28:35.494] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000)
[2016-01-06 00:28:35.495] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.1.scale = 0 (0x00000000)
[2016-01-06 00:28:35.496] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.1.val = 23.000000
[2016-01-06 00:28:35.498] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.1.scaleString = "°C"
[2016-01-06 00:28:35.499] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.1 = Empty
[2016-01-06 00:28:35.563] [I] [core] Notification: device-info (device-temperature): {"dev":"Sensor Temperature 2-0-49-1 Aeon Labs","l":"23 °C"}
[2016-01-06 00:28:36.150] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 98 40 2B )
[2016-01-06 00:28:36.150] [D] [zway] SENT ACK
[2016-01-06 00:28:36.150] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:36.151] [I] [zway] Node 2:0 CC Security: sending Nonce Report
[2016-01-06 00:28:36.151] [I] [zway] Adding job: Nonce Report
[2016-01-06 00:28:36.151] [D] [zway] SENDING (cb 0x57): ( 01 11 00 13 02 0A 98 80 DC B7 1D 86 D6 60 0A 2F 05 57 DC )
[2016-01-06 00:28:36.160] [D] [zway] RECEIVED ACK
[2016-01-06 00:28:36.160] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-06 00:28:36.161] [D] [zway] SENT ACK
[2016-01-06 00:28:36.161] [D] [zway] Delivered to Z-Wave stack
[2016-01-06 00:28:36.180] [D] [zway] RECEIVED: ( 01 07 00 13 57 00 00 02 BE )
[2016-01-06 00:28:36.180] [D] [zway] SENT ACK
[2016-01-06 00:28:36.180] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-06 00:28:36.180] [D] [zway] SETDATA devices.2.data.lastPacketInfo.delivered = True
[2016-01-06 00:28:36.181] [D] [zway] SETDATA devices.2.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-06 00:28:36.181] [D] [zway] SETDATA devices.2.data.lastPacketInfo.deliveryTime = 19 (0x00000013)
[2016-01-06 00:28:36.181] [D] [zway] SETDATA devices.2.data.lastPacketInfo = **********
[2016-01-06 00:28:36.181] [D] [zway] SendData Response with callback 0x57 received: received by recipient
[2016-01-06 00:28:36.181] [D] [zway] SETDATA devices.2.data.lastSend = 1145652 (0x00117b34)
[2016-01-06 00:28:36.181] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-06 00:28:36.181] [I] [zway] Removing job: Nonce Report
[2016-01-06 00:28:36.194] [D] [zway] RECEIVED: ( 01 1F 00 04 00 02 19 98 81 FD 31 5F 23 DC FE D6 5A 48 6F 77 D0 2D F8 DC E0 EE 58 0A BF 58 AA 96 F6 

)
[2016-01-06 00:28:36.194] [D] [zway] SENT ACK
[2016-01-06 00:28:36.194] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:36.195] [I] [zway] Node 2:0 CC Security: Received a secure message
[2016-01-06 00:28:36.195] [D] [zway] SETDATA devices.2.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-06 00:28:36.195] [I] [zway] Node 2:0 CC Security: passing decrypted packet to application level: [ 31 05 05 01 15 ]
[2016-01-06 00:28:36.195] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.5.deviceScale = 0 (0x00000000)
[2016-01-06 00:28:36.195] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.5.scale = 0 (0x00000000)
[2016-01-06 00:28:36.195] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.5.val = 21.000000
[2016-01-06 00:28:36.197] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.5.scaleString = "%"
[2016-01-06 00:28:36.197] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.5 = Empty
[2016-01-06 00:28:36.206] [I] [core] Notification: device-info (device-humidity): {"dev":"Sensor Humidity 2-0-49-5 Aeon Labs","l":"21 null"}
[2016-01-06 00:28:37.229] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 98 40 2B )
[2016-01-06 00:28:37.229] [D] [zway] SENT ACK
[2016-01-06 00:28:37.230] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:37.230] [I] [zway] Node 2:0 CC Security: sending Nonce Report
[2016-01-06 00:28:37.230] [I] [zway] Adding job: Nonce Report
[2016-01-06 00:28:37.230] [D] [zway] SENDING (cb 0x58): ( 01 11 00 13 02 0A 98 80 9B 0A 3C CF 19 11 DA EB 05 58 EB )
[2016-01-06 00:28:37.239] [D] [zway] RECEIVED ACK
[2016-01-06 00:28:37.240] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-06 00:28:37.240] [D] [zway] SENT ACK
[2016-01-06 00:28:37.240] [D] [zway] Delivered to Z-Wave stack
[2016-01-06 00:28:37.260] [D] [zway] RECEIVED: ( 01 07 00 13 58 00 00 02 B1 )
[2016-01-06 00:28:37.260] [D] [zway] SENT ACK
[2016-01-06 00:28:37.260] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-06 00:28:37.260] [D] [zway] SETDATA devices.2.data.lastPacketInfo.delivered = True
[2016-01-06 00:28:37.260] [D] [zway] SETDATA devices.2.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-06 00:28:37.260] [D] [zway] SETDATA devices.2.data.lastPacketInfo.deliveryTime = 20 (0x00000014)
[2016-01-06 00:28:37.260] [D] [zway] SETDATA devices.2.data.lastPacketInfo = **********
[2016-01-06 00:28:37.260] [D] [zway] SendData Response with callback 0x58 received: received by recipient
[2016-01-06 00:28:37.260] [D] [zway] SETDATA devices.2.data.lastSend = 1145758 (0x00117b9e)
[2016-01-06 00:28:37.260] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-06 00:28:37.261] [I] [zway] Removing job: Nonce Report
[2016-01-06 00:28:37.271] [D] [zway] RECEIVED: ( 01 1D 00 04 00 02 17 98 81 66 BA C9 49 4A 44 32 55 39 71 B5 D6 9B 6D E9 6F FA F7 82 82 01 88 )
[2016-01-06 00:28:37.271] [D] [zway] SENT ACK
[2016-01-06 00:28:37.272] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:37.272] [I] [zway] Node 2:0 CC Security: Received a secure message
[2016-01-06 00:28:37.272] [D] [zway] SETDATA devices.2.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-06 00:28:37.272] [I] [zway] Node 2:0 CC Security: passing decrypted packet to application level: [ 80 03 64 ]
[2016-01-06 00:28:37.272] [D] [zway] SETDATA devices.2.instances.0.commandClasses.128.data.history.100 = 1452036517 (0x568c51a5)
[2016-01-06 00:28:37.272] [D] [zway] SETDATA devices.2.instances.0.commandClasses.128.data.last = 100 (0x00000064)
[2016-01-06 00:28:37.341] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 98 40 2B )
[2016-01-06 00:28:37.342] [D] [zway] SENT ACK
[2016-01-06 00:28:37.342] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:37.342] [I] [zway] Node 2:0 CC Security: sending Nonce Report
[2016-01-06 00:28:37.342] [I] [zway] Adding job: Nonce Report
[2016-01-06 00:28:37.342] [D] [zway] SENDING (cb 0x59): ( 01 11 00 13 02 0A 98 80 71 82 C3 A1 E6 7B F8 53 05 59 16 )
[2016-01-06 00:28:37.351] [D] [zway] RECEIVED ACK
[2016-01-06 00:28:37.351] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-06 00:28:37.351] [D] [zway] SENT ACK
[2016-01-06 00:28:37.351] [D] [zway] Delivered to Z-Wave stack
[2016-01-06 00:28:37.371] [D] [zway] RECEIVED: ( 01 07 00 13 59 00 00 02 B0 )
[2016-01-06 00:28:37.371] [D] [zway] SENT ACK
[2016-01-06 00:28:37.371] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-06 00:28:37.371] [D] [zway] SETDATA devices.2.data.lastPacketInfo.delivered = True
[2016-01-06 00:28:37.371] [D] [zway] SETDATA devices.2.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-06 00:28:37.371] [D] [zway] SETDATA devices.2.data.lastPacketInfo.deliveryTime = 20 (0x00000014)
[2016-01-06 00:28:37.372] [D] [zway] SETDATA devices.2.data.lastPacketInfo = **********
[2016-01-06 00:28:37.372] [D] [zway] SendData Response with callback 0x59 received: received by recipient
[2016-01-06 00:28:37.372] [D] [zway] SETDATA devices.2.data.lastSend = 1145769 (0x00117ba9)
[2016-01-06 00:28:37.372] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-06 00:28:37.372] [I] [zway] Removing job: Nonce Report
[2016-01-06 00:28:37.391] [D] [zway] RECEIVED: ( 01 20 00 04 00 02 1A 98 81 5E 92 85 91 20 C0 A0 64 46 F1 A5 C8 4A 3D 99 71 A0 41 A6 83 04 02 6B 8B 

41 )
[2016-01-06 00:28:37.391] [D] [zway] SENT ACK
[2016-01-06 00:28:37.391] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:37.391] [I] [zway] Node 2:0 CC Security: Received a secure message
[2016-01-06 00:28:37.391] [D] [zway] SETDATA devices.2.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-06 00:28:37.391] [I] [zway] Node 2:0 CC Security: passing decrypted packet to application level: [ 31 05 03 0a 00 00 ]
[2016-01-06 00:28:37.392] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.3.deviceScale = 1 (0x00000001)
[2016-01-06 00:28:37.392] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.3.scale = 1 (0x00000001)
[2016-01-06 00:28:37.392] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.3.val = 0.000000
[2016-01-06 00:28:37.393] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.3.scaleString = "Lux"
[2016-01-06 00:28:37.393] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.3 = Empty
[2016-01-06 00:28:37.482] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 98 40 2B )
[2016-01-06 00:28:37.482] [D] [zway] SENT ACK
[2016-01-06 00:28:37.483] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:37.483] [I] [zway] Node 2:0 CC Security: sending Nonce Report
[2016-01-06 00:28:37.483] [I] [zway] Adding job: Nonce Report
[2016-01-06 00:28:37.483] [D] [zway] SENDING (cb 0x5a): ( 01 11 00 13 02 0A 98 80 AC 8C FD CD CD BA 70 94 05 5A 31 )
[2016-01-06 00:28:37.492] [D] [zway] RECEIVED ACK
[2016-01-06 00:28:37.493] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-06 00:28:37.493] [D] [zway] SENT ACK
[2016-01-06 00:28:37.493] [D] [zway] Delivered to Z-Wave stack
[2016-01-06 00:28:37.512] [D] [zway] RECEIVED: ( 01 07 00 13 5A 00 00 02 B3 )
[2016-01-06 00:28:37.512] [D] [zway] SENT ACK
[2016-01-06 00:28:37.512] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-06 00:28:37.512] [D] [zway] SETDATA devices.2.data.lastPacketInfo.delivered = True
[2016-01-06 00:28:37.512] [D] [zway] SETDATA devices.2.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-06 00:28:37.512] [D] [zway] SETDATA devices.2.data.lastPacketInfo.deliveryTime = 19 (0x00000013)
[2016-01-06 00:28:37.512] [D] [zway] SETDATA devices.2.data.lastPacketInfo = **********
[2016-01-06 00:28:37.512] [D] [zway] SendData Response with callback 0x5a received: received by recipient
[2016-01-06 00:28:37.513] [D] [zway] SETDATA devices.2.data.lastSend = 1145783 (0x00117bb7)
[2016-01-06 00:28:37.513] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-06 00:28:37.513] [I] [zway] Removing job: Nonce Report
[2016-01-06 00:28:37.532] [D] [zway] RECEIVED: ( 01 1F 00 04 00 02 19 98 81 BF 87 51 6C 15 58 1C CA 06 CB 3E A8 1A 37 AC 71 8E 91 37 10 7C 3D E6 4C 

)
[2016-01-06 00:28:37.532] [D] [zway] SENT ACK
[2016-01-06 00:28:37.532] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:37.532] [I] [zway] Node 2:0 CC Security: Received a secure message
[2016-01-06 00:28:37.532] [D] [zway] SETDATA devices.2.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-06 00:28:37.533] [I] [zway] Node 2:0 CC Security: passing decrypted packet to application level: [ 31 05 1b 01 00 ]
[2016-01-06 00:28:37.533] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.27.deviceScale = 0 (0x00000000)
[2016-01-06 00:28:37.533] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.27.scale = 0 (0x00000000)
[2016-01-06 00:28:37.533] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.27.val = 0.000000
[2016-01-06 00:28:37.534] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.27.scaleString = "UV index"
[2016-01-06 00:28:37.535] [D] [zway] SETDATA devices.2.instances.0.commandClasses.49.data.27 = Empty
[2016-01-06 00:28:37.583] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 )
[2016-01-06 00:28:37.583] [D] [zway] SENT ACK
[2016-01-06 00:28:37.583] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-06 00:28:37.583] [W] [zway] Got unsecure packet from device 2, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-06 00:28:55.950] [D] [zway] Job 0x13: deleted from queue
[2016-01-06 00:28:56.623] [D] [zway] Job 0x13: deleted from queue
[2016-01-06 00:28:57.652] [D] [zway] Job 0x13: deleted from queue
[2016-01-06 00:28:57.724] [D] [zway] Job 0x13: deleted from queue
[2016-01-06 00:28:57.816] [D] [zway] Job 0x13: deleted from queue
[2016-01-06 00:30:00.696] [I] [core] ---  ZWayVDev_zway_2-0-48-1 performCommand processing: {"0":"update"}

Can this really be pinned on the manufacturer? It's a bit strange that they alternate between sending secure/unsecure packages, or am I missing something?

Br,
Bo

[PoltoS]

Answered in another thread

[bogr]

Yepp, will try that suggestion. Thanx a lot!