Backup fails

[MyZip]

My sd card from my raspberry pi just crashed so I had to start a fresh installion.
I've got two different backup files:
*.zab
*.zbk

I was able to recover the *.zab file by using the "user backup method" (#/admin). I checked the "I am sure" and the "Restore Z-Wave Network" checkpoint and the restoration worked. But no devices where found.

When going to #/zwave/devices it sais:

An unexpected error occurred during loading data. Try to reload the page.
Please check
1.) if the controller is plugged in correctly,
2.) that in the app 'Z-Wave Network Access' the right port is entered
(Z-Station: '/dev/ttyUSB0', RaZberry 7: '/dev/ttyAMA0', UZB: '/dev/ttyACM0', Z-Station or UZB on Windows: '\\.\COM3', Z-Stick: '/dev/ttyUSB0', embedded boxes: '/dev/ttyS0' or '/dev/ttyS1')
3.) the app is active. If not you could activate it under Menu > Apps > Active or add a new one under Menu > Apps > Local.
The Setting 'Expert View' needs to be active under Menu > My Settings.

I am using a Raspberry Pi 4 with Raspbian and Razberry 2.

Code: Select all

$: file /dev/ttyAMA0

Code: Select all

/dev/ttyAMA0: character special (204/64)

When going to expert panel and trying to restore the *.zbk file an error accours:

Upload or restore of backup has failed

"Z-Wave Network Access" is active under "apps"

The backups where made on 5th May 2023 with version 4.1.0 (as far as i know)

There is an update for 4.1.1 awaylabe but I cannot install it:

Err(1.18.3): main.c: 498: The spawned process 'shell:/bin/systemctl stop netif.$IFACE' has exited with code 5 and says:

Software info
Firmware version
UI version v1.15.1
Built date 03-05-2023 00:36:45

I cannot find 'Expert View' under "My Settings"

What am I doing wrong?

Edit: I tried to attach the backup files, but it seems to be forbidden. https://ufile.io/0mvipi1z

[seattleneil]

It's great that you have a recent .zab backup file as it should make recovering from and SD card failure painless.

Keep in mind the .zab backup file gets created from the smarthome UI and is a superset of information of the .zbk backup file (created from the expert UI).

Since your new SD card needs both the low-level Z-Wave network information (i.e., the contents of the .zbk file) and the higher-level Z-Way automation information, you should restore your configuration using the .zab file via the smarthome UI. In old versions of Z-Way, you needed to restore both the .zab and .zbk files, but newer Z-Way versions have simplified the backup/restore process by combining the Z-Wave network information into the .zab file.

From the what you wrote,

