When sending an average of 8-14 commands at once (packed into a scene) almost every time the not all devices process the request even though the UI show the request has been processed.
Example:
When turning off 8-14 dimmers/switches at once then UI almost every time does get updated correctly, some devices are shown as they remain "on" in the UI when in fact are "off" . The devices do support instants status report (Cooper RF9540) . but for some reason the Z-way dongle does not capture all the the status update report.
It feels as when many commands are sent at once lots of traffic is not captures by the z-way dongle.
Wondering if somebody else have experienced this and if they been able to overcome this problem?
Would it possible to add a couple milliseconds to the z-wave queue between commands being sent by the dongle?
Missing actions/status report when sending multiple requests at once
Re: Missing actions/status report when sending multiple requests at once
Looks like collisions. Can you show Z-Way log when commands are issues?
Re: Missing actions/status report when sending multiple requests at once
Yes, I have the same... Also can even make it happen on one device, just turn it on, off, adjust dimmer value, off, and on again and then status get's lost quicky... (not only in the "normal" GUI, but also in the "devices" view in advanced section
When looking in the queue, I can also see that events are just not delivered in time anymore...
Was already wondering on this longer time.... and maybe this has an impact ? :
* Log-level setting in "confg.xml" (I have it one "1" now, is that a high level of logging or a low one ?)
* Devicereplytimeout in "defaults.xml" (currently on two, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
* Devicerelaxdelay in "defaults.xml" (currently at three, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
* Maximium number of commands per multichannel (currently also at three, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
Maybe above settings can help tuning little bit better but I hope someone has a better explenation of how it hould be set an why (and if it helps, also for delay's)
When looking in the queue, I can also see that events are just not delivered in time anymore...
Was already wondering on this longer time.... and maybe this has an impact ? :
* Log-level setting in "confg.xml" (I have it one "1" now, is that a high level of logging or a low one ?)
* Devicereplytimeout in "defaults.xml" (currently on two, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
* Devicerelaxdelay in "defaults.xml" (currently at three, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
* Maximium number of commands per multichannel (currently also at three, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
Maybe above settings can help tuning little bit better but I hope someone has a better explenation of how it hould be set an why (and if it helps, also for delay's)
Re: Missing actions/status report when sending multiple requests at once
Code: Select all
[2021-04-14 21:31:34.157] [D] [zway] RECEIVED: ( 01 17 00 04 00 15 0E 32 02 21 74 00 0F F4 38 00 00 B3 BE 00 57 BE 00 00 B5 )
[2021-04-14 21:31:34.157] [D] [zway] SENT ACK
[2021-04-14 21:31:34.157] [D] [zway] SETDATA devices.21.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:34.158] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.2.val = 1045.560059
[2021-04-14 21:31:34.158] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2021-04-14 21:31:34.158] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2021-04-14 21:31:34.158] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.2 = Empty
[2021-04-14 21:31:34.167] [I] [core] HK: updated ZWayVDev_zway_21-0-50-2
[2021-04-14 21:31:34.234] [D] [zway] RECEIVED: ( 01 17 00 04 00 15 0E 32 02 21 64 00 27 3B 06 00 53 00 27 3A E3 BE 00 00 8B )
[2021-04-14 21:31:34.234] [D] [zway] SENT ACK
[2021-04-14 21:31:34.235] [D] [zway] SETDATA devices.21.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:34.235] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.0.val = 2571.013916
[2021-04-14 21:31:34.235] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.0.delta = 83 (0x00000053)
[2021-04-14 21:31:34.235] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.0.ratetype = 1 (0x00000001)
[2021-04-14 21:31:34.235] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.0.previous = 2570.979004
[2021-04-14 21:31:34.235] [D] [zway] SETDATA devices.21.instances.0.commandClasses.50.data.0 = Empty
[2021-04-14 21:31:34.239] [I] [core] HK: updated ZWayVDev_zway_21-0-50-0
[2021-04-14 21:31:35.039] [I] [core] --- Scenes_16 performCommand processing: {"0":"on","1":{}}
[2021-04-14 21:31:35.040] [I] [core] --- ZWayVDev_zway_2-0-38 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.040] [I] [zway] Adding job: SwitchMultilevel Set
[2021-04-14 21:31:35.041] [I] [zway] Adding job: SwitchMultilevel Get
[2021-04-14 21:31:35.041] [I] [core] --- ZWayVDev_zway_7-0-38 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.041] [I] [zway] Adding job: SwitchMultilevel Set
[2021-04-14 21:31:35.041] [I] [zway] Adding job: SwitchMultilevel Get
[2021-04-14 21:31:35.042] [I] [core] --- ZWayVDev_zway_14-0-37 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.042] [I] [zway] Adding job: SwitchBinary Set
[2021-04-14 21:31:35.042] [I] [zway] Adding job: SwitchBinary Get
[2021-04-14 21:31:35.042] [I] [core] --- ZWayVDev_zway_15-0-37 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.042] [I] [zway] Adding job: SwitchBinary Set
[2021-04-14 21:31:35.043] [I] [zway] Adding job: SwitchBinary Get
[2021-04-14 21:31:35.043] [I] [core] --- ZWayVDev_zway_18-0-38 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.043] [I] [zway] Adding job: SwitchMultilevel Set
[2021-04-14 21:31:35.044] [I] [zway] Adding job: SwitchMultilevel Get
[2021-04-14 21:31:35.044] [I] [core] --- ZWayVDev_zway_23-0-38 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.045] [I] [zway] Adding job: SwitchMultilevel Set
[2021-04-14 21:31:35.045] [I] [zway] Adding job: SwitchMultilevel Get
[2021-04-14 21:31:35.045] [I] [core] --- ZWayVDev_zway_33-0-38 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.045] [I] [zway] Adding job: SwitchMultilevel Set
[2021-04-14 21:31:35.046] [I] [zway] Adding job: SwitchMultilevel Get
[2021-04-14 21:31:35.046] [I] [core] --- ZWayVDev_zway_59-0-37 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.046] [I] [zway] Adding job: SwitchBinary Set
[2021-04-14 21:31:35.046] [I] [zway] Adding job: SwitchBinary Get
[2021-04-14 21:31:35.046] [I] [core] --- ZWayVDev_zway_39-0-37 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.046] [I] [zway] Adding job: SwitchBinary Set
[2021-04-14 21:31:35.046] [I] [zway] Adding job: SwitchBinary Get
[2021-04-14 21:31:35.047] [I] [core] --- ZWayVDev_zway_41-0-37 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.047] [I] [zway] Adding job: SwitchBinary Set
[2021-04-14 21:31:35.047] [I] [zway] Adding job: SwitchBinary Get
[2021-04-14 21:31:35.047] [I] [core] --- ZWayVDev_zway_42-0-37 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.047] [I] [zway] Adding job: SwitchBinary Set
[2021-04-14 21:31:35.047] [I] [zway] Adding job: SwitchBinary Get
[2021-04-14 21:31:35.047] [I] [core] --- ZWayVDev_zway_43-0-37 performCommand processing: {"0":"off"}
[2021-04-14 21:31:35.048] [I] [zway] Adding job: SwitchBinary Set
[2021-04-14 21:31:35.048] [I] [zway] Adding job: SwitchBinary Get
[2021-04-14 21:31:35.050] [I] [core] Notification: device-info (device-OnOff): {"dev":"All Lights OFF Test","l":"on","location":0,"customIcon":false}
[2021-04-14 21:31:35.050] [D] [zway] SENDING (cb 0xe3): ( 01 0B 00 13 02 04 26 01 00 FF 25 E3 FF )
[2021-04-14 21:31:35.051] [I] [core] HK: updated Scenes_16
[2021-04-14 21:31:35.052] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:35.061] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:35.061] [D] [zway] SENT ACK
[2021-04-14 21:31:35.062] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:35.088] [D] [zway] RECEIVED: ( 01 18 00 13 E3 00 00 02 00 BB 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 AE )
[2021-04-14 21:31:35.088] [D] [zway] SENT ACK
[2021-04-14 21:31:35.088] [I] [zway] Job 0x13 (SwitchMultilevel Set): Delivered
[2021-04-14 21:31:35.088] [D] [zway] SendData Response with callback 0xe3 received: received by recipient
[2021-04-14 21:31:35.088] [D] [zway] SETDATA devices.2.data.lastSendInternal = **********
[2021-04-14 21:31:35.088] [D] [zway] SETDATA devices.2.data.lastSend = 27052036 (0x019cc804)
[2021-04-14 21:31:35.088] [D] [zway] Job 0x13 (SwitchMultilevel Set): success
[2021-04-14 21:31:35.088] [I] [zway] Removing job: SwitchMultilevel Set
[2021-04-14 21:31:35.089] [D] [zway] SENDING (cb 0xe4): ( 01 0B 00 13 07 04 26 01 00 FF 25 E4 FD )
[2021-04-14 21:31:35.091] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:35.099] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:35.099] [D] [zway] SENT ACK
[2021-04-14 21:31:35.099] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:35.221] [D] [zway] RECEIVED: ( 01 18 00 13 E4 00 00 0C 04 B8 7F 7F 7F 7F 01 01 03 30 32 2C 41 02 01 00 00 CF )
[2021-04-14 21:31:35.221] [D] [zway] SENT ACK
[2021-04-14 21:31:35.221] [I] [zway] Job 0x13 (SwitchMultilevel Set): Delivered
[2021-04-14 21:31:35.221] [D] [zway] SendData Response with callback 0xe4 received: received by recipient
[2021-04-14 21:31:35.221] [D] [zway] SETDATA devices.7.data.lastSendInternal = **********
[2021-04-14 21:31:35.221] [D] [zway] SETDATA devices.7.data.lastSend = 27052050 (0x019cc812)
[2021-04-14 21:31:35.221] [D] [zway] Job 0x13 (SwitchMultilevel Set): success
[2021-04-14 21:31:35.221] [I] [zway] Removing job: SwitchMultilevel Set
[2021-04-14 21:31:35.222] [D] [zway] SENDING (cb 0xe5): ( 01 09 00 13 02 02 26 02 25 E5 01 )
[2021-04-14 21:31:35.224] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:35.232] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:35.233] [D] [zway] SENT ACK
[2021-04-14 21:31:35.233] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:35.259] [D] [zway] RECEIVED: ( 01 18 00 13 E5 00 00 02 00 BB 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 A8 )
[2021-04-14 21:31:35.259] [D] [zway] SENT ACK
[2021-04-14 21:31:35.259] [I] [zway] Job 0x13 (SwitchMultilevel Get): Delivered
[2021-04-14 21:31:35.259] [D] [zway] SendData Response with callback 0xe5 received: received by recipient
[2021-04-14 21:31:35.260] [D] [zway] SETDATA devices.2.data.lastSendInternal = **********
[2021-04-14 21:31:35.260] [D] [zway] SETDATA devices.2.data.lastSend = 27052055 (0x019cc817)
[2021-04-14 21:31:35.260] [D] [zway] Job 0x13 (SwitchMultilevel Get): success
[2021-04-14 21:31:35.260] [I] [zway] Waiting for job reply: SwitchMultilevel Get
[2021-04-14 21:31:35.260] [D] [zway] SENDING (cb 0xe6): ( 01 09 00 13 07 02 26 02 25 E6 07 )
[2021-04-14 21:31:35.264] [W] [zway] Received SOF, while awaiting ACK
[2021-04-14 21:31:35.270] [D] [zway] RECEIVED: ( 01 0C 00 04 00 02 03 26 03 2E BB 00 00 46 )
[2021-04-14 21:31:35.270] [D] [zway] SENT ACK
[2021-04-14 21:31:35.271] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:35.271] [D] [zway] Received reply on job (SwitchMultilevel Get)
[2021-04-14 21:31:35.271] [D] [zway] SETDATA devices.2.instances.0.commandClasses.38.data.level = 46 (0x0000002e)
[2021-04-14 21:31:35.274] [D] [zway] RECEIVED CAN
[2021-04-14 21:31:35.285] [D] [zway] SENDING (cb 0xe7): ( 01 09 00 13 07 02 26 02 25 E7 06 )
[2021-04-14 21:31:35.287] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:35.295] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:35.295] [D] [zway] SENT ACK
[2021-04-14 21:31:35.295] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:35.340] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (#2)","l":"46%","location":0}
[2021-04-14 21:31:35.341] [I] [core] HK: updated ZWayVDev_zway_2-0-38
[2021-04-14 21:31:35.406] [D] [zway] RECEIVED: ( 01 18 00 13 E7 00 00 0B 04 B6 7F 7F 7F 7F 01 01 03 30 32 2C 41 02 01 00 00 C5 )
[2021-04-14 21:31:35.406] [D] [zway] SENT ACK
[2021-04-14 21:31:35.406] [I] [zway] Job 0x13 (SwitchMultilevel Get): Delivered
[2021-04-14 21:31:35.406] [D] [zway] SendData Response with callback 0xe7 received: received by recipient
[2021-04-14 21:31:35.406] [D] [zway] SETDATA devices.7.data.lastSendInternal = **********
[2021-04-14 21:31:35.407] [D] [zway] SETDATA devices.7.data.lastSend = 27052071 (0x019cc827)
[2021-04-14 21:31:35.407] [D] [zway] Job 0x13 (SwitchMultilevel Get): success
[2021-04-14 21:31:35.407] [I] [zway] Waiting for job reply: SwitchMultilevel Get
[2021-04-14 21:31:35.407] [D] [zway] SENDING (cb 0xe8): ( 01 0A 00 13 0E 03 25 01 00 25 E8 02 )
[2021-04-14 21:31:35.409] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:35.417] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:35.417] [D] [zway] SENT ACK
[2021-04-14 21:31:35.417] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:35.645] [D] [zway] RECEIVED: ( 01 10 00 04 00 07 03 26 03 2B B8 00 04 41 2C 32 30 32 )
[2021-04-14 21:31:35.646] [D] [zway] SENT ACK
[2021-04-14 21:31:35.646] [D] [zway] SETDATA devices.7.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:35.646] [D] [zway] Received reply on job (SwitchMultilevel Get)
[2021-04-14 21:31:35.646] [D] [zway] SETDATA devices.7.instances.0.commandClasses.38.data.level = 43 (0x0000002b)
[2021-04-14 21:31:35.656] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (#7)","l":"43%","location":0}
[2021-04-14 21:31:35.656] [I] [core] HK: updated ZWayVDev_zway_7-0-38
[2021-04-14 21:31:35.675] [D] [zway] RECEIVED: ( 01 10 00 04 00 07 03 26 03 2B B8 00 04 41 2C 32 30 32 )
[2021-04-14 21:31:35.675] [D] [zway] SENT ACK
[2021-04-14 21:31:35.675] [D] [zway] SETDATA devices.7.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:35.675] [D] [zway] SETDATA devices.7.instances.0.commandClasses.38.data.level = 43 (0x0000002b)
[2021-04-14 21:31:35.686] [I] [core] HK: updated ZWayVDev_zway_7-0-38
[2021-04-14 21:31:35.701] [D] [zway] RECEIVED: ( 01 10 00 04 00 07 03 26 03 2B B6 00 04 41 2C 32 30 3C )
[2021-04-14 21:31:35.701] [D] [zway] SENT ACK
[2021-04-14 21:31:35.701] [D] [zway] SETDATA devices.7.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:35.701] [D] [zway] SETDATA devices.7.instances.0.commandClasses.38.data.level = 43 (0x0000002b)
[2021-04-14 21:31:35.716] [I] [core] HK: updated ZWayVDev_zway_7-0-38
[2021-04-14 21:31:35.788] [D] [zway] RECEIVED: ( 01 18 00 13 E8 00 00 25 01 BC 7F 7F 7F 7F 01 01 03 37 00 00 00 01 01 00 00 B0 )
[2021-04-14 21:31:35.788] [D] [zway] SENT ACK
[2021-04-14 21:31:35.788] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2021-04-14 21:31:35.788] [D] [zway] SendData Response with callback 0xe8 received: received by recipient
[2021-04-14 21:31:35.788] [D] [zway] SETDATA devices.14.data.lastSendInternal = **********
[2021-04-14 21:31:35.788] [D] [zway] SETDATA devices.14.data.lastSend = 27052110 (0x019cc84e)
[2021-04-14 21:31:35.788] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2021-04-14 21:31:35.788] [I] [zway] Removing job: SwitchBinary Set
[2021-04-14 21:31:35.789] [D] [zway] SENDING (cb 0xe9): ( 01 0A 00 13 0F 03 25 01 00 25 E9 02 )
[2021-04-14 21:31:35.794] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:35.799] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:35.799] [D] [zway] SENT ACK
[2021-04-14 21:31:35.799] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:35.837] [D] [zway] RECEIVED: ( 01 18 00 13 E9 00 00 04 00 BF 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 A6 )
[2021-04-14 21:31:35.838] [D] [zway] SENT ACK
[2021-04-14 21:31:35.838] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2021-04-14 21:31:35.838] [D] [zway] SendData Response with callback 0xe9 received: received by recipient
[2021-04-14 21:31:35.838] [D] [zway] SETDATA devices.15.data.lastSendInternal = **********
[2021-04-14 21:31:35.838] [D] [zway] SETDATA devices.15.data.lastSend = 27052116 (0x019cc854)
[2021-04-14 21:31:35.838] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2021-04-14 21:31:35.838] [I] [zway] Removing job: SwitchBinary Set
[2021-04-14 21:31:35.839] [D] [zway] SENDING (cb 0xea): ( 01 09 00 13 0E 02 25 02 25 EA 01 )
[2021-04-14 21:31:35.843] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:35.849] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:35.850] [D] [zway] SENT ACK
[2021-04-14 21:31:35.850] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:36.076] [D] [zway] RECEIVED: ( 01 18 00 13 EA 00 00 17 01 BB 7F 7F 7F 7F 01 01 03 37 00 00 00 01 01 00 00 87 )
[2021-04-14 21:31:36.076] [D] [zway] SENT ACK
[2021-04-14 21:31:36.076] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2021-04-14 21:31:36.076] [D] [zway] SendData Response with callback 0xea received: received by recipient
[2021-04-14 21:31:36.076] [D] [zway] SETDATA devices.14.data.lastSendInternal = **********
[2021-04-14 21:31:36.076] [D] [zway] SETDATA devices.14.data.lastSend = 27052140 (0x019cc86c)
[2021-04-14 21:31:36.076] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2021-04-14 21:31:36.076] [I] [zway] Waiting for job reply: SwitchBinary Get
[2021-04-14 21:31:36.076] [D] [zway] SENDING (cb 0xeb): ( 01 09 00 13 0F 02 25 02 25 EB 01 )
[2021-04-14 21:31:36.083] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:36.083] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:36.083] [D] [zway] SENT ACK
[2021-04-14 21:31:36.084] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:36.127] [D] [zway] RECEIVED: ( 01 18 00 13 EB 00 00 04 00 C3 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 D8 )
[2021-04-14 21:31:36.127] [D] [zway] SENT ACK
[2021-04-14 21:31:36.127] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2021-04-14 21:31:36.127] [D] [zway] SendData Response with callback 0xeb received: received by recipient
[2021-04-14 21:31:36.127] [D] [zway] SETDATA devices.15.data.lastSendInternal = **********
[2021-04-14 21:31:36.127] [D] [zway] SETDATA devices.15.data.lastSend = 27052146 (0x019cc872)
[2021-04-14 21:31:36.127] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2021-04-14 21:31:36.128] [I] [zway] Waiting for job reply: SwitchBinary Get
[2021-04-14 21:31:36.128] [D] [zway] SENDING (cb 0xec): ( 01 0B 00 13 12 04 26 01 00 FF 25 EC E0 )
[2021-04-14 21:31:36.132] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:36.138] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:36.138] [D] [zway] SENT ACK
[2021-04-14 21:31:36.138] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:36.208] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0E 03 25 03 00 BC 00 01 37 57 )
[2021-04-14 21:31:36.208] [D] [zway] SENT ACK
[2021-04-14 21:31:36.209] [D] [zway] SETDATA devices.14.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:36.209] [D] [zway] Received reply on job (SwitchBinary Get)
[2021-04-14 21:31:36.209] [D] [zway] SETDATA devices.14.instances.0.commandClasses.37.data.level = False
[2021-04-14 21:31:36.219] [I] [core] Notification: device-info (device-OnOff): {"dev":"Goap Switch (14.0)","l":"off","location":0}
[2021-04-14 21:31:36.220] [I] [core] HK: updated ZWayVDev_zway_14-0-37
[2021-04-14 21:31:36.221] [D] [zway] RECEIVED: ( 01 0C 00 04 00 0F 03 25 03 00 C3 00 00 1E )
[2021-04-14 21:31:36.221] [D] [zway] SENT ACK
[2021-04-14 21:31:36.221] [D] [zway] SETDATA devices.15.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:36.221] [D] [zway] Received reply on job (SwitchBinary Get)
[2021-04-14 21:31:36.221] [D] [zway] SETDATA devices.15.instances.0.commandClasses.37.data.level = False
[2021-04-14 21:31:36.253] [I] [core] Notification: device-info (device-OnOff): {"dev":"Goap Switch (15.0)","l":"off","location":0}
[2021-04-14 21:31:36.254] [I] [core] HK: updated ZWayVDev_zway_15-0-37
[2021-04-14 21:31:36.459] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0E 03 25 03 00 BC 00 01 37 57 )
[2021-04-14 21:31:36.459] [D] [zway] SENT ACK
[2021-04-14 21:31:36.459] [D] [zway] SETDATA devices.14.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:36.459] [D] [zway] SETDATA devices.14.instances.0.commandClasses.37.data.level = False
[2021-04-14 21:31:36.463] [I] [core] HK: updated ZWayVDev_zway_14-0-37
[2021-04-14 21:31:36.703] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0E 03 25 03 00 BC 00 01 37 57 )
[2021-04-14 21:31:36.703] [D] [zway] SENT ACK
[2021-04-14 21:31:36.703] [D] [zway] SETDATA devices.14.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:36.703] [D] [zway] SETDATA devices.14.instances.0.commandClasses.37.data.level = False
[2021-04-14 21:31:36.708] [I] [core] HK: updated ZWayVDev_zway_14-0-37
[2021-04-14 21:31:36.936] [D] [zway] RECEIVED: ( 01 18 00 13 EC 00 00 50 04 C9 7F 7F 7F 7F 01 01 04 2A 28 37 17 02 02 00 00 A3 )
[2021-04-14 21:31:36.937] [D] [zway] SENT ACK
[2021-04-14 21:31:36.937] [I] [zway] Job 0x13 (SwitchMultilevel Set): Delivered
[2021-04-14 21:31:36.937] [D] [zway] SendData Response with callback 0xec received: received by recipient
[2021-04-14 21:31:36.937] [D] [zway] SETDATA devices.18.data.lastSendInternal = **********
[2021-04-14 21:31:36.937] [D] [zway] SETDATA devices.18.data.lastSend = 27052227 (0x019cc8c3)
[2021-04-14 21:31:36.937] [D] [zway] Job 0x13 (SwitchMultilevel Set): success
[2021-04-14 21:31:36.937] [I] [zway] Removing job: SwitchMultilevel Set
[2021-04-14 21:31:36.938] [D] [zway] SENDING (cb 0xed): ( 01 0B 00 13 17 04 26 01 00 FF 25 ED E4 )
[2021-04-14 21:31:36.944] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:36.948] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:36.948] [D] [zway] SENT ACK
[2021-04-14 21:31:36.948] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:37.174] [D] [zway] RECEIVED: ( 01 18 00 13 ED 00 00 16 04 C7 7F 7F 7F 7F 01 01 03 2A 07 02 41 02 01 00 00 A2 )
[2021-04-14 21:31:37.174] [D] [zway] SENT ACK
[2021-04-14 21:31:37.174] [I] [zway] Job 0x13 (SwitchMultilevel Set): Delivered
[2021-04-14 21:31:37.174] [D] [zway] SendData Response with callback 0xed received: received by recipient
[2021-04-14 21:31:37.174] [D] [zway] SETDATA devices.23.data.lastSendInternal = **********
[2021-04-14 21:31:37.175] [D] [zway] SETDATA devices.23.data.lastSend = 27052251 (0x019cc8db)
[2021-04-14 21:31:37.175] [D] [zway] Job 0x13 (SwitchMultilevel Set): success
[2021-04-14 21:31:37.175] [I] [zway] Removing job: SwitchMultilevel Set
[2021-04-14 21:31:37.175] [D] [zway] SENDING (cb 0xee): ( 01 09 00 13 12 02 26 02 25 EE 1A )
[2021-04-14 21:31:37.180] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:37.185] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:37.185] [D] [zway] SENT ACK
[2021-04-14 21:31:37.186] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:37.328] [I] [zway] Adding job: SwitchMultilevel Get
[2021-04-14 21:31:37.369] [D] [zway] RECEIVED: ( 01 18 00 13 EE 00 00 13 04 C7 7F 7F 7F 7F 01 01 03 2A 28 37 17 02 01 00 00 E8 )
[2021-04-14 21:31:37.369] [D] [zway] SENT ACK
[2021-04-14 21:31:37.369] [I] [zway] Job 0x13 (SwitchMultilevel Get): Delivered
[2021-04-14 21:31:37.370] [D] [zway] SendData Response with callback 0xee received: received by recipient
[2021-04-14 21:31:37.370] [D] [zway] SETDATA devices.18.data.lastSendInternal = **********
[2021-04-14 21:31:37.370] [D] [zway] SETDATA devices.18.data.lastSend = 27052271 (0x019cc8ef)
[2021-04-14 21:31:37.370] [D] [zway] Job 0x13 (SwitchMultilevel Get): success
[2021-04-14 21:31:37.370] [I] [zway] Waiting for job reply: SwitchMultilevel Get
[2021-04-14 21:31:37.370] [D] [zway] SENDING (cb 0xef): ( 01 09 00 13 17 02 26 02 25 EF 1E )
[2021-04-14 21:31:37.375] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:37.380] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:37.380] [D] [zway] SENT ACK
[2021-04-14 21:31:37.380] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:37.419] [D] [zway] Job 0x13: deleted from queue
[2021-04-14 21:31:37.565] [D] [zway] RECEIVED: ( 01 18 00 13 EF 00 00 12 04 C9 7F 7F 7F 7F 01 01 03 2A 07 02 41 02 01 00 00 AA )
[2021-04-14 21:31:37.565] [D] [zway] SENT ACK
[2021-04-14 21:31:37.565] [I] [zway] Job 0x13 (SwitchMultilevel Get): Delivered
[2021-04-14 21:31:37.566] [D] [zway] SendData Response with callback 0xef received: received by recipient
[2021-04-14 21:31:37.566] [D] [zway] SETDATA devices.23.data.lastSendInternal = **********
[2021-04-14 21:31:37.566] [D] [zway] SETDATA devices.23.data.lastSend = 27052291 (0x019cc903)
[2021-04-14 21:31:37.566] [D] [zway] Job 0x13 (SwitchMultilevel Get): success
[2021-04-14 21:31:37.566] [I] [zway] Waiting for job reply: SwitchMultilevel Get
[2021-04-14 21:31:37.566] [D] [zway] Job 0x13: deleted from queue
[2021-04-14 21:31:37.566] [D] [zway] SENDING (cb 0xf0): ( 01 0B 00 13 21 04 26 01 00 FF 25 F0 CF )
[2021-04-14 21:31:37.568] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:37.576] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:37.576] [D] [zway] SENT ACK
[2021-04-14 21:31:37.576] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:37.667] [D] [zway] RECEIVED: ( 01 10 00 04 00 17 03 26 03 27 C9 00 04 41 02 07 2A 5E )
[2021-04-14 21:31:37.667] [D] [zway] SENT ACK
[2021-04-14 21:31:37.667] [D] [zway] SETDATA devices.23.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:37.667] [D] [zway] Received reply on job (SwitchMultilevel Get)
[2021-04-14 21:31:37.668] [D] [zway] SETDATA devices.23.instances.0.commandClasses.38.data.level = 39 (0x00000027)
[2021-04-14 21:31:37.670] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (23.0)","l":"39%","location":0}
[2021-04-14 21:31:37.670] [I] [core] HK: updated ZWayVDev_zway_23-0-38
[2021-04-14 21:31:37.688] [D] [zway] RECEIVED: ( 01 10 00 04 00 17 03 26 03 27 C7 00 04 41 02 07 2A 50 )
[2021-04-14 21:31:37.688] [D] [zway] SENT ACK
[2021-04-14 21:31:37.688] [D] [zway] SETDATA devices.23.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:37.688] [D] [zway] SETDATA devices.23.instances.0.commandClasses.38.data.level = 39 (0x00000027)
[2021-04-14 21:31:37.702] [I] [core] HK: updated ZWayVDev_zway_23-0-38
[2021-04-14 21:31:37.737] [D] [zway] RECEIVED: ( 01 18 00 13 F0 00 00 10 02 C1 7F 7F 7F 7F 01 01 03 28 3A 00 00 02 01 00 00 C5 )
[2021-04-14 21:31:37.737] [D] [zway] SENT ACK
[2021-04-14 21:31:37.737] [I] [zway] Job 0x13 (SwitchMultilevel Set): Delivered
[2021-04-14 21:31:37.737] [D] [zway] SendData Response with callback 0xf0 received: received by recipient
[2021-04-14 21:31:37.737] [D] [zway] SETDATA devices.33.data.lastSendInternal = **********
[2021-04-14 21:31:37.737] [D] [zway] SETDATA devices.33.data.lastSend = 27052310 (0x019cc916)
[2021-04-14 21:31:37.737] [D] [zway] Job 0x13 (SwitchMultilevel Set): success
[2021-04-14 21:31:37.737] [I] [zway] Removing job: SwitchMultilevel Set
[2021-04-14 21:31:37.738] [D] [zway] SENDING (cb 0xf1): ( 01 0A 00 13 3B 03 25 01 00 25 F1 2E )
[2021-04-14 21:31:37.739] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:37.748] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:37.748] [D] [zway] SENT ACK
[2021-04-14 21:31:37.748] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:37.757] [D] [zway] RECEIVED: ( 01 10 00 04 00 17 03 26 03 27 C9 00 04 41 02 07 2A 5E )
[2021-04-14 21:31:37.757] [D] [zway] SENT ACK
[2021-04-14 21:31:37.757] [D] [zway] SETDATA devices.23.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:37.757] [D] [zway] SETDATA devices.23.instances.0.commandClasses.38.data.level = 39 (0x00000027)
[2021-04-14 21:31:37.764] [I] [core] HK: updated ZWayVDev_zway_23-0-38
[2021-04-14 21:31:37.799] [D] [zway] RECEIVED: ( 01 18 00 13 F1 00 00 04 01 C4 7F 7F 7F 7F 01 01 03 33 00 00 00 02 01 00 00 F7 )
[2021-04-14 21:31:37.799] [D] [zway] SENT ACK
[2021-04-14 21:31:37.799] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2021-04-14 21:31:37.799] [D] [zway] SendData Response with callback 0xf1 received: received by recipient
[2021-04-14 21:31:37.799] [D] [zway] SETDATA devices.59.data.lastSendInternal = **********
[2021-04-14 21:31:37.799] [D] [zway] SETDATA devices.59.data.lastSend = 27052318 (0x019cc91e)
[2021-04-14 21:31:37.799] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2021-04-14 21:31:37.799] [I] [zway] Removing job: SwitchBinary Set
[2021-04-14 21:31:37.800] [D] [zway] SENDING (cb 0xf2): ( 01 09 00 13 21 02 26 02 25 F2 35 )
[2021-04-14 21:31:37.801] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:37.810] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:37.810] [D] [zway] SENT ACK
[2021-04-14 21:31:37.810] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:37.849] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BB 00 00 4C )
[2021-04-14 21:31:37.849] [D] [zway] SENT ACK
[2021-04-14 21:31:37.849] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:37.849] [D] [zway] Received reply on job (SwitchMultilevel Get)
[2021-04-14 21:31:37.849] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:37.855] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (18.0)","l":"36%","location":0}
[2021-04-14 21:31:37.856] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:37.949] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 B8 00 00 4F )
[2021-04-14 21:31:37.949] [D] [zway] SENT ACK
[2021-04-14 21:31:37.949] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:37.949] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:37.951] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.019] [D] [zway] RECEIVED: ( 01 18 00 13 F2 00 00 14 02 C1 7F 7F 7F 7F 01 01 03 28 3A 00 00 02 01 00 00 C3 )
[2021-04-14 21:31:38.019] [D] [zway] SENT ACK
[2021-04-14 21:31:38.019] [I] [zway] Job 0x13 (SwitchMultilevel Get): Delivered
[2021-04-14 21:31:38.019] [D] [zway] SendData Response with callback 0xf2 received: received by recipient
[2021-04-14 21:31:38.020] [D] [zway] SETDATA devices.33.data.lastSendInternal = **********
[2021-04-14 21:31:38.020] [D] [zway] SETDATA devices.33.data.lastSend = 27052341 (0x019cc935)
[2021-04-14 21:31:38.020] [D] [zway] Job 0x13 (SwitchMultilevel Get): success
[2021-04-14 21:31:38.020] [I] [zway] Waiting for job reply: SwitchMultilevel Get
[2021-04-14 21:31:38.020] [D] [zway] SENDING (cb 0xf3): ( 01 09 00 13 3B 02 25 02 25 F3 2D )
[2021-04-14 21:31:38.021] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:38.030] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:38.030] [D] [zway] SENT ACK
[2021-04-14 21:31:38.030] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:38.141] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BB 00 00 4C )
[2021-04-14 21:31:38.141] [D] [zway] SENT ACK
[2021-04-14 21:31:38.141] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.141] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.147] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.178] [D] [zway] RECEIVED: ( 01 18 00 13 F3 00 00 0F 01 C4 7F 7F 7F 7F 01 01 03 33 00 00 00 02 02 00 00 FD )
[2021-04-14 21:31:38.178] [D] [zway] SENT ACK
[2021-04-14 21:31:38.178] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2021-04-14 21:31:38.178] [D] [zway] SendData Response with callback 0xf3 received: received by recipient
[2021-04-14 21:31:38.178] [D] [zway] SETDATA devices.59.data.lastSendInternal = **********
[2021-04-14 21:31:38.178] [D] [zway] SETDATA devices.59.data.lastSend = 27052358 (0x019cc946)
[2021-04-14 21:31:38.178] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2021-04-14 21:31:38.178] [I] [zway] Waiting for job reply: SwitchBinary Get
[2021-04-14 21:31:38.178] [D] [zway] SENDING (cb 0xf4): ( 01 0A 00 13 27 03 25 01 00 25 F4 37 )
[2021-04-14 21:31:38.186] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:38.188] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:38.189] [D] [zway] SENT ACK
[2021-04-14 21:31:38.189] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:38.229] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 B5 00 00 42 )
[2021-04-14 21:31:38.229] [D] [zway] SENT ACK
[2021-04-14 21:31:38.229] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.229] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.233] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.250] [D] [zway] Job 0x13: deleted from queue
[2021-04-14 21:31:38.251] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BB 00 00 4C )
[2021-04-14 21:31:38.251] [D] [zway] SENT ACK
[2021-04-14 21:31:38.251] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.251] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.265] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.271] [D] [zway] RECEIVED: ( 01 18 00 13 F4 00 00 08 00 C1 7F 7F 7F 7F 00 00 03 00 00 00 00 03 02 00 00 CB )
[2021-04-14 21:31:38.272] [D] [zway] SENT ACK
[2021-04-14 21:31:38.272] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2021-04-14 21:31:38.272] [D] [zway] SendData Response with callback 0xf4 received: received by recipient
[2021-04-14 21:31:38.272] [D] [zway] SETDATA devices.39.data.lastSendInternal = **********
[2021-04-14 21:31:38.272] [D] [zway] SETDATA devices.39.data.lastSend = 27052370 (0x019cc952)
[2021-04-14 21:31:38.272] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2021-04-14 21:31:38.272] [I] [zway] Removing job: SwitchBinary Set
[2021-04-14 21:31:38.272] [D] [zway] SENDING (cb 0xf5): ( 01 0A 00 13 29 03 25 01 00 25 F5 38 )
[2021-04-14 21:31:38.281] [W] [zway] Received SOF, while awaiting ACK
[2021-04-14 21:31:38.283] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 C4 00 00 33 )
[2021-04-14 21:31:38.283] [D] [zway] SENT ACK
[2021-04-14 21:31:38.283] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.283] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.293] [D] [zway] RECEIVED CAN
[2021-04-14 21:31:38.298] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.303] [D] [zway] Job 0x13: deleted from queue
[2021-04-14 21:31:38.303] [D] [zway] SENDING (cb 0xf6): ( 01 09 00 13 27 02 25 02 25 F6 34 )
[2021-04-14 21:31:38.305] [D] [zway] RECEIVED ACK
[2021-04-14 21:31:38.314] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2021-04-14 21:31:38.314] [D] [zway] SENT ACK
[2021-04-14 21:31:38.314] [D] [zway] Delivered to Z-Wave stack
[2021-04-14 21:31:38.334] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BE 00 00 49 )
[2021-04-14 21:31:38.334] [D] [zway] SENT ACK
[2021-04-14 21:31:38.335] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.335] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.338] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.427] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 B1 00 00 46 )
[2021-04-14 21:31:38.427] [D] [zway] SENT ACK
[2021-04-14 21:31:38.427] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.428] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.430] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BF 00 00 48 )
[2021-04-14 21:31:38.430] [D] [zway] SENT ACK
[2021-04-14 21:31:38.430] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.431] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.431] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.463] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.479] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 B5 00 00 42 )
[2021-04-14 21:31:38.479] [D] [zway] SENT ACK
[2021-04-14 21:31:38.479] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.480] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.491] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 B9 00 00 4E )
[2021-04-14 21:31:38.491] [D] [zway] SENT ACK
[2021-04-14 21:31:38.491] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.491] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.495] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.502] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BB 00 00 4C )
[2021-04-14 21:31:38.502] [D] [zway] SENT ACK
[2021-04-14 21:31:38.502] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.503] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.503] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 B8 00 00 4F )
[2021-04-14 21:31:38.504] [D] [zway] SENT ACK
[2021-04-14 21:31:38.504] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.504] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.506] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.545] [D] [zway] RECEIVED: ( 01 0C 00 04 00 27 03 25 03 00 C7 00 00 32 )
[2021-04-14 21:31:38.546] [D] [zway] SENT ACK
[2021-04-14 21:31:38.546] [D] [zway] SETDATA devices.39.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.546] [D] [zway] SETDATA devices.39.instances.0.commandClasses.37.data.level = False
[2021-04-14 21:31:38.548] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.556] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 26 BB 00 00 4E )
[2021-04-14 21:31:38.556] [D] [zway] SENT ACK
[2021-04-14 21:31:38.556] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.557] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.558] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (18.0)","l":"38%","location":0}
[2021-04-14 21:31:38.558] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.567] [I] [core] HK: updated ZWayVDev_zway_39-0-37
[2021-04-14 21:31:38.601] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.612] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BE 00 00 49 )
[2021-04-14 21:31:38.612] [D] [zway] SENT ACK
[2021-04-14 21:31:38.613] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.613] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.632] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (18.0)","l":"36%","location":0}
[2021-04-14 21:31:38.632] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.660] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 B8 00 00 4F )
[2021-04-14 21:31:38.660] [D] [zway] SENT ACK
[2021-04-14 21:31:38.660] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.660] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.662] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.684] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 26 B0 00 00 45 )
[2021-04-14 21:31:38.685] [D] [zway] SENT ACK
[2021-04-14 21:31:38.685] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.685] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.692] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (18.0)","l":"38%","location":0}
[2021-04-14 21:31:38.692] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.713] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 BC 00 00 7A )
[2021-04-14 21:31:38.713] [D] [zway] SENT ACK
[2021-04-14 21:31:38.713] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.713] [D] [zway] Received reply on job (SwitchMultilevel Get)
[2021-04-14 21:31:38.713] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.722] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (#33)","l":"38%","location":0}
[2021-04-14 21:31:38.722] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:38.725] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 26 B9 00 00 4C )
[2021-04-14 21:31:38.725] [D] [zway] SENT ACK
[2021-04-14 21:31:38.725] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.725] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.752] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.777] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 C1 00 00 07 )
[2021-04-14 21:31:38.777] [D] [zway] SENT ACK
[2021-04-14 21:31:38.777] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.777] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.781] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:38.788] [D] [zway] RECEIVED: ( 01 0F 00 04 00 17 02 82 01 C7 00 04 41 02 07 2A CF )
[2021-04-14 21:31:38.788] [D] [zway] SENT ACK
[2021-04-14 21:31:38.789] [D] [zway] SETDATA devices.23.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.789] [I] [zway] Adding job: SwitchMultilevel Get
[2021-04-14 21:31:38.799] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 26 BF 00 00 4A )
[2021-04-14 21:31:38.799] [D] [zway] SENT ACK
[2021-04-14 21:31:38.800] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.800] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.810] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.845] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BB 00 00 4C )
[2021-04-14 21:31:38.846] [D] [zway] SENT ACK
[2021-04-14 21:31:38.846] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.846] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:38.852] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (18.0)","l":"36%","location":0}
[2021-04-14 21:31:38.853] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:38.904] [D] [zway] RECEIVED: ( 01 0C 00 04 10 3B 03 25 03 00 B3 00 00 4A )
[2021-04-14 21:31:38.905] [D] [zway] SENT ACK
[2021-04-14 21:31:38.905] [D] [zway] SETDATA devices.59.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.905] [D] [zway] Received reply on job (SwitchBinary Get)
[2021-04-14 21:31:38.905] [D] [zway] SETDATA devices.59.instances.0.commandClasses.37.data.level = False
[2021-04-14 21:31:38.916] [I] [core] HK: updated ZWayVDev_zway_59-0-37
[2021-04-14 21:31:38.926] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 B0 00 00 76 )
[2021-04-14 21:31:38.926] [D] [zway] SENT ACK
[2021-04-14 21:31:38.927] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.927] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.937] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 B9 00 00 7F )
[2021-04-14 21:31:38.937] [D] [zway] SENT ACK
[2021-04-14 21:31:38.938] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.938] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.948] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:38.958] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:38.969] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 C1 00 00 07 )
[2021-04-14 21:31:38.969] [D] [zway] SENT ACK
[2021-04-14 21:31:38.969] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.969] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:38.987] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:38.998] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 B6 00 00 70 )
[2021-04-14 21:31:38.998] [D] [zway] SENT ACK
[2021-04-14 21:31:38.998] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:38.998] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:39.009] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 B8 00 00 7E )
[2021-04-14 21:31:39.009] [D] [zway] SENT ACK
[2021-04-14 21:31:39.009] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:39.009] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:39.019] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:39.020] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 26 BB 00 00 7D )
[2021-04-14 21:31:39.020] [D] [zway] SENT ACK
[2021-04-14 21:31:39.020] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:39.020] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:39.029] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:39.059] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-14 21:31:39.152] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 26 B5 00 00 40 )
[2021-04-14 21:31:39.152] [D] [zway] SENT ACK
[2021-04-14 21:31:39.152] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:39.152] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 38 (0x00000026)
[2021-04-14 21:31:39.167] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BF 00 00 48 )
[2021-04-14 21:31:39.167] [D] [zway] SENT ACK
[2021-04-14 21:31:39.167] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:39.167] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:39.170] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:39.180] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:39.189] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 24 BB 00 00 4C )
[2021-04-14 21:31:39.190] [D] [zway] SENT ACK
[2021-04-14 21:31:39.190] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:39.190] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 36 (0x00000024)
[2021-04-14 21:31:39.209] [I] [core] HK: updated ZWayVDev_zway_18-0-38
[2021-04-14 21:31:39.218] [D] [zway] RECEIVED: ( 01 0C 00 04 10 12 03 26 03 26 AE 00 00 5B )
[2021-04-14 21:31:39.219] [D] [zway] SENT ACK
[2021-04-14 21:31:39.219] [D] [zway] SETDATA devices.18.data.lastReceived = 0 (0x00000000)
[2021-04-14 21:31:39.219] [D] [zway] SETDATA devices.18.instances.0.commandClasses.38.data.level = 38 (0x00000026)
Re: Missing actions/status report when sending multiple requests at once
Here is a little cleaner
https://pastebin.com/ZqZX1JUw
A little example of what zsniffer shows "Too much retransmission" and "Could not be delivered to Z-Wave stack"
Code: Select all
n U W S E D Ack Resp Cbk Repl Timeout NodeId Description Progress Buffer
2 D + + + 12.57 2 SwitchMultilevel Set Not delivered to recipient
Delivered 2 4 26 1 0 ff 25
1 D + + + 12.68 7 SwitchMultilevel Set Delivered 7 4 26 1 0 ff 25
1 D + + + 13.00 14 SwitchBinary Set Delivered e 3 25 1 0 25
1 D + + + 13.05 15 SwitchBinary Set Delivered f 3 25 1 0 25
1 D + + + 13.32 18 SwitchMultilevel Set Delivered 12 4 26 1 0 ff 25
1 D + + + 13.37 23 SwitchMultilevel Set Delivered 17 4 26 1 0 ff 25
2 D + + + 14.41 33 SwitchMultilevel Set Delivered 21 4 26 1 0 ff 25
1 D + + + 14.53 59 SwitchBinary Set Delivered 3b 3 25 1 0 25
1 D + + + 16.68 39 SwitchBinary Set Delivered 27 3 25 1 0 25
1 D + + + 16.83 41 SwitchBinary Set Delivered 29 3 25 1 0 25
1 D + + + 17.23 42 SwitchBinary Set Delivered 2a 3 25 1 0 25
3 D + + + 17.75 43 SwitchBinary Set Delivered 2b 3 25 1 0 25
3 D + + - 19.93 43 SwitchBinary Get Could not be delivered to Z-Wave stack
Removing job due to too much retransmitions 2b 2 25 2 25
2 D + + 17.96 0 Get background noise level RSSI Ch#1: -88 dBm, Ch#2: -88 dBm, Ch#3: invalid
2 + + - - 19.98 23 SwitchMultilevel Get 17 2 26 2 25
0 - - - - 0.20 18 SwitchMultilevel Get 12 2 26 2 25
Although the Z-way network is in a open space without any brick/mansory walls in between and everything is within 30 feet (10 meters) max of distance with repeaters in between...
Sometimes commands sent by the z-way dongle are not reached by the devices ... other times the device gets the command but the z-wa dongle fails to capture the status report. the devices affected randomly changes... which makes it hard to troubleshoot or understand what the cause is.
Re: Missing actions/status report when sending multiple requests at once
@ronluna What is the firmware of your RaZberry? Please upgrade to 5.39. The "Could not delivered to stack" is the reason. This means the SiLabs SDK got crazy and stopped accepting packets for delivery. Newer SDKs are fixing this.
Re: Missing actions/status report when sending multiple requests at once
@vinisz:
* Log-level setting in "confg.xml" (I have it one "1" now, is that a high level of logging or a low one ?)
Higher is more silent
* Devicereplytimeout in "defaults.xml" (currently on two, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
This is to give time for a device to answer. If intensive routing is involved, this might take few attempts. default is usually okey
* Devicerelaxdelay in "defaults.xml" (currently at three, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
This is for damn slow devices that can not handle subsequent packets and starts to loose fast requests. rare case, but happens
* Maximium number of commands per multichannel (currently also at three, should this be longer or shorter in large network with almost 200 devices ? 95% z-wave plus)
This is per MultCmd, not per channel. Again, for buggy devices that can not handle more than 2-3 commands in one packet.
I would propose first to make Network Reorg. Usually this helps. If so, do it weekly.
* Log-level setting in "confg.xml" (I have it one "1" now, is that a high level of logging or a low one ?)
Higher is more silent
* Devicereplytimeout in "defaults.xml" (currently on two, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
This is to give time for a device to answer. If intensive routing is involved, this might take few attempts. default is usually okey
* Devicerelaxdelay in "defaults.xml" (currently at three, should this be longer or shorter in large network with almost 200 devices ?, 95% z-wave plus)
This is for damn slow devices that can not handle subsequent packets and starts to loose fast requests. rare case, but happens
* Maximium number of commands per multichannel (currently also at three, should this be longer or shorter in large network with almost 200 devices ? 95% z-wave plus)
This is per MultCmd, not per channel. Again, for buggy devices that can not handle more than 2-3 commands in one packet.
I would propose first to make Network Reorg. Usually this helps. If so, do it weekly.
Re: Missing actions/status report when sending multiple requests at once
The dongle is on 5.39. I've recently upgrade the entire setup (Ubuntu 20.04) to be able to upgrade to the latest Z-way firmware.
Hardware
Vendor: Z-Wave.Me
Vendors Product ID: 1024 / 1
Z-Wave Chip: ZW0500
Firmware
Library Type: Static Controller
SDK Version: 6.82.01
Serial API Version: 05.39
Software Information
Version number: v3.1.3
Compile-ID: 8561a549de9777bbae2ceb8908702098fe6e20c3
Compile-Date: 2021-02-16 02:59:08 +0300
UI
UI version v1.5.5
Built date 16-02-2021 02:51:12
Anything else I could try ?
Re: Missing actions/status report when sending multiple requests at once
Hm... Please do network reogranization. Better two times. This might help to clean internal routes and make the SDK happy
Re: Missing actions/status report when sending multiple requests at once
I did your suggestion. Although It did not help. I can still see "Could not be delivered to Z-Wave stack"
Code: Select all
[2021-04-15 16:56:01.577] [D] [zway] RECEIVED CAN
[2021-04-15 16:56:01.577] [D] [zway] RECEIVED: ( 01 0C 00 04 10 21 03 26 03 0F B9 00 00 56 )
[2021-04-15 16:56:01.577] [D] [zway] SENT ACK
[2021-04-15 16:56:01.578] [D] [zway] SETDATA devices.33.data.lastReceived = 0 (0x00000000)
[2021-04-15 16:56:01.578] [D] [zway] SETDATA devices.33.instances.0.commandClasses.38.data.level = 15 (0x0000000f)
[2021-04-15 16:56:01.578] [D] [zway] SENDING (cb 0x93): ( 01 0A 00 13 2A 03 25 01 00 25 93 5D )
[2021-04-15 16:56:01.579] [D] [zway] RECEIVED ACK
[2021-04-15 16:56:01.582] [I] [core] Notification: device-info (device-status): {"dev":"Cooper Dimmer (#33)","l":"15%","location":0}
[2021-04-15 16:56:01.583] [I] [core] HK: updated ZWayVDev_zway_33-0-38
[2021-04-15 16:56:01.588] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2021-04-15 16:56:01.589] [D] [zway] SENT ACK
[2021-04-15 16:56:01.589] [I] [zway] Job 0x13 (SwitchBinary Set): Could not be delivered to Z-Wave stack
[2021-04-15 16:56:01.589] [I] [zway] Job 0x13 (SwitchBinary Set): Removing job due to too much retransmitions
[2021-04-15 16:56:01.589] [D] [zway] Job 0x13 (SwitchBinary Set): fail
[2021-04-15 16:56:01.589] [I] [zway] Removing job: SwitchBinary Set
RF9540 and the Qubino Shutters, send status reports while dimming . Adding a lot more traffic when sending multiple requests. Wondering if that could be the source of the problem ? Which it would totally make Z-wave networks unreliable for midsize to large size sites (I think even small sites would be unreliable) ... On the other hand... Sending commands 200 milliseconds apart does help remedy this situation.... I'm wondering if it's possible to add a delay between commands being sent by the Z-Way dongle?