server fails after automatic snap update

[eil047]

I am not an experienced programmer :) On 21Dec2022 I successfully installed Zwave JS UI with Snap on my RPi4B following this post (https://community.home-assistant.io/t/install-and-setup-zwave-js-ui-with-snap-on-a-raspberry-pi/486484) Everything was running smoothly until suddenly on 4Jun2023 my Z-Wave JS UI didn't function anymore and showed this error message: Driver: Failed to open the serial port: Error: Operation not permitted, cannot open /dev/ttyUSB0 (ZW0100) Error: Driver: Failed to open the serial port: Error: Operation not permitted, cannot open /dev/ttyUSB0 (ZW0100) In trying to understand/solve this issue I noticed that there has been a snap update on the very moment of the start of my issue (4Jun2023)? For the moment I haven't had physical access to my RPi yet so I haven't been able to pull out/re-insert my Z-Stick 7 to see if this would solve the problem. The log file constantly repeats these lines 2023-06-24T09:07:12.674Z DRIVER ███████╗ ██╗ ██╗ █████╗ ██╗ ██╗ ███████╗ ██╗ ███████╗ ╚══███╔╝ ██║ ██║ ██╔══██╗ ██║ ██║ ██╔════╝ ██║ ██╔════╝ ███╔╝ ██║ █╗ ██║ ███████║ ██║ ██║ █████╗ █████╗ ██║ ███████╗ ███╔╝ ██║███╗██║ ██╔══██║ ╚██╗ ██╔╝ ██╔══╝ ╚════╝ ██ ██║ ╚════██║ ███████╗ ╚███╔███╔╝ ██║ ██║ ╚████╔╝ ███████╗ ╚█████╔╝ ███████║ ╚══════╝ ╚══╝╚══╝ ╚═╝ ╚═╝ ╚═══╝ ╚══════╝ ╚════╝ ╚══════╝ 2023-06-24T09:07:12.675Z DRIVER version 10.23.2 2023-06-24T09:07:12.675Z DRIVER
2023-06-24T09:07:12.675Z DRIVER starting driver... 2023-06-24T09:07:12.679Z DRIVER opening serial port /dev/ttyUSB0 2023-06-24T09:07:21.696Z DRIVER Failed to open the serial port: Error: Operation not permitted, cannot open /d ev/ttyUSB0 2023-06-24T09:07:21.697Z DRIVER destroying driver instance... 2023-06-24T09:07:21.697Z DRIVER driver instance destroyed

Thanks for your suggestions to solve/prevent these kind of issues, don't hesitate to ask for more information and I apologize for my lack of knowledge and if this would only be a trivial problem Luc

[jmgiaever]

Hi. It can be that your stick is simply stuck, and that you need to plug it out and insert it again. I assume you have tried to reboot your system?

[eil047]

Hi, thanks for your reply and yes I have rebooted several times but without any change. Plugging the stick out and in again I can only do next week. I'll let you know if this solves the problem.

[jmgiaever]

Hi,

Do you have access to the logs from the previous revision, before the upgrade? It should be located in the folder /var/snap/zwave-js-ui folder under the second highest revision number.

Can you paste the last 100 lines of this log, just before the upgrade started?

[eil047]

Hi, I think this is what you ask for, if not, ask again :)