I was able to recover the *.zab file by using the "user backup method" (#/admin). I checked the "I am sure" and the "Restore Z-Wave Network" checkpoint and the restoration worked. But no devices where found.

you did everything right.

The challenge is to solve the "No devices where found." problem. A likely explanation is that your Pi has multiple instances of the z-way-server process running or there's another process that's using /dev/ttyAMA0. To check if there's a tty port conflict, run this command:
sudo lsof -n | grep AMA0

If the lsof command is not installed on your Pi, run: sudo apt insall lsof

The lsof command output should show multiple lines, all associated with a single z-way-server process ID, having multiple threads. Here's an example of the lsof output (where the z-way-server process has PID 13912):
$ sudo lsof -n | grep AMA0

Code: Select all

z-way-ser 13912                             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13913 Optimizin             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13914 v8:Sweepe             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13915 v8:Sweepe             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13916 v8:Sweepe             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13917 v8:Sweepe             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13918 zway/core             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13919 zway/webs             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13920 zway/time             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13921 zway/dev/             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0
z-way-ser 13912 13922 mosquitto             root    6u      CHR     204,64      0t0        132 /dev/ttyAMA0

If you see multiple processes, you will need to stop those processes (use systemctl or init.d commands) and may need to change configuration files to prevent these processes from automatically restarting when the Pi reboots.

If your backup was made using 4.1.0, don't worry about installing 4.1.1.

Good luck.

[MyZip]

Thank you for your fast reply. lsof does not post anything:

Code: Select all

sudo lsof -n | grep AMA0

lsof: WARNING: can't stat() fuse.gvfsd-fuse file system /run/user/1000/gvfs
Output information may be incomplete.

lsof should be working:

Code: Select all

sudo lsof -n 2>/dev/null | wc

14313 144900 2027234

Any idea?

Code: Select all

sudo service z-way-server status

● z-way-server.service - LSB: RaZberry Z-Wave service
Loaded: loaded (/etc/init.d/z-way-server; generated)
Active: active (running) since Thu 2023-07-27 13:53:52 CEST; 23min ago
Docs: man:systemd-sysv-generator(8)
Process: 1880 ExecStart=/etc/init.d/z-way-server start (code=exited, status=0/SUCCESS)
Tasks: 9 (limit: 3933)
CPU: 20.076s
CGroup: /system.slice/z-way-server.service
└─1885 z-way-server

Jul 27 13:53:52 raspberrypi systemd[1]: Starting LSB: RaZberry Z-Wave service...
Jul 27 13:53:52 raspberrypi z-way-server[1880]: Starting z-way-server: done.
Jul 27 13:53:52 raspberrypi systemd[1]: Started LSB: RaZberry Z-Wave service.

Code: Select all

tail -500 /var/log/z-way-server.log

[2023-07-27 13:54:04.755] [D] [zway] [46] 84
[2023-07-27 13:54:04.755] [D] [zway] [47] 85
[2023-07-27 13:54:04.755] [D] [zway] [48] 86
[2023-07-27 13:54:04.755] [D] [zway] [49] 87
[2023-07-27 13:54:04.755] [D] [zway] [50] 88
[2023-07-27 13:54:04.755] [D] [zway] [51] 94
[2023-07-27 13:54:04.755] [D] [zway] [52] 95
[2023-07-27 13:54:04.755] [D] [zway] [53] 96
[2023-07-27 13:54:04.755] [D] [zway] [54] 97
[2023-07-27 13:54:04.755] [D] [zway] [55] 98
[2023-07-27 13:54:04.756] [D] [zway] [56] 99
[2023-07-27 13:54:04.756] [D] [zway] [57] 128
[2023-07-27 13:54:04.756] [D] [zway] [58] 146
[2023-07-27 13:54:04.756] [D] [zway] [59] 147
[2023-07-27 13:54:04.756] [D] [zway] [60] 186
[2023-07-27 13:54:04.756] [D] [zway] [61] 190
[2023-07-27 13:54:04.756] [D] [zway] [62] 208
[2023-07-27 13:54:04.756] [D] [zway] [63] 209
[2023-07-27 13:54:04.756] [D] [zway] [64] 210
[2023-07-27 13:54:04.756] [D] [zway] [65] 211
[2023-07-27 13:54:04.756] [D] [zway] [66] 218
[2023-07-27 13:54:04.756] [D] [zway] [67] 219
[2023-07-27 13:54:04.756] [D] [zway] [68] 220
[2023-07-27 13:54:04.756] [D] [zway] [69] 242
[2023-07-27 13:54:04.756] [D] [zway] [70] 243
[2023-07-27 13:54:04.756] [D] [zway] [71] 244
[2023-07-27 13:54:04.756] [D] [zway] [72] 245
[2023-07-27 13:54:04.756] [D] [zway] [73] 248
[2023-07-27 13:54:04.756] [D] [zway] SETDATA controller.data.functionClassesNames = string[74]
[2023-07-27 13:54:04.756] [D] [zway] [00] "SerialAPIGetInitData"
[2023-07-27 13:54:04.756] [D] [zway] [01] "SerialAPIApplicationNodeInformation"
[2023-07-27 13:54:04.756] [D] [zway] [02] "ApplicationCommandHandler"
[2023-07-27 13:54:04.756] [D] [zway] [03] "GetControllerCapabilities"
[2023-07-27 13:54:04.757] [D] [zway] [04] "SerialAPISetTimeouts"
[2023-07-27 13:54:04.757] [D] [zway] [05] "GetSerialAPICapabilities"
[2023-07-27 13:54:04.757] [D] [zway] [06] "SerialAPISoftReset"
[2023-07-27 13:54:04.757] [D] [zway] [07] "SerialAPIStarted"
[2023-07-27 13:54:04.757] [D] [zway] [08] "SerialAPISetup"
[2023-07-27 13:54:04.757] [D] [zway] [09] "SendNodeInformation"
[2023-07-27 13:54:04.757] [D] [zway] [10] "SendData"
[2023-07-27 13:54:04.757] [D] [zway] [11] "SendDataMulti"
[2023-07-27 13:54:04.757] [D] [zway] [12] "GetVersion"
[2023-07-27 13:54:04.757] [D] [zway] [13] "SendDataAbort"
[2023-07-27 13:54:04.757] [D] [zway] [14] "RFPowerLevelSet"
[2023-07-27 13:54:04.757] [D] [zway] [15] "GetHomeId"
[2023-07-27 13:54:04.757] [D] [zway] [16] "MemoryGetByte"
[2023-07-27 13:54:04.757] [D] [zway] [17] "MemoryPutByte"
[2023-07-27 13:54:04.757] [D] [zway] [18] "MemoryGetBuffer"
[2023-07-27 13:54:04.757] [D] [zway] [19] "MemoryPutBuffer"
[2023-07-27 13:54:04.757] [D] [zway] [20] "FlashAutoProgSet"
[2023-07-27 13:54:04.757] [D] [zway] [21] "NVMGetId"
[2023-07-27 13:54:04.757] [D] [zway] [22] "NVMExtReadLongBuffer"
[2023-07-27 13:54:04.757] [D] [zway] [23] "NVMExtWriteLongBuffer"
[2023-07-27 13:54:04.757] [D] [zway] [24] "NVMExtReadLongByte"
[2023-07-27 13:54:04.757] [D] [zway] [25] "NVMExtWriteLongByte"
[2023-07-27 13:54:04.757] [D] [zway] [26] "ClearNetworkStats"
[2023-07-27 13:54:04.757] [D] [zway] [27] "GetNetworkStats"
[2023-07-27 13:54:04.757] [D] [zway] [28] "GetBackgroundRSSI"
[2023-07-27 13:54:04.757] [D] [zway] [29] "RemoveNodeIdFromNetwork"
[2023-07-27 13:54:04.757] [D] [zway] [30] "GetNodeProtocolInformation"
[2023-07-27 13:54:04.757] [D] [zway] [31] "SetDefault"
[2023-07-27 13:54:04.758] [D] [zway] [32] "ReplicationReceiveComplete"
[2023-07-27 13:54:04.758] [D] [zway] [33] "AssignReturnRoute"
[2023-07-27 13:54:04.758] [D] [zway] [34] "DeleteReturnRoute"
[2023-07-27 13:54:04.758] [D] [zway] [35] "RequestNodeNeighbourUpdate"
[2023-07-27 13:54:04.758] [D] [zway] [36] "ApplicationNodeUpdate"
[2023-07-27 13:54:04.758] [D] [zway] [37] "AddNodeToNetwork"
[2023-07-27 13:54:04.758] [D] [zway] [38] "RemoveNodeFromNetwork"
[2023-07-27 13:54:04.758] [D] [zway] [39] "CreateNewPrimary"
[2023-07-27 13:54:04.758] [D] [zway] [40] "ControllerChange"
[2023-07-27 13:54:04.758] [D] [zway] [41] "AssignPriorityReturnRoute"
[2023-07-27 13:54:04.758] [D] [zway] [42] "SetLearnMode"
[2023-07-27 13:54:04.758] [D] [zway] [43] "AssignSUCReturnRoute"
[2023-07-27 13:54:04.758] [D] [zway] [44] "EnableSUC"
[2023-07-27 13:54:04.758] [D] [zway] [45] "RequestNetworkUpdate"
[2023-07-27 13:54:04.758] [D] [zway] [46] "SetSUCNodeId"
[2023-07-27 13:54:04.758] [D] [zway] [47] "DeleteSUCReturnRoute"
[2023-07-27 13:54:04.758] [D] [zway] [48] "GetSUCNodeId"
[2023-07-27 13:54:04.758] [D] [zway] [49] "SendSUCNodeId"
[2023-07-27 13:54:04.758] [D] [zway] [50] "AssignPrioritySUC ReturnRoute"
[2023-07-27 13:54:04.758] [D] [zway] [51] "ExploreRequestInclusion"
[2023-07-27 13:54:04.758] [D] [zway] [52] "ExploreRequestExclusion"
[2023-07-27 13:54:04.758] [D] [zway] [53] "RequestNodeInformation"
[2023-07-27 13:54:04.758] [D] [zway] [54] "RemoveFailedNode"
[2023-07-27 13:54:04.758] [D] [zway] [55] "IsFailedNode"
[2023-07-27 13:54:04.758] [D] [zway] [56] "ReplaceFailedNode"
[2023-07-27 13:54:04.758] [D] [zway] [57] "GetRoutingTableLine"
[2023-07-27 13:54:04.759] [D] [zway] [58] "GetPriorityRoute"
[2023-07-27 13:54:04.759] [D] [zway] [59] "SetPriorityRoute"
[2023-07-27 13:54:04.759] [D] [zway] [60] "RFPowerLevelGet"
[2023-07-27 13:54:04.759] [D] [zway] [61] "SendTestFrame"
[2023-07-27 13:54:04.759] [D] [zway] [62] "SetPromiscuousMode"
[2023-07-27 13:54:04.759] [D] [zway] [63] "PromiscuousCommandHandler"
[2023-07-27 13:54:04.759] [D] [zway] [64] "WatchDogStart"
[2023-07-27 13:54:04.759] [D] [zway] [65] "WatchDogStop"
[2023-07-27 13:54:04.759] [D] [zway] [66] "GetLongRangeNodes"
[2023-07-27 13:54:04.759] [D] [zway] [67] "GetLongRangeChannel"
[2023-07-27 13:54:04.759] [D] [zway] [68] "SetLongRangeChannel"
[2023-07-27 13:54:04.759] [D] [zway] [69] "ZMEFreqChange"
[2023-07-27 13:54:04.759] [D] [zway] [70] "ZMERestore"
[2023-07-27 13:54:04.759] [D] [zway] [71] "ZMEBootloaderFlash"
[2023-07-27 13:54:04.759] [D] [zway] [72] "ZMECapabilities"
[2023-07-27 13:54:04.759] [D] [zway] [73] "ZMESerialAPIOptions"
[2023-07-27 13:54:04.759] [D] [zway] Opened device: /dev/ttyAMA0
[2023-07-27 13:54:04.759] [i/o] Setting port speed to 115200
[2023-07-27 13:54:04.760] [D] [zway] Worker thread successfully created
[2023-07-27 13:54:04.760] [D] [zway] Worker thread entry point
[2023-07-27 13:54:04.760] [zway] Adding job: Get controller info and supported function classes
[2023-07-27 13:54:04.770] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 13:54:04.972] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:54:04.972] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 13:54:05.175] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:54:05.175] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 13:54:05.378] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:54:05.378] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2023-07-27 13:54:05.378] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2023-07-27 13:54:05.378] [C] [zway] Get Serial API Capabilities returned zero.
[2023-07-27 13:54:05.378] [zway] Removing job: Get controller info and supported function classes
[2023-07-27 13:54:05.388] [D] [zway] Worker thread exit point
[2023-07-27 13:54:05.389] [D] [zway] Worker thread successfully finished
[2023-07-27 13:54:05.389] [D] [i/o] Closing port
[2023-07-27 13:54:05.400] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2023-07-27 13:54:05.412] [core] Error: Bad data - communication failed
[2023-07-27 13:55:36.035] [core] --- ZWave.stop()
[2023-07-27 13:55:36.036] [core] --- Stopping module Z-Wave Network Access
[2023-07-27 13:55:36.051] [I] [core] --- Starting module Z-Wave Network Access
[2023-07-27 13:55:36.201] [D] [zway] SETDATA controller.data = Empty
[2023-07-27 13:55:36.201] [D] [zway] SETDATA controller.data.nodeId = Empty
[2023-07-27 13:55:36.201] [D] [zway] SETDATA controller.data.nodeId = 0 (0x00000000)
[2023-07-27 13:55:36.201] [D] [zway] SETDATA controller.data.homeId = Empty
[2023-07-27 13:55:36.201] [D] [zway] SETDATA controller.data.homeId = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.SUCNodeId = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isPrimary = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isPrimary = False
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isInOthersNetwork = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isRealPrimary = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isRealPrimary = False
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isSUC = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.isSUC = False
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.SISPresent = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.SISPresent = False
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.libType = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.libType = ""
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.SDK = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.SDK = ""
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWlibMajor = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWlibMinor = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWLib = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWLib = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWVersion = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWaveChip = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.ZWaveChip = ""
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.APIVersion = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.APIVersion = ""
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.APIVersionMajor = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.APIVersionMajor = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.APIVersionMinor = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.APIVersionMinor = 0 (0x00000000)
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.manufacturerId = Empty
[2023-07-27 13:55:36.202] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.vendor = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.vendor = ""
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.manufacturerProductType = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.manufacturerProductId = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.capabilities = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.controllerState = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.nonManagmentJobs = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.lastIncludedDevice = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.lastExcludedDevice = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.secureInclusion = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.secureInclusion = 2 (0x00000002)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.countJobs = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.countJobs = False
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.memoryGetAddress = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.memoryGetData = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.memoryManufacturerId = Empty
[2023-07-27 13:55:36.203] [D] [zway] SETDATA controller.data.memoryType = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.memoryCapacity = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.functionClasses = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.functionClassesNames = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.softwareRevisionVersion = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.softwareRevisionVersion = "v4.1.0"
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.softwareRevisionId = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.softwareRevisionId = "5cfcb83c7f15414e1cad9d3fa347880cc88d5e65"
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.softwareRevisionDate = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.softwareRevisionDate = "2023-05-03 00:58:45 +0300"
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.uuid = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.hardware = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.hardware.programmerId = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.hardware.productionSeq = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.hardware.uartSpeed = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.bootloader = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.bootloader.version = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.bootloader.crc = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.version = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.build = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.crc = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.crc = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.maxNodes = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.staticApi = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.maxPower = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.backup = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.wup = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.advancedIMA = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.longRange = Empty
[2023-07-27 13:55:36.204] [D] [zway] SETDATA controller.data.firmware.caps.ultraUART = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.caps.swapSubvendor = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.caps.promisc = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.caps.zniffer = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.caps.jammingDetection = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.caps.pti = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.caps.modem = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.fault = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.fault.scb = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.fault.reg = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.fault.stack = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.firmware.fault.version = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.frequency = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.frequency = ""
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.deviceRelaxDelay = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.deviceRelaxDelay = 3 (0x00000003)
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.pauseSending = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.pauseSending = 0 (0x00000000)
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.promiscMode = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.promiscMode = False
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket.dstNodeId = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket.securePayload = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket.RSSI = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket.frameType = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket.hops = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.incomingPacket.duplicate = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.outgoingPacket = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.outgoingPacket.delivered = Empty
[2023-07-27 13:55:36.205] [D] [zway] SETDATA controller.data.outgoingPacket.deliveryTime = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.packetLength = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.nodeId = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.returnRSSI = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.hops = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.returnChannel = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.txChannel = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.speed = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.schemeState = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.tries = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.lastFailPath = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.outgoingPacket.securePayload = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = 127 (0x0000007f)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = 127 (0x0000007f)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = 127 (0x0000007f)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = 0 (0x00000000)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = 0 (0x00000000)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = 0 (0x00000000)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = 0 (0x00000000)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = 0 (0x00000000)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = Empty
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = 0 (0x00000000)
[2023-07-27 13:55:36.206] [D] [zway] SETDATA controller.data.statistics.priorityRoute = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.priorityRoute.dstNodeId = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.priorityRoute.routeType = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.priorityRoute.speed = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.priorityRoute.hops = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.ch1Threshold = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.ch1Threshold = 127 (0x0000007f)
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.ch2Threshold = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.ch2Threshold = 127 (0x0000007f)
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.ch3Threshold = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.ch3Threshold = 127 (0x0000007f)
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.detected = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.statistics.jammingDetection.detected = 0 (0x00000000)
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.homeName = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.homeName = ""
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.homeNotes = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.homeNotes = ""
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.S2RequireCSA = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.S2RequireCSA = False
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.smartStart = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.smartStart.requestFrom = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.smartStart.dskProvisioningList = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.smartStart.includingDSK = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.S2AutoInclude = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.S2AutoInclude.pin = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.S2AutoInclude.keys = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.longRange = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.longRange.enabled = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.longRange.channel = Empty
[2023-07-27 13:55:36.207] [D] [zway] SETDATA controller.data.longRange.inclusion = Empty
[2023-07-27 13:55:36.208] [D] [zway] SETDATA controller.data.functionClasses = int[74]
[2023-07-27 13:55:36.208] [D] [zway] [00] 2
[2023-07-27 13:55:36.208] [D] [zway] [01] 3
[2023-07-27 13:55:36.208] [D] [zway] [02] 4
[2023-07-27 13:55:36.208] [D] [zway] [03] 5
[2023-07-27 13:55:36.208] [D] [zway] [04] 6
[2023-07-27 13:55:36.208] [D] [zway] [05] 7
[2023-07-27 13:55:36.208] [D] [zway] [06] 8
[2023-07-27 13:55:36.208] [D] [zway] [07] 10
[2023-07-27 13:55:36.208] [D] [zway] [08] 11
[2023-07-27 13:55:36.208] [D] [zway] [09] 18
[2023-07-27 13:55:36.208] [D] [zway] [10] 19
[2023-07-27 13:55:36.208] [D] [zway] [11] 20
[2023-07-27 13:55:36.208] [D] [zway] [12] 21
[2023-07-27 13:55:36.208] [D] [zway] [13] 22
[2023-07-27 13:55:36.208] [D] [zway] [14] 23
[2023-07-27 13:55:36.208] [D] [zway] [15] 32
[2023-07-27 13:55:36.208] [D] [zway] [16] 33
[2023-07-27 13:55:36.208] [D] [zway] [17] 34
[2023-07-27 13:55:36.208] [D] [zway] [18] 35
[2023-07-27 13:55:36.208] [D] [zway] [19] 36
[2023-07-27 13:55:36.208] [D] [zway] [20] 39
[2023-07-27 13:55:36.208] [D] [zway] [21] 41
[2023-07-27 13:55:36.208] [D] [zway] [22] 42
[2023-07-27 13:55:36.208] [D] [zway] [23] 43
[2023-07-27 13:55:36.208] [D] [zway] [24] 44
[2023-07-27 13:55:36.208] [D] [zway] [25] 45
[2023-07-27 13:55:36.208] [D] [zway] [26] 57
[2023-07-27 13:55:36.208] [D] [zway] [27] 58
[2023-07-27 13:55:36.208] [D] [zway] [28] 59
[2023-07-27 13:55:36.208] [D] [zway] [29] 63
[2023-07-27 13:55:36.208] [D] [zway] [30] 65
[2023-07-27 13:55:36.208] [D] [zway] [31] 66
[2023-07-27 13:55:36.208] [D] [zway] [32] 68
[2023-07-27 13:55:36.208] [D] [zway] [33] 70
[2023-07-27 13:55:36.208] [D] [zway] [34] 71
[2023-07-27 13:55:36.209] [D] [zway] [35] 72
[2023-07-27 13:55:36.209] [D] [zway] [36] 73
[2023-07-27 13:55:36.209] [D] [zway] [37] 74
[2023-07-27 13:55:36.209] [D] [zway] [38] 75
[2023-07-27 13:55:36.209] [D] [zway] [39] 76
[2023-07-27 13:55:36.209] [D] [zway] [40] 77
[2023-07-27 13:55:36.209] [D] [zway] [41] 79
[2023-07-27 13:55:36.209] [D] [zway] [42] 80
[2023-07-27 13:55:36.209] [D] [zway] [43] 81
[2023-07-27 13:55:36.209] [D] [zway] [44] 82
[2023-07-27 13:55:36.209] [D] [zway] [45] 83
[2023-07-27 13:55:36.209] [D] [zway] [46] 84
[2023-07-27 13:55:36.209] [D] [zway] [47] 85
[2023-07-27 13:55:36.209] [D] [zway] [48] 86
[2023-07-27 13:55:36.209] [D] [zway] [49] 87
[2023-07-27 13:55:36.209] [D] [zway] [50] 88
[2023-07-27 13:55:36.209] [D] [zway] [51] 94
[2023-07-27 13:55:36.209] [D] [zway] [52] 95
[2023-07-27 13:55:36.209] [D] [zway] [53] 96
[2023-07-27 13:55:36.209] [D] [zway] [54] 97
[2023-07-27 13:55:36.209] [D] [zway] [55] 98
[2023-07-27 13:55:36.209] [D] [zway] [56] 99
[2023-07-27 13:55:36.209] [D] [zway] [57] 128
[2023-07-27 13:55:36.209] [D] [zway] [58] 146
[2023-07-27 13:55:36.209] [D] [zway] [59] 147
[2023-07-27 13:55:36.209] [D] [zway] [60] 186
[2023-07-27 13:55:36.209] [D] [zway] [61] 190
[2023-07-27 13:55:36.209] [D] [zway] [62] 208
[2023-07-27 13:55:36.209] [D] [zway] [63] 209
[2023-07-27 13:55:36.209] [D] [zway] [64] 210
[2023-07-27 13:55:36.209] [D] [zway] [65] 211
[2023-07-27 13:55:36.209] [D] [zway] [66] 218
[2023-07-27 13:55:36.209] [D] [zway] [67] 219
[2023-07-27 13:55:36.209] [D] [zway] [68] 220
[2023-07-27 13:55:36.209] [D] [zway] [69] 242
[2023-07-27 13:55:36.209] [D] [zway] [70] 243
[2023-07-27 13:55:36.209] [D] [zway] [71] 244
[2023-07-27 13:55:36.209] [D] [zway] [72] 245
[2023-07-27 13:55:36.209] [D] [zway] [73] 248
[2023-07-27 13:55:36.210] [D] [zway] SETDATA controller.data.functionClassesNames = string[74]
[2023-07-27 13:55:36.210] [D] [zway] [00] "SerialAPIGetInitData"
[2023-07-27 13:55:36.210] [D] [zway] [01] "SerialAPIApplicationNodeInformation"
[2023-07-27 13:55:36.210] [D] [zway] [02] "ApplicationCommandHandler"
[2023-07-27 13:55:36.210] [D] [zway] [03] "GetControllerCapabilities"
[2023-07-27 13:55:36.210] [D] [zway] [04] "SerialAPISetTimeouts"
[2023-07-27 13:55:36.210] [D] [zway] [05] "GetSerialAPICapabilities"
[2023-07-27 13:55:36.210] [D] [zway] [06] "SerialAPISoftReset"
[2023-07-27 13:55:36.210] [D] [zway] [07] "SerialAPIStarted"
[2023-07-27 13:55:36.210] [D] [zway] [08] "SerialAPISetup"
[2023-07-27 13:55:36.210] [D] [zway] [09] "SendNodeInformation"
[2023-07-27 13:55:36.210] [D] [zway] [10] "SendData"
[2023-07-27 13:55:36.210] [D] [zway] [11] "SendDataMulti"
[2023-07-27 13:55:36.210] [D] [zway] [12] "GetVersion"
[2023-07-27 13:55:36.210] [D] [zway] [13] "SendDataAbort"
[2023-07-27 13:55:36.210] [D] [zway] [14] "RFPowerLevelSet"
[2023-07-27 13:55:36.210] [D] [zway] [15] "GetHomeId"
[2023-07-27 13:55:36.210] [D] [zway] [16] "MemoryGetByte"
[2023-07-27 13:55:36.210] [D] [zway] [17] "MemoryPutByte"
[2023-07-27 13:55:36.210] [D] [zway] [18] "MemoryGetBuffer"
[2023-07-27 13:55:36.210] [D] [zway] [19] "MemoryPutBuffer"
[2023-07-27 13:55:36.210] [D] [zway] [20] "FlashAutoProgSet"
[2023-07-27 13:55:36.210] [D] [zway] [21] "NVMGetId"
[2023-07-27 13:55:36.210] [D] [zway] [22] "NVMExtReadLongBuffer"
[2023-07-27 13:55:36.210] [D] [zway] [23] "NVMExtWriteLongBuffer"
[2023-07-27 13:55:36.210] [D] [zway] [24] "NVMExtReadLongByte"
[2023-07-27 13:55:36.210] [D] [zway] [25] "NVMExtWriteLongByte"
[2023-07-27 13:55:36.210] [D] [zway] [26] "ClearNetworkStats"
[2023-07-27 13:55:36.210] [D] [zway] [27] "GetNetworkStats"
[2023-07-27 13:55:36.210] [D] [zway] [28] "GetBackgroundRSSI"
[2023-07-27 13:55:36.210] [D] [zway] [29] "RemoveNodeIdFromNetwork"
[2023-07-27 13:55:36.210] [D] [zway] [30] "GetNodeProtocolInformation"
[2023-07-27 13:55:36.210] [D] [zway] [31] "SetDefault"
[2023-07-27 13:55:36.210] [D] [zway] [32] "ReplicationReceiveComplete"
[2023-07-27 13:55:36.210] [D] [zway] [33] "AssignReturnRoute"
[2023-07-27 13:55:36.210] [D] [zway] [34] "DeleteReturnRoute"
[2023-07-27 13:55:36.210] [D] [zway] [35] "RequestNodeNeighbourUpdate"
[2023-07-27 13:55:36.210] [D] [zway] [36] "ApplicationNodeUpdate"
[2023-07-27 13:55:36.210] [D] [zway] [37] "AddNodeToNetwork"
[2023-07-27 13:55:36.211] [D] [zway] [38] "RemoveNodeFromNetwork"
[2023-07-27 13:55:36.211] [D] [zway] [39] "CreateNewPrimary"
[2023-07-27 13:55:36.211] [D] [zway] [40] "ControllerChange"
[2023-07-27 13:55:36.211] [D] [zway] [41] "AssignPriorityReturnRoute"
[2023-07-27 13:55:36.211] [D] [zway] [42] "SetLearnMode"
[2023-07-27 13:55:36.211] [D] [zway] [43] "AssignSUCReturnRoute"
[2023-07-27 13:55:36.211] [D] [zway] [44] "EnableSUC"
[2023-07-27 13:55:36.211] [D] [zway] [45] "RequestNetworkUpdate"
[2023-07-27 13:55:36.211] [D] [zway] [46] "SetSUCNodeId"
[2023-07-27 13:55:36.211] [D] [zway] [47] "DeleteSUCReturnRoute"
[2023-07-27 13:55:36.211] [D] [zway] [48] "GetSUCNodeId"
[2023-07-27 13:55:36.211] [D] [zway] [49] "SendSUCNodeId"
[2023-07-27 13:55:36.211] [D] [zway] [50] "AssignPrioritySUC ReturnRoute"
[2023-07-27 13:55:36.211] [D] [zway] [51] "ExploreRequestInclusion"
[2023-07-27 13:55:36.211] [D] [zway] [52] "ExploreRequestExclusion"
[2023-07-27 13:55:36.211] [D] [zway] [53] "RequestNodeInformation"
[2023-07-27 13:55:36.211] [D] [zway] [54] "RemoveFailedNode"
[2023-07-27 13:55:36.211] [D] [zway] [55] "IsFailedNode"
[2023-07-27 13:55:36.211] [D] [zway] [56] "ReplaceFailedNode"
[2023-07-27 13:55:36.211] [D] [zway] [57] "GetRoutingTableLine"
[2023-07-27 13:55:36.211] [D] [zway] [58] "GetPriorityRoute"
[2023-07-27 13:55:36.211] [D] [zway] [59] "SetPriorityRoute"
[2023-07-27 13:55:36.211] [D] [zway] [60] "RFPowerLevelGet"
[2023-07-27 13:55:36.211] [D] [zway] [61] "SendTestFrame"
[2023-07-27 13:55:36.211] [D] [zway] [62] "SetPromiscuousMode"
[2023-07-27 13:55:36.211] [D] [zway] [63] "PromiscuousCommandHandler"
[2023-07-27 13:55:36.211] [D] [zway] [64] "WatchDogStart"
[2023-07-27 13:55:36.211] [D] [zway] [65] "WatchDogStop"
[2023-07-27 13:55:36.211] [D] [zway] [66] "GetLongRangeNodes"
[2023-07-27 13:55:36.211] [D] [zway] [67] "GetLongRangeChannel"
[2023-07-27 13:55:36.211] [D] [zway] [68] "SetLongRangeChannel"
[2023-07-27 13:55:36.211] [D] [zway] [69] "ZMEFreqChange"
[2023-07-27 13:55:36.211] [D] [zway] [70] "ZMERestore"
[2023-07-27 13:55:36.211] [D] [zway] [71] "ZMEBootloaderFlash"
[2023-07-27 13:55:36.211] [D] [zway] [72] "ZMECapabilities"
[2023-07-27 13:55:36.211] [D] [zway] [73] "ZMESerialAPIOptions"
[2023-07-27 13:55:36.212] [D] [zway] Opened device: /dev/ttyAMA0
[2023-07-27 13:55:36.212] [I] [i/o] Setting port speed to 115200
[2023-07-27 13:55:36.212] [D] [zway] Worker thread successfully created
[2023-07-27 13:55:36.212] [D] [zway] Worker thread entry point
[2023-07-27 13:55:36.212] [I] [zway] Adding job: Get controller info and supported function classes
[2023-07-27 13:55:36.222] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 13:55:36.424] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:55:36.424] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 13:55:36.625] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:55:36.625] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 13:55:36.827] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:55:36.827] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2023-07-27 13:55:36.827] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2023-07-27 13:55:36.827] [C] [zway] Get Serial API Capabilities returned zero.
[2023-07-27 13:55:36.827] [I] [zway] Removing job: Get controller info and supported function classes
[2023-07-27 13:55:36.838] [D] [zway] Worker thread exit point
[2023-07-27 13:55:36.838] [D] [zway] Worker thread successfully finished
[2023-07-27 13:55:36.838] [D] [i/o] Closing port
[2023-07-27 13:55:36.849] [I] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2023-07-27 13:55:36.854] [I] [core] Error: Bad data - communication failed
[2023-07-27 13:55:36.872] [E] [HTTP] Callback execution error: TypeError: Cannot read property 'zway' of undefined
at AutomationController.zwayReg (automation/modules/SwitchControlGenerator/index.js:69:52)
at AutomationController.EventEmitter.emit (automation/lib/eventemitter2.js:311:33)
at ZWave.init (automation/modules/ZWave/index.js:237:18)
at AutomationController.reconfigureInstance (automation/classes/AutomationController.js23)
at ZAutomationAPIWebRequest._.extend.reconfigureInstanceFunc (automation/ZAutomationAPIProvider.js31)
at ZAutomationAPIWebRequest.handlerFunc (automation/ZAutomationAPIProvider.js:4017:30)
at ZAutomationAPIWebRequest.ZAutomationWebRequest.handleRequest (automation/WebserverRequestRouter.js:253:35)
at automation/WebserverRequestRouter.js:32:29
at WebServer.ws.execute (automation/Webserver.js:127:9)
at WebServer.document_root (automation/Webserver.js:26:34)

