Everspring AC301-1 ThermostatSetPoint Setter Failed

[MrRusch]

After finally some time of stable z-wave, when winter really comes I realize my thermostats are not working as before.
I have a set of Everspring AC301-1 devices (https://products.z-wavealliance.org/products/3772) rebranded as Nexa ZRT-106 (https://nexa.se/smarta-hem/z-wave/zrt-106).

When attempting to change the target temperature of a device, the GUI resets after a few seconds of wait - regardless if I force wake the device before making the change, or not. It always sets the device back to 35 degrees and Heat mode.

thermostats.png (56.96 KiB) Viewed 3893 times

Looking at the logs

Code: Select all

[2025-01-06 11:58:47.638] [D] [zway] RECEIVED: ( 01 0D 00 04 00 48 05 31 05 05 01 1D BA 00 2C )
[2025-01-06 11:58:47.638] [D] [zway] SENT ACK
[2025-01-06 11:58:47.638] [D] [zway] SETDATA devices.72.data.lastReceived = 0 (0x00000000)
[2025-01-06 11:58:47.639] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.5.deviceScale = 0 (0x00000000)
[2025-01-06 11:58:47.639] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.5.scale = 0 (0x00000000)
[2025-01-06 11:58:47.639] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.5.scaleString = "%"
[2025-01-06 11:58:47.639] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.5.precision = **********
[2025-01-06 11:58:47.639] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.5.intVal = **********
[2025-01-06 11:58:47.639] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.5.val = 29.000000
[2025-01-06 11:58:47.639] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.5 = Empty
[2025-01-06 11:58:47.659] [D] [zway] RECEIVED: ( 01 0E 00 04 00 48 06 31 05 01 22 00 E6 BA 00 F0 )
[2025-01-06 11:58:47.659] [D] [zway] SENT ACK
[2025-01-06 11:58:47.660] [D] [zway] SETDATA devices.72.data.lastReceived = 0 (0x00000000)
[2025-01-06 11:58:47.660] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000)
[2025-01-06 11:58:47.660] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.1.scaleString = "°C"
[2025-01-06 11:58:47.661] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.1.precision = **********
[2025-01-06 11:58:47.661] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.1.intVal = **********
[2025-01-06 11:58:47.661] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.1.val = 23.000000
[2025-01-06 11:58:47.661] [D] [zway] SETDATA devices.72.instances.0.commandClasses.49.data.1 = Empty
[2025-01-06 11:58:52.646] [I] [zway] Adding job: Get background noise level
[2025-01-06 11:58:52.655] [D] [zway] SENDING: ( 01 03 00 3B C7 )
[2025-01-06 11:58:52.657] [D] [zway] RECEIVED ACK
[2025-01-06 11:58:52.659] [D] [zway] RECEIVED: ( 01 07 01 3B 99 95 95 7F 24 )
[2025-01-06 11:58:52.659] [D] [zway] SENT ACK
[2025-01-06 11:58:52.659] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = 153 (0x00000099)
[2025-01-06 11:58:52.659] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = 149 (0x00000095)
[2025-01-06 11:58:52.659] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = 149 (0x00000095)
[2025-01-06 11:58:52.659] [I] [zway] Job 0x3b (Get background noise level): RSSI Ch#1: -103 dBm, Ch#2: -107 dBm, Ch#3: -107 dBm
[2025-01-06 11:58:52.659] [D] [zway] Job 0x3b (Get background noise level): success
[2025-01-06 11:58:52.659] [I] [zway] Removing job: Get background noise level
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.setVal = 21.000000
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27 = Empty
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.status = Empty
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.duration = Empty
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.moreStatusUpdates = Empty
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.lastEvent = Empty
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.status = 255 (0x000000ff)
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.duration = 0 (0x00000000)
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.moreStatusUpdates = False
[2025-01-06 11:58:52.799] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.lastEvent = 7908819 (0x0078add3)
[2025-01-06 11:58:52.800] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27 = Empty
[2025-01-06 11:58:52.800] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.lastSession = 27 (0x0000001b)
[2025-01-06 11:58:52.800] [I] [zway] Adding job: ThermostatSetPoint Set to node 70
[2025-01-06 11:58:52.803] [D] [zway] SENDING (cb 0x18): ( 01 11 00 13 46 0A 6C 01 9B 06 43 01 01 22 00 D2 05 18 EF )
[2025-01-06 11:58:52.808] [D] [zway] RECEIVED ACK
[2025-01-06 11:58:52.810] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2025-01-06 11:58:52.810] [D] [zway] SENT ACK
[2025-01-06 11:58:52.810] [D] [zway] Delivered to Z-Wave stack
[2025-01-06 11:58:54.056] [D] [zway] RECEIVED: ( 01 1D 00 13 18 00 00 7A 00 AC 7F 7F 7F 7F 01 01 03 00 00 00 00 42 01 00 00 7F 7F 7F 7F 7F 00 )
[2025-01-06 11:58:54.057] [D] [zway] SENT ACK
[2025-01-06 11:58:54.057] [I] [zway] Job 0x13 (ThermostatSetPoint Set to node 70): Delivered
[2025-01-06 11:58:54.057] [D] [zway] SendData Response with callback 0x18 received: received by recipient
[2025-01-06 11:58:54.057] [D] [zway] SETDATA devices.70.data.lastSendInternal = **********
[2025-01-06 11:58:54.057] [D] [zway] SETDATA devices.70.data.lastSend = 7908941 (0x0078ae4d)
[2025-01-06 11:58:54.057] [D] [zway] Job 0x13 (ThermostatSetPoint Set to node 70): success
[2025-01-06 11:58:54.057] [I] [zway] Waiting for job reply: ThermostatSetPoint Set from node 70
[2025-01-06 11:58:54.069] [D] [zway] RECEIVED: ( 01 0D 00 04 00 46 05 6C 02 1B 02 00 AD 00 6F )
[2025-01-06 11:58:54.069] [D] [zway] SENT ACK
[2025-01-06 11:58:54.069] [D] [zway] SETDATA devices.70.data.lastReceived = 0 (0x00000000)
[2025-01-06 11:58:54.069] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.status = 2 (0x00000002)
[2025-01-06 11:58:54.069] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.duration = 0 (0x00000000)
[2025-01-06 11:58:54.070] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.moreStatusUpdates = False
[2025-01-06 11:58:54.070] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27.lastEvent = 7908942 (0x0078ae4e)
[2025-01-06 11:58:54.070] [D] [zway] SETDATA devices.70.instances.0.commandClasses.108.data.27 = Empty
[2025-01-06 11:58:54.070] [D] [zway] Received reply on job (ThermostatSetPoint Set to node 70)
[2025-01-06 11:58:54.070] [W] [zway] Node 70:0 CC Supervision: Setter failed by the recipient: ThermostatSetPoint Set
[2025-01-06 11:58:56.112] [D] [zway] Node 70:0 CC ThermostatSetPoint: Unsolicited report not received, sending Get
[2025-01-06 11:58:56.112] [I] [zway] Adding job: ThermostatSetPoint Get to node 70
[2025-01-06 11:58:56.123] [D] [zway] SENDING (cb 0x19): ( 01 0A 00 13 46 03 43 02 01 05 19 FF )
[2025-01-06 11:58:56.126] [D] [zway] RECEIVED ACK
[2025-01-06 11:58:56.128] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2025-01-06 11:58:56.128] [D] [zway] SENT ACK
[2025-01-06 11:58:56.128] [D] [zway] Delivered to Z-Wave stack
[2025-01-06 11:58:56.190] [D] [zway] Job 0x13: deleted from queue
[2025-01-06 11:58:57.353] [D] [zway] RECEIVED: ( 01 1D 00 13 19 00 00 78 00 AD 7F 7F 7F 7F 01 01 03 00 00 00 00 42 01 00 00 7F 7F 7F 7F 7F 02 )
[2025-01-06 11:58:57.353] [D] [zway] SENT ACK
[2025-01-06 11:58:57.353] [I] [zway] Job 0x13 (ThermostatSetPoint Get to node 70): Delivered
[2025-01-06 11:58:57.354] [D] [zway] SendData Response with callback 0x19 received: received by recipient
[2025-01-06 11:58:57.354] [D] [zway] SETDATA devices.70.data.lastSendInternal = **********
[2025-01-06 11:58:57.354] [D] [zway] SETDATA devices.70.data.lastSend = 7909259 (0x0078af8b)
[2025-01-06 11:58:57.354] [D] [zway] Job 0x13 (ThermostatSetPoint Get to node 70): success
[2025-01-06 11:58:57.354] [I] [zway] Waiting for job reply: ThermostatSetPoint Get from node 70
[2025-01-06 11:58:57.365] [D] [zway] RECEIVED: ( 01 0E 00 04 00 46 06 43 03 01 22 01 5E AD 00 24 )
[2025-01-06 11:58:57.365] [D] [zway] SENT ACK
[2025-01-06 11:58:57.365] [D] [zway] SETDATA devices.70.data.lastReceived = 0 (0x00000000)
[2025-01-06 11:58:57.365] [D] [zway] Received reply on job (ThermostatSetPoint Get to node 70)
[2025-01-06 11:58:57.365] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.size = **********
[2025-01-06 11:58:57.365] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.precision = **********
[2025-01-06 11:58:57.366] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.deviceScale = 0 (0x00000000)
[2025-01-06 11:58:57.366] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.deviceScaleString = "°C"
[2025-01-06 11:58:57.366] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.intVal = **********
[2025-01-06 11:58:57.366] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.val = 35.000000
[2025-01-06 11:58:57.366] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1.setVal = 35.000000
[2025-01-06 11:58:57.366] [D] [zway] SETDATA devices.70.instances.0.commandClasses.67.data.1 = Empty
[2025-01-06 11:58:59.493] [D] [zway] Job 0x13: deleted from queue

