Migrate from UZB -> RaZberry PRO 7

[DesT]

Hi @PoltoS,

Long time Hope your are doing well!

I just migrate from the my old UZB to the new RaZberry 7 Pro by doing a backup restore procedure.

It works fine for a couple of day... but since the last 2-3 days I'm having something weird.

I'm having a lot of output in z-way logs like that:

Code: Select all

[2024-01-30 06:55:08.397] [D] [zway] RECEIVED: ( 01 1D 00 13 C7 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 34 )
[2024-01-30 06:55:08.397] [D] [zway] SENT ACK
[2024-01-30 06:55:08.397] [I] [zway] Job 0x13 (Nonce Get to node 120): Not delivered to recipient
[2024-01-30 06:55:08.400] [D] [zway] SENDING (cb 0xc8): ( 01 09 00 13 78 02 98 40 25 C8 AA )
[2024-01-30 06:55:08.407] [D] [zway] RECEIVED ACK
[2024-01-30 06:55:08.407] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2024-01-30 06:55:08.407] [D] [zway] SENT ACK
[2024-01-30 06:55:08.408] [D] [zway] Delivered to Z-Wave stack
[2024-01-30 06:55:08.415] [D] [zway] RECEIVED: ( 01 1D 00 13 C8 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 3B )
[2024-01-30 06:55:08.415] [D] [zway] SENT ACK
[2024-01-30 06:55:08.415] [I] [zway] Job 0x13 (Nonce Get to node 120): Not delivered to recipient
[2024-01-30 06:55:08.418] [D] [zway] SENDING (cb 0xc9): ( 01 09 00 13 78 02 98 40 25 C9 AB )
[2024-01-30 06:55:08.425] [D] [zway] RECEIVED ACK
[2024-01-30 06:55:08.425] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2024-01-30 06:55:08.425] [D] [zway] SENT ACK
[2024-01-30 06:55:08.425] [D] [zway] Delivered to Z-Wave stack
[2024-01-30 06:55:08.432] [D] [zway] RECEIVED: ( 01 1D 00 13 C9 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 3A )
[2024-01-30 06:55:08.432] [D] [zway] SENT ACK
[2024-01-30 06:55:08.432] [I] [zway] Job 0x13 (Nonce Get to node 120): Not delivered to recipient
[2024-01-30 06:55:08.433] [I] [zway] Job 0x13 (Nonce Get to node 120): Removing job due to too much retransmitions
[2024-01-30 06:55:08.433] [I] [zway] Adding job: Check if node is failed to node 120
[2024-01-30 06:55:08.433] [D] [zway] Job 0x13 (Nonce Get to node 120): fail
[2024-01-30 06:55:08.433] [I] [zway] Node 120:0 CC Security: sending Nonce Get
[2024-01-30 06:55:08.434] [D] [zway] SETDATA devices.120.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2024-01-30 06:55:08.434] [I] [zway] Adding job: Nonce Get to node 120
[2024-01-30 06:55:08.434] [I] [zway] Removing job: Nonce Get to node 120
[2024-01-30 06:55:08.438] [D] [zway] SENDING: ( 01 04 00 62 78 E1 )
[2024-01-30 06:55:08.459] [D] [zway] Job 0x13: deleted from queue
[2024-01-30 06:55:08.470] [D] [zway] Job 0x62: deleted from queue
[2024-01-30 06:55:08.480] [D] [zway] RECEIVED ACK
[2024-01-30 06:55:08.480] [D] [zway] RECEIVED: ( 01 04 01 62 00 98 )
[2024-01-30 06:55:08.481] [D] [zway] SENT ACK
[2024-01-30 06:55:08.481] [D] [zway] SETDATA devices.120.data.isFailed = False
[2024-01-30 06:55:08.481] [I] [zway] Job 0x62 (Check if node is failed to node 120): Node 120 is operating
[2024-01-30 06:55:08.481] [D] [zway] Job 0x62 (Check if node is failed to node 120): success
[2024-01-30 06:55:08.481] [D] [zway] SETDATA devices.120.data.failureCount = 0 (0x00000000)
[2024-01-30 06:55:08.481] [D] [zway] SETDATA devices.120.data.lastSendInternal = **********
[2024-01-30 06:55:08.481] [I] [zway] Removing job: Check if node is failed to node 120
[2024-01-30 06:55:08.483] [D] [zway] SENDING (cb 0xca): ( 01 09 00 13 42 02 98 40 05 CA B2 )
[2024-01-30 06:55:08.490] [D] [zway] RECEIVED ACK
[2024-01-30 06:55:08.490] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2024-01-30 06:55:08.490] [D] [zway] SENT ACK
[2024-01-30 06:55:08.490] [D] [zway] Delivered to Z-Wave stack
[2024-01-30 06:55:08.497] [D] [zway] RECEIVED: ( 01 1D 00 13 CA 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 39 )
[2024-01-30 06:55:08.497] [D] [zway] SENT ACK
[2024-01-30 06:55:08.497] [I] [zway] Job 0x13 (Nonce Get to node 66): Not delivered to recipient
[2024-01-30 06:55:08.500] [D] [zway] SENDING (cb 0xcb): ( 01 09 00 13 42 02 98 40 05 CB B3 )
[2024-01-30 06:55:08.507] [D] [zway] RECEIVED ACK
[2024-01-30 06:55:08.507] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2024-01-30 06:55:08.507] [D] [zway] SENT ACK
[2024-01-30 06:55:08.507] [D] [zway] Delivered to Z-Wave stack