[MyZip]

I've tried a restart.

/var/log/z-way-server

[2023-07-27 14:22:49.952] [D] [zway] Opened device: /dev/ttyAMA0
[2023-07-27 14:22:49.952] [i/o] Setting port speed to 115200
[2023-07-27 14:22:49.952] [D] [zway] Worker thread successfully created
[2023-07-27 14:22:49.953] [D] [zway] Worker thread entry point
[2023-07-27 14:22:49.953] [zway] Adding job: Get controller info and supported function classes
[2023-07-27 14:22:49.963] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 14:22:50.165] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 14:22:50.165] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 14:22:50.366] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 14:22:50.366] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 14:22:50.568] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 14:22:50.568] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2023-07-27 14:22:50.568] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2023-07-27 14:22:50.568] [C] [zway] Get Serial API Capabilities returned zero.
[2023-07-27 14:22:50.568] [zway] Removing job: Get controller info and supported function classes
[2023-07-27 14:22:50.578] [D] [zway] Worker thread exit point
[2023-07-27 14:22:50.578] [D] [zway] Worker thread successfully finished
[2023-07-27 14:22:50.578] [D] [i/o] Closing port
[2023-07-27 14:22:50.589] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2023-07-27 14:22:50.594] [core] Error: Bad data - communication failed