...the error seems to be:

[2025-01-06 11:58:54.070] [W] [zway] Node 70:0 CC Supervision: Setter failed by the recipient: ThermostatSetPoint Set
[2025-01-06 11:58:56.112] [D] [zway] Node 70:0 CC ThermostatSetPoint: Unsolicited report not received, sending Get

I also sometimes see:

[2025-01-06 11:38:03.475] [W] [zway] Node 70:0 CC Supervision: Matching Supervised Setter not found (Setter failed)

It's failing to set the setpoint and then reverts to the old value..

I just now tried to exclude one of the devices as they are unsecurely included. After successfully re-including the device with S2, the error is still the same in logs.

Any ideas on how to further debug would be helpful.

Note: Device 72 is a different product (MCOHOME MH7H-WH/EH) and works fine still.

[lanbrown]

See if disabling supervision helps:
https://forum.z-wave.me/viewtopic.php?p=99935#p99935

[MrRusch]

See if disabling supervision helps:
https://forum.z-wave.me/viewtopic.php?p=99935#p99935

Supervision is already disabled by default for all of these devices.

EDIT: this might not have been correct. but either way, having it disabled does not solve the issue.

[MrRusch]

Just found this.. https://community.home-assistant.io/t/e ... /243521/12

which ironically links back to the post i created a few years back when i had the same issue. and solved it by disabling survision..
i guess i should try harder.