it's not always the same device ID

Also, something when I restart z-way, I got:

Code: Select all

[2024-01-30 06:59:21.996] [E] [core] Callback execution error: TypeError: Cannot read property 'toJSON' of null
    at automation/userModules/SwitchPollingSelect/index.js:60:24
    at Array.forEach (native)
    at AutomationController.onPoll (automation/userModules/SwitchPollingSelect/index.js:58:19)
    at AutomationController.EventEmitter.emit (automation/lib/eventemitter2.js:311:33)
    at automation/modules/Cron/index.js:138:27
    at Array.forEach (native)
    at automation/modules/Cron/index.js:126:18
    at Array.forEach (native)
    at Function.<anonymous> (automation/modules/Cron/index.js:123:31)

At boot, I can also have a lot of :

Code: Select all

[2024-01-30 07:11:43.174] [I] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.data.interviewDone = True
[2024-01-30 07:11:43.174] [I] [zway] Interview for Node #6 finished.
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.37.data.interviewCounter = 10 (0x0000000a)
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.37.data.level = True
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.supported = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.supported = True
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.version = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.version = 1 (0x00000001)
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.security = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.security = False
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.interviewDone = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.interviewCounter = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.interviewDone = False
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.interviewCounter = 10 (0x0000000a)
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.resettable = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.scalemask = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.supported = False
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.version = 1 (0x00000001)
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.security = False
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.interviewDone = False
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.3.commandClasses.50.data.interviewCounter = 10 (0x0000000a)
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.dynamic = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.dynamic = False
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.genericType = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.genericType = 0 (0x00000000)
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.specificType = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.specificType = 0 (0x00000000)
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.nodeInfoFrame = Empty
[2024-01-30 07:11:43.174] [D] [zway] SETDATA devices.6.instances.2.data.dynamic = False

For ALL devices

I have really no idea where to start to debug my issue...

[DesT]

@PoltoS,

Another quick question, what's the serial port speed when using a razberry pro 7 with the High Speed feature ?

is it still 115200 or it can be more ?

[lanbrown]

Some of the early firmware releases for the Razberry 7 were buggy, bugs from Silicon Labs SDK that they resolved. So you might want to check your firmware level on the Razberry 7 board.

[DesT]

I already updated the firmware to the latest version!

@poltos, never got this kind of issue and unstability compared to the old UZB

it works perfectly for a while... and then, out of sudden, I'm having around 500-600 job in the queue and no matter what I can't fixed it until I try to restart z-way-server a couple of time and even that, sometime I need to just wait.

[seattleneil]

The logs you provided show 2 different issues.

For the issue related to RaZberry PRO 7 reliability, follow the instructions in this post: https://forum.z-wave.me/viewtopic.php?p=98974#p98974

For the issue related to JavaScript errors ("at automation/userModules/SwitchPollingSelect/index.js:60:24"), the Z-Way developers did not provide this module. This error is caused by a user-added module and is your responsibility to resolve. If you don't know have a need for running this module, then I suggest you either disable the module or delete the module.

[DesT]

thanks for that! will try the daily reset

[PoltoS]

The speed can be set right in the Z-Wave Binding UI (if the feature is enabled in the dongle via license - it is free).

Make sure to update the firmware to the latest - errors should go away.

As for the automation/userModules/SwitchPollingSelect/index.js file, as you see, it is in userModules, which means it was installed from the App Store. Just install it back.

[DesT]

@PoltoS,

It's not stable at all

The current firmware version is: 7.38 [Bootloader: 1766938484]

[lanbrown]

That is not the latest which is why I asked back on the 30th. You need to use the token all to get the newer firmware update that has been out for quite sometime.

[DesT]

@ianbrown

thanks again, I never read it properly on the 30th. Currently upgrading to the "all" latest version