[seattleneil]

Eek. Something is definitely wrong with your system. The output you've provided shows the z-way-server process is unable to communicate to the z-way card. It could be a software problem or a hardware problem.

Since I don't know the steps you took to re-install the OS or install Z-Way, to rule out a software problem please consider re-installing the software using the zip file for "Raspberry Pi SD card image with pre-installed Z-Way" from https://storage.z-wave.me/z-way-server/ This should bring the software in your system to a known, working state. If you continue to see log file error messages similar to the following, then there's a good chance the z-way card has stopped working.

Code: Select all

[2023-07-27 13:55:36.625] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:55:36.625] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-07-27 13:55:36.827] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-07-27 13:55:36.827] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2023-07-27 13:55:36.827] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2023-07-27 13:55:36.827] [C] [zway] Get Serial API Capabilities returned zero.
[2023-07-27 13:55:36.827] [I] [zway] Removing job: Get controller info and supported function classes
[2023-07-27 13:55:36.838] [D] [zway] Worker thread exit point
[2023-07-27 13:55:36.838] [D] [zway] Worker thread successfully finished
[2023-07-27 13:55:36.838] [D] [i/o] Closing port
[2023-07-27 13:55:36.849] [I] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2023-07-27 13:55:36.854] [I] [core] Error: Bad data - communication failed