2023-06-03T22:00:00.001Z DRIVER Checking for configuration updates... 2023-06-03T22:00:00.977Z DRIVER No configuration update available... 2023-06-03T22:00:07.495Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:00:07.496Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:00:07.502Z SERIAL « [ACK] (0x06) 2023-06-03T22:00:07.504Z SERIAL « 0x0107013b9a96967f27 (9 bytes) 2023-06-03T22:00:07.506Z SERIAL » [ACK] (0x06) 2023-06-03T22:00:07.529Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -102 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:00:37.487Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:00:37.487Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:00:37.492Z SERIAL « [ACK] (0x06) 2023-06-03T22:00:37.493Z SERIAL « 0x0107013b9a97977f27 (9 bytes) 2023-06-03T22:00:37.494Z SERIAL » [ACK] (0x06) 2023-06-03T22:00:37.505Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -102 dBm channel 1: -105 dBm channel 2: -105 dBm 2023-06-03T22:01:07.493Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:01:07.494Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:01:07.507Z SERIAL « [ACK] (0x06) 2023-06-03T22:01:07.509Z SERIAL « 0x0107013b9b96967f26 (9 bytes) 2023-06-03T22:01:07.511Z SERIAL » [ACK] (0x06) 2023-06-03T22:01:07.533Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:01:37.494Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:01:37.495Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:01:37.501Z SERIAL « [ACK] (0x06) 2023-06-03T22:01:37.503Z SERIAL « 0x0107013b9b96967f26 (9 bytes) 2023-06-03T22:01:37.505Z SERIAL » [ACK] (0x06) 2023-06-03T22:01:37.528Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:02:07.495Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:02:07.496Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:02:07.502Z SERIAL « [ACK] (0x06) 2023-06-03T22:02:07.504Z SERIAL « 0x0107013b9a95957f27 (9 bytes) 2023-06-03T22:02:07.506Z SERIAL » [ACK] (0x06) 2023-06-03T22:02:07.523Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -102 dBm channel 1: -107 dBm channel 2: -107 dBm 2023-06-03T22:02:37.486Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:02:37.487Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:02:37.491Z SERIAL « [ACK] (0x06) 2023-06-03T22:02:37.493Z SERIAL « 0x0107013b9b95957f26 (9 bytes) 2023-06-03T22:02:37.494Z SERIAL » [ACK] (0x06) 2023-06-03T22:02:37.504Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -107 dBm channel 2: -107 dBm 2023-06-03T22:03:07.492Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:03:07.493Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:03:07.498Z SERIAL « [ACK] (0x06) 2023-06-03T22:03:07.500Z SERIAL « 0x0107013b9a96967f27 (9 bytes) 2023-06-03T22:03:07.503Z SERIAL » [ACK] (0x06) 2023-06-03T22:03:07.522Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -102 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:03:37.493Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:03:37.494Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:03:37.500Z SERIAL « [ACK] (0x06) 2023-06-03T22:03:37.502Z SERIAL « 0x0107013b9b96967f26 (9 bytes) 2023-06-03T22:03:37.504Z SERIAL » [ACK] (0x06) 2023-06-03T22:03:37.532Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:03:57.788Z SERIAL « 0x010f00a8000118063105012200b300b350 (17 bytes) 2023-06-03T22:03:57.790Z SERIAL » [ACK] (0x06) 2023-06-03T22:03:57.792Z CNTRLR [Node 024] [Multilevel Sensor] Air temperature: metadata updated [Endpoint 0] 2023-06-03T22:03:57.794Z CNTRLR [Node 024] [~] [Multilevel Sensor] Air temperature: 18.1 => 17.9 [Endpoint 0] 2023-06-03T22:03:57.799Z DRIVER « [Node 024] [REQ] [BridgeApplicationCommand] │ RSSI: -77 dBm └─[MultilevelSensorCCReport] type: Air temperature scale: Celsius value: 17.9 2023-06-03T22:04:07.493Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:04:07.494Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:04:07.500Z SERIAL « [ACK] (0x06) 2023-06-03T22:04:07.502Z SERIAL « 0x0107013b9b95957f26 (9 bytes) 2023-06-03T22:04:07.504Z SERIAL » [ACK] (0x06) 2023-06-03T22:04:07.527Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -107 dBm channel 2: -107 dBm 2023-06-03T22:04:37.487Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:04:37.488Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:04:37.493Z SERIAL « [ACK] (0x06) 2023-06-03T22:04:37.494Z SERIAL « 0x0107013b9a96967f27 (9 bytes) 2023-06-03T22:04:37.495Z SERIAL » [ACK] (0x06) 2023-06-03T22:04:37.504Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -102 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:05:07.494Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:05:07.496Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:05:07.511Z SERIAL « [ACK] (0x06) 2023-06-03T22:05:07.514Z SERIAL « 0x0107013b9a96967f27 (9 bytes) 2023-06-03T22:05:07.515Z SERIAL » [ACK] (0x06) 2023-06-03T22:05:07.530Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -102 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:05:37.494Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:05:37.495Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:05:37.501Z SERIAL « [ACK] (0x06) 2023-06-03T22:05:37.503Z SERIAL « 0x0107013b9b95957f26 (9 bytes) 2023-06-03T22:05:37.505Z SERIAL » [ACK] (0x06) 2023-06-03T22:05:37.527Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -107 dBm channel 2: -107 dBm 2023-06-03T22:06:07.488Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:06:07.489Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:06:07.494Z SERIAL « [ACK] (0x06) 2023-06-03T22:06:07.495Z SERIAL « 0x0107013b9b96967f26 (9 bytes) 2023-06-03T22:06:07.497Z SERIAL » [ACK] (0x06) 2023-06-03T22:06:07.508Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:06:37.487Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:06:37.487Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:06:37.492Z SERIAL « [ACK] (0x06) 2023-06-03T22:06:37.494Z SERIAL « 0x0107013b9b96967f26 (9 bytes) 2023-06-03T22:06:37.494Z SERIAL » [ACK] (0x06) 2023-06-03T22:06:37.505Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:07:07.487Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T22:07:07.488Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T22:07:07.493Z SERIAL « [ACK] (0x06) 2023-06-03T22:07:07.494Z SERIAL « 0x0107013b9b96967f26 (9 bytes) 2023-06-03T22:07:07.495Z SERIAL » [ACK] (0x06) 2023-06-03T22:07:07.506Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -101 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T22:08:05.559Z DRIVER ███████╗ ██╗ ██╗ █████╗ ██╗ ██╗ ███████╗ ██╗ ███████╗ ╚══███╔╝ ██║ ██║ ██╔══██╗ ██║ ██║ ██╔════╝ ██║ ██╔════╝ ███╔╝ ██║ █╗ ██║ ███████║ ██║ ██║ █████╗ █████╗ ██║ ███████╗ ███╔╝ ██║███╗██║ ██╔══██║ ╚██╗ ██╔╝ ██╔══╝ ╚════╝ ██ ██║ ╚════██║ ███████╗ ╚███╔███╔╝ ██║ ██║ ╚████╔╝ ███████╗ ╚█████╔╝ ███████║ ╚══════╝ ╚══╝╚══╝ ╚═╝ ╚═╝ ╚═══╝ ╚══════╝ ╚════╝ ╚══════╝ 2023-06-03T22:08:05.562Z DRIVER version 10.22.2 2023-06-03T22:08:05.562Z DRIVER
2023-06-03T22:08:05.563Z DRIVER starting driver... 2023-06-03T22:08:05.590Z DRIVER opening serial port /dev/ttyUSB0 2023-06-03T22:08:14.627Z DRIVER Failed to open the serial port: Error: Operation not permitted, cannot open /d ev/ttyUSB0 2023-06-03T22:08:14.632Z DRIVER destroying driver instance... 2023-06-03T22:08:14.640Z DRIVER driver instance destroyed 2023-06-03T22:08:17.662Z DRIVER ███████╗ ██╗ ██╗ █████╗ ██╗ ██╗ ███████╗ ██╗ ███████╗ ╚══███╔╝ ██║ ██║ ██╔══██╗ ██║ ██║ ██╔════╝ ██║ ██╔════╝ ███╔╝ ██║ █╗ ██║ ███████║ ██║ ██║ █████╗ █████╗ ██║ ███████╗ ███╔╝ ██║███╗██║ ██╔══██║ ╚██╗ ██╔╝ ██╔══╝ ╚════╝ ██ ██║ ╚════██║ ███████╗ ╚███╔███╔╝ ██║ ██║ ╚████╔╝ ███████╗ ╚█████╔╝ ███████║ ╚══════╝ ╚══╝╚══╝ ╚═╝ ╚═╝ ╚═══╝ ╚══════╝ ╚════╝ ╚══════╝ 2023-06-03T22:08:17.664Z DRIVER version 10.22.2 2023-06-03T22:08:17.665Z DRIVER
2023-06-03T22:08:17.666Z DRIVER starting driver... 2023-06-03T22:08:17.680Z DRIVER opening serial port /dev/ttyUSB0 2023-06-03T22:08:26.715Z DRIVER Failed to open the serial port: Error: Operation not permitted, cannot open /d ev/ttyUSB0 2023-06-03T22:08:26.717Z DRIVER destroying driver instance... 2023-06-03T22:08:26.719Z DRIVER driver instance destroyed

