Z-Wave timeouts

Add-ons Bindings
,
1

I have an Aeotec Lightbulb that is defined a follows and is unable to communicatie with the controller and vice versa:

image
image1424×1600 214 KB

Now the 82: Color temperature in cold white component is set at 6500. and the communication fails. If I change the value to 6499 the bulb is communicating.

image
image1452×1550 214 KB

image
image1374×1570 204 KB

2

Did this just start (i.e. new device) or has it always been that way?

Can you get a debug log while changing the parameter around?

3

It has been going on for a couple of weeks now that my devices get unresponsive. I used to be able to fix this by doing a soft reset. But a couple of days ago it started getting difficult in keeping the system working.

Mostly after a reboot or updating the container to a newer version things will end up having communication errors.

I tried rebooting and the problem of devices losing communication reoccurred. Even the ones with 6499 for 82: Color temperature in cold white component. So that did not help.

4

Then it might not be that. Need some debug. From the picture the “pending” is a slight concern since for powered devices that should happen quickly. It could be transitory though. Could be communication related-Is it near the controller & is the controller away from any interference?. Does the device have 5 lines on the UI page?
Five Lines of configured node

5

I have no log from the moment I changed the attribues and the lights became communicative again. But as stated in previous message setting to 6499 did not seem to be te solution. I have attached the DEBUG log.

zwave.log (847.8 KB)

6

My mains powered device mostly don’t have the “Reinitialize the device” option. I would like them to always be there as this solves the problem on many an occasion.

image
image1254×296 13.9 KB

7

Yes that is a problem.

I haven’t encountered this before, so it might need some study. Anyway, it appears to be a controller related. If you use the zwave log viewer here the binding seems to want to reset the controller and the controller sends something related to RF. In the binding these are the serial classes

        SerialApiSoftReset(0x08, false, false, false), // Soft reset. Restarts Z-Wave chip
        RfReceiveMode(0x10), // Power down the RF section of the stick

Also the NAK is a concern.

case NAK:
                        // NAK means the controller didn't receive the message - probably because of a Checksum error

There seems to be many messages of Checksum errors.
Here is a section of the viewer;

controller 2023-12-06 101439
controller 2023-12-06 101439336×641 11 KB

Do you have a spare controller? What kind of controller do you have? Have you backed up the nvm on the controller? I’m thinking about Hard reset and reload the network from a backup or load the network on the spare stick and try that. There might be something less severe, but IDK.

8

I have a RazBerry 7 Pro (the one with the separate antenna)

How can I create a backup of the NVM for this device? I can do using the z-way-server software and do a hard reset and perform a restore. I have done this already a couple of times to no avail.

9

When reinitializing a device it also takes a long time to go past “VERSION”.

image
image2110×760 45.2 KB

10

Sometimes sending a command to the light also helps in it starting to listen again.

11

This is the amount of NAK’s (116 per minute) are registered in the Log file. All going to Node 255. Can this count for the insanely growing number of thread on my system as well?

2023-12-06 17:46:40.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:40.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:41.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:41.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:42.683 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:42.683 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:43.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:43.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:44.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:44.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:45.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:45.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:46.964 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:46.964 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:48.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:48.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:49.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:49.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:50.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:50.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:51.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:51.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:52.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:52.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:53.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:53.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:54.453 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:54.453 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:55.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:55.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:56.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:56.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:57.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:57.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:58.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:58.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:59.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:46:59.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:00.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:00.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:01.940 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:01.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:03.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:03.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:04.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:04.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:05.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:05.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:06.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:06.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:07.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:07.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:08.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:08.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:09.428 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:09.428 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:10.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:10.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:11.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:11.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:12.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:12.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:13.706 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:13.706 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:14.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:14.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:15.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:15.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:16.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:16.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:17.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:17.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:19.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:19.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:20.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:20.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:21.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:21.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:22.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:22.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:23.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:23.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:24.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:24.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:25.479 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:25.479 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:26.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:26.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:27.620 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:27.621 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:28.691 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:28.691 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:29.761 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:29.761 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:30.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:30.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:31.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:31.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:32.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:32.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:34.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:34.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:35.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:35.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:36.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:36.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:37.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:37.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:38.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:38.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:39.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:39.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:40.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:40.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:41.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
2023-12-06 17:47:41.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=NAK[3], dest=255, callback=0, payload=
12

Sorry to drop off there, I was upgrading to M4 and had problems.

I don’t know if he has time, but @chris need to look at this. It is beyond my experience and knowledge.
FWIW, my thoughts;

  1. The only messages that seem to get through are from Node 29 and they use CRC_16_ENCAP. There are also numerous messages about mismatched CRC
    WaveCRC16EncapsulationCommandClass] - NODE 31: CRC check failed message contains EE BE but should be AA 3D From the above NAK also is related to failed checks. Were these nodes included securely and did anything happen to 16 byte code on the controller UI page? Node 255 is the controller, so the binding and controller are not speaking the same language. Is there a loose wire and/or is the hat securely fastened?
  2. I’m a little concerned about the “7 Pro” using the 700 chip, but none of the messages in the log are the issues I’ve seen with a 700 controller. For example the messages that get through use ApplicationCommandHandler (0x04) which is the same as a 500 chip uses. How long have you had that controller? Also if you get a chance, run a debug from an OH startup and look for something like these;
    Got MessageGetVersion response. Version=Z-Wave 6.07, Library Type=1 Got MessageGetVersion response. Version=Z-Wave 7.19, Library Type=7
    I’m curious about what SDK this device is using.
  3. As to nvm backup, I’ve never used these devices, but it sounds like the Zway application is handling that ok and from your report that is not helping.

EDIT: I did think of something last night. After a network restore a soft reset is often required especially on 7 series controllers. For a Zstick this means unplug and replug after a nvm operation. From the Debug the binding seems to be trying to do a soft reset, but the controller is not responding. For your controller you might need to shutdown the Pi and then restart. I’m thinking a reboot might happen too fast (capacitors could still have a charge). Might be worth a try

13

The reset was all my doing. Sometimes after a restart it helps to perform a Synchronize Network on the brdige then save the bridge. The wait for it to come back and then perform a Soft Reset Controller and save again.

14

FYI- unless you have more than one controller that command doesn’t do anything. IIRC it tells the primary controller to send node information to the other controllers.

15

Good to know.

16

Another question on this topic. In the z-way-server community a scheduled soft-reset of the controller stick is very helpful. Can this be achieved in OpenHAB?

17

Yes. From the UI page for the controller near the bottom. Two comments;

  1. I don’t have real any experience with this.
  2. From the debug log viewer extract in Post 7 doesn’t seem to work (assuming this was from OH)
18

What does such soft-reset execatly do?

19

It performs a unplug and plugin action.

20

I know it can be done from the UI. I would like to perform this from the cron if possible.