Assuming the z-way-server process runs successfully using the Z-Way SD card image, you can try restoring the .zab backup file. Note that when you use the freshly installed image, you won't see any devices. If you're interested in an interim step, restoring the .zbk file should let you see/control your Z-Wave devices from the expert UI.

[MyZip]

I tried to purge and reinstall z-way-server but nothing changed. So I just ordered a new sd card to test the Razberry with the prepared image file. I'll let you know.

[piet66]

In my experience, it's always a good idea to check the integrity of SD cards and USB drives before using them.
On Windows you can use H2testw, on Linux f3 (sudo apt-get install f3).

[PoltoS]

No ACK means there is no connection with the RaZberry board. Check if it works (LEDs on start and then use a USB-to-Serial adapter to check it - if it is a RaZberry issue or the wrong port config on the PRi)

[MyZip]

I bought a new Razberry 2 (the blue one) and everything worked fine but after about 3 days it stopped working (from one minute to another). Now I keep getting the same error as with the old board. The two leds (led 2 and led 3) both remain on.

Code: Select all

[2023-08-06 15:53:35.975] [D] [zway] Opened device: /dev/ttyAMA0
[2023-08-06 15:53:35.975] [I] [i/o] Setting port speed to 115200
[2023-08-06 15:53:35.975] [D] [zway] Worker thread successfully created
[2023-08-06 15:53:35.975] [D] [zway] Worker thread entry point
[2023-08-06 15:53:35.975] [I] [zway] Adding job: Get controller info and supported function classes
[2023-08-06 15:53:35.986] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-08-06 15:53:36.187] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-08-06 15:53:36.187] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-08-06 15:53:36.389] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-08-06 15:53:36.389] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2023-08-06 15:53:36.591] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2023-08-06 15:53:36.591] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2023-08-06 15:53:36.591] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2023-08-06 15:53:36.591] [C] [zway] Get Serial API Capabilities returned zero.
[2023-08-06 15:53:36.591] [I] [zway] Removing job: Get controller info and supported function classes
[2023-08-06 15:53:36.601] [D] [zway] Worker thread exit point
[2023-08-06 15:53:36.601] [D] [zway] Worker thread successfully finished
[2023-08-06 15:53:36.601] [D] [i/o] Closing port
[2023-08-06 15:53:36.612] [I] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2023-08-06 15:53:36.617] [I] [core] Error: Bad data - communication failed

[seattleneil]

In normal operation, the red LED will light up steady when the Z-Way board is in inclusion or exclusion mode and the green LED will flicker when the board is transmitting.

Having both LEDs remain on is a sign that the board is not operating correctly. Once that happens, run sudo poweroff and cycle power on the Pi after waiting 30 seconds for the poweroff command to finish. Re-starting the z-way-server process is not sufficient for clearing a fault in the board.

When power is re-applied, both LEDs should light up for 1-2 seconds, and then turn off. If the board operates correctly for several days, and then suddenly stop working with both LEDs lit, the board could have an overheating problem. Does the processor chip feel hot to the touch? If the chip feels hot, the board likely has to be replaced. If the LEDs remain lit for more than 3 seconds after power is applied, the board is defective and has to be replaced.

Another possible cause for the board failing after 3 days is a bad/undersized power supply.