[MrRusch]

So after disabling supervision properly, I get a slightly different error in logs:

Code: Select all

[2025-01-06 18:55:16.455] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.setVal = 21.000000
[2025-01-06 18:55:16.455] [I] [zway] Adding job: ThermostatSetPoint Set to node 78
[2025-01-06 18:55:16.456] [D] [zway] SENDING (cb 0x6c): ( 01 0D 00 13 4E 06 43 01 01 22 00 D2 05 6C 73 )
[2025-01-06 18:55:16.460] [D] [zway] RECEIVED ACK
[2025-01-06 18:55:16.462] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2025-01-06 18:55:16.462] [D] [zway] SENT ACK
[2025-01-06 18:55:16.462] [D] [zway] Delivered to Z-Wave stack
[2025-01-06 18:55:16.675] [D] [zway] Job 0x3b: deleted from queue
...
[2025-01-06 18:55:18.852] [D] [zway] RECEIVED: ( 01 0B 00 04 00 4E 03 22 02 00 B7 00 2A )
[2025-01-06 18:55:18.853] [D] [zway] SENT ACK
[2025-01-06 18:55:18.853] [D] [zway] SETDATA devices.78.data.lastReceived = 0 (0x00000000)
[2025-01-06 18:55:18.854] [W] [zway] Node 78:0 CC ApplicationStatus: Request rejected by device application layer, but can't find the rejected command in the queue
[2025-01-06 18:55:21.158] [D] [zway] RECEIVED: ( 01 1D 00 13 6C 00 01 CC 00 B6 7F 7F 7F 7F 01 01 01 00 00 00 00 42 04 00 00 7F 7F 7F 7F 7F DE )
[2025-01-06 18:55:21.158] [D] [zway] SENT ACK
[2025-01-06 18:55:21.158] [I] [zway] Job 0x13 (ThermostatSetPoint Set to node 78): Delivered
[2025-01-06 18:55:21.158] [D] [zway] SendData Response with callback 0x6c received: received by recipient
[2025-01-06 18:55:21.158] [D] [zway] SETDATA devices.78.data.lastSendInternal = **********
[2025-01-06 18:55:21.158] [D] [zway] SETDATA devices.78.data.lastSend = 37317 (0x000091c5)
[2025-01-06 18:55:21.158] [D] [zway] Job 0x13 (ThermostatSetPoint Set to node 78): success
[2025-01-06 18:55:21.158] [I] [zway] Removing job: ThermostatSetPoint Set to node 78
[2025-01-06 18:55:21.166] [D] [zway] RECEIVED: ( 01 0B 00 04 00 4E 03 22 02 00 B6 00 2B )
[2025-01-06 18:55:21.166] [D] [zway] SENT ACK
[2025-01-06 18:55:21.166] [D] [zway] SETDATA devices.78.data.lastReceived = 0 (0x00000000)
[2025-01-06 18:55:21.166] [I] [zway] Job 0x13 (ThermostatSetPoint Set to node 78): Request rejected by device application layer
[2025-01-06 18:55:23.213] [D] [zway] Node 78:0 CC ThermostatSetPoint: Unsolicited report not received, sending Get
[2025-01-06 18:55:23.213] [I] [zway] Adding job: ThermostatSetPoint Get to node 78
[2025-01-06 18:55:23.223] [D] [zway] SENDING (cb 0x6d): ( 01 0A 00 13 4E 03 43 02 01 05 6D 83 )
[2025-01-06 18:55:23.230] [D] [zway] RECEIVED ACK
[2025-01-06 18:55:23.230] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2025-01-06 18:55:23.230] [D] [zway] SENT ACK
[2025-01-06 18:55:23.230] [D] [zway] Delivered to Z-Wave stack
[2025-01-06 18:55:24.418] [D] [zway] RECEIVED: ( 01 1D 00 13 6D 00 00 74 00 B6 7F 7F 7F 7F 01 01 03 00 00 00 00 42 01 00 00 7F 7F 7F 7F 7F 61 )
[2025-01-06 18:55:24.419] [D] [zway] SENT ACK
[2025-01-06 18:55:24.419] [I] [zway] Job 0x13 (ThermostatSetPoint Get to node 78): Delivered
[2025-01-06 18:55:24.419] [D] [zway] SendData Response with callback 0x6d received: received by recipient
[2025-01-06 18:55:24.419] [D] [zway] SETDATA devices.78.data.lastSendInternal = **********
[2025-01-06 18:55:24.419] [D] [zway] SETDATA devices.78.data.lastSend = 37632 (0x00009300)
[2025-01-06 18:55:24.419] [D] [zway] Job 0x13 (ThermostatSetPoint Get to node 78): success
[2025-01-06 18:55:24.419] [I] [zway] Waiting for job reply: ThermostatSetPoint Get from node 78
[2025-01-06 18:55:24.430] [D] [zway] RECEIVED: ( 01 0E 00 04 00 4E 06 43 03 01 22 01 5E B5 00 34 )
[2025-01-06 18:55:24.430] [D] [zway] SENT ACK
[2025-01-06 18:55:24.430] [D] [zway] SETDATA devices.78.data.lastReceived = 0 (0x00000000)
[2025-01-06 18:55:24.430] [D] [zway] Received reply on job (ThermostatSetPoint Get to node 78)
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.size = **********
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.precision = **********
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.deviceScale = 0 (0x00000000)
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.deviceScaleString = "°C"
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.intVal = **********
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.val = 35.000000
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1.setVal = 35.000000
[2025-01-06 18:55:24.431] [D] [zway] SETDATA devices.78.instances.0.commandClasses.67.data.1 = Empty

Where relevant lines seem to be:
[2025-01-06 18:55:18.854] [W] [zway] Node 78:0 CC ApplicationStatus: Request rejected by device application layer, but can't find the rejected command in the queue

which then yields
[2025-01-06 18:55:21.166] [ I] [zway] Job 0x13 (ThermostatSetPoint Set to node 78): Request rejected by device application layer
[2025-01-06 18:55:23.213] [D] [zway] Node 78:0 CC ThermostatSetPoint: Unsolicited report not received, sending Get

Not sure why it resolved the issue last time but not now..

EDIT: When trying with other devices it seems to work. So only the one I have been testing with now is stuck.

[MrRusch]

OK so the one I was testing with was rejecting the change as it had yet to be AdAP calibrated. After that was completed, it works fine.

Issue resolved!