[jmgiaever]

Hi,

Doesn't seem there's much activity in that log file. Can you search the log files in that same directory for the search terms

• «Failed to execute controller command»
• «Timeout while waiting for an ACK from the controller»

[jmgiaever]

E.g grep -rnw '/var/snap/zwave-js-ui/<revision>' -e '<search term>'

Replace revision and search term

[eil047]

Hi Joachim, in the meantime I had the chance to plug the stick out and in again, but the problem remains the same :( Via the grep I couldn't find any «Timeout while waiting for an ACK from the controller» lines but several «Failed to execute controller command» Below you find the last appearance of such a line and I added some more lines before and after... Don't hesitate if you need more information to solve this issue Thanks! Luc

2023-06-03T09:55:48.148Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T09:55:48.150Z SERIAL « 0x010f00a8000118063105030a000300b3ca (17 bytes) 2023-06-03T09:55:48.155Z SERIAL » [ACK] (0x06) 2023-06-03T09:55:48.161Z CNTRLR [Node 024] [Multilevel Sensor] Illuminance: metadata updated [Endpoint 0] 2023-06-03T09:55:48.164Z CNTRLR [Node 024] [~] [Multilevel Sensor] Illuminance: 2 => 3 [Endpoint 0] 2023-06-03T09:55:48.168Z DRIVER « [Node 024] [REQ] [BridgeApplicationCommand] │ RSSI: -77 dBm └─[MultilevelSensorCCReport] type: Illuminance scale: Lux value: 3 2023-06-03T09:55:48.181Z SERIAL « [CAN] (0x18) 2023-06-03T09:55:48.185Z CNTRLR Failed to execute controller command after 1/3 attempts. Scheduling next try i n 100 ms. 2023-06-03T09:55:48.286Z SERIAL » 0x0103003bc7 (5 bytes) 2023-06-03T09:55:48.287Z DRIVER » [REQ] [GetBackgroundRSSI] 2023-06-03T09:55:48.291Z SERIAL « [ACK] (0x06) 2023-06-03T09:55:48.292Z SERIAL « 0x0107013b9c96967f21 (9 bytes) 2023-06-03T09:55:48.293Z SERIAL » [ACK] (0x06) 2023-06-03T09:55:48.293Z DRIVER « [RES] [GetBackgroundRSSI] channel 0: -100 dBm channel 1: -106 dBm channel 2: -106 dBm 2023-06-03T09:56:18.149Z SERIAL » 0x0103003bc7

[jmgiaever]

Closing as I think this issue doesnt seem to affect anyone.