[bug] zwavejs 5.0.0 doesn't run #1326

yajrendrag · 2021-06-10T22:00:34Z

Version

Checklist:

I am not using HomeAssistant. Or: a developer has told me to come here. - i opened at HomeAssistant and they instructed me to open issue here
I have checked the troubleshooting section and my problem is not described there.
I have read the changelog problem was not mentioned there.

Build/Run method

Docker
PKG
Snap package
Manually built (git clone - yarn install - yarn run build )

zwavejs2mqtt version: 5.0.0
zwave-js version: 7.7.3

Describe the bug

zwavejs2mqtt doesn't start

Error in logfile:

Writing the device index to disk failed: File or directory '/**/zwavejs2mqtt/node_modules/@zwave-js/config/config/devices/index.json' was not included into executable at compilation stage. Please recompile adding it as asset or script

Results of systemctl status zwavejs2mqtt:
● zwavejs2mqtt.service - zwavejs2mqtt server
Loaded: loaded (/etc/systemd/system/zwavejs2mqtt.service; enabled; vendor preset: enabled)
Active: failed (Result: exit-code) since Thu 2021-06-10 13:52:27 MDT; 11s ago
Process: 45269 ExecStart=/home/jhass/zwavejs2mqtt/zwavejs2mqtt (code=exited, status=1/FAILURE)
Main PID: 45269 (code=exited, status=1/FAILURE)

Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at Object.Module._extensions..node (internal/modules/cjs/loader.js:1248:18)
Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at Module.load (internal/modules/cjs/loader.js:1047:32)
Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at Function.Module._load (internal/modules/cjs/loader.js:935:14)
Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at Module.require (internal/modules/cjs/loader.js:1087:19)
Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at Module.require (pkg/prelude/bootstrap.js:1338:31)
Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at require (internal/modules/cjs/helpers.js:73:18)
Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at bindings (/snapshot/zwavejs2mqtt/node_modules/bindings/bindings.js:112:48)
Jun 10 13:52:27 hass zwavejs2mqtt[45269]: at Object. (/snapshot/zwavejs2mqtt/node_modules/@serialport/bindings/lib/linux.js:2:3
Jun 10 13:52:27 hass systemd[1]: zwavejs2mqtt.service: Main process exited, code=exited, status=1/FAILURE
Jun 10 13:52:27 hass systemd[1]: zwavejs2mqtt.service: Failed with result 'exit-code'.


### To Reproduce

Steps to reproduce the behavior:

1. stop zwavejs2mqtt
2. upgrade to zwavejs2mqtt v5.0.0 by unzipping zwavejs2mqtt-v5.0.0.zip
3. start zwavejs2mqtt (i have a systemd unit file, so i simply do systemctl start zwavejs2mqtt

### Expected behavior

zwavejs2mqtt should be running when i check via `pgrep zwavejs2mqtt`
it isn't running, however.

### Additional context

Reinstalling zwavejs2mqtt v 4.5.2 runs fine.

- zwavejs2mqtt is installed on my hass machine via git clone from the repository
- first i stop zwavejs2mqtt
- i simply unzip and overwrite bindings.node and zwavejs2mqtt executable
- then i restart by `systemctl start zwavejs2mqtt`
- contents of my systemd unit file:

[Unit]
Description=zwavejs2mqtt server
After=network-online.target

[Service]
Type=simple
User=jhass
WorkingDirectory=/home/jhass/zwavejs2mqtt
ExecStart=/home/jhass/zwavejs2mqtt/zwavejs2mqtt

[Install]
WantedBy=multi-user.target

The text was updated successfully, but these errors were encountered:

RualdOrdelman · 2021-06-11T07:24:10Z

@robertsLando Maybe it's the same issue. I did a container recreate to update to release 5.00. After that upgrade the containers starts successful but ZwaveJs2Mqtt isn't running. I saw the following error in de log in the Docker container

`Error: Cannot find module '/usr/src/app/bin/www'

at Function.Module._resolveFilename (node:internal/modules/cjs/loader:924:15)

at Function.Module._load (node:internal/modules/cjs/loader:769:27)

at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:76:12)

at node:internal/main/run_main_module:17:47 {

code: 'MODULE_NOT_FOUND',

requireStack: []

}

node:internal/modules/cjs/loader:927

throw err;

^
`

robertsLando · 2021-06-11T07:47:31Z

@RualdOrdelman Could you try to remove the container, drop the local z2m image and pull a fresh new one using latest tag?

robertsLando · 2021-06-11T07:50:54Z

@yajrendrag Please show me all the steps you are doing to run z2m. I think you may have forgot to run npm install or npm run build? Or are you using pkg version?

yajrendrag · 2021-06-11T11:48:46Z

i am running a package version. i simply unzip it - it overwrites the bindings.node and zwavejs2mqtt executable, then i start with systemctl. (see initial post additional context section for my systemd unit file). BTW, i do have a 2nd instance that i run in docker and that one runs fine (& i updated that one like you suggest avboce - removing container, dropping the image and then pull a new one with latest tag).

Fixes #1326

yajrendrag · 2021-06-15T14:51:31Z

tried the fix - still doesn't work... different error message now:

log file content after installing release 5.01:

2021-06-15T06:50:12.618Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.
2021-06-15T07:49:11.503Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.
2021-06-15T08:48:10.217Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.
2021-06-15T09:47:12.632Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.
2021-06-15T10:46:11.506Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.
2021-06-15T11:45:12.128Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.
2021-06-15T12:44:10.179Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.
2021-06-15T13:43:10.395Z CNTRLR   [Node 071] The node did not respond after 1 attempts.
                                  It is probably asleep, moving its messages to the wakeup queue.

systemctl status message:

root@hass:/home/jhass/zwavejs2mqtt# systemctl status zwavejs2mqtt
● zwavejs2mqtt.service - zwavejs2mqtt server
   Loaded: loaded (/etc/systemd/system/zwavejs2mqtt.service; enabled; vendor preset: enabled)
   Active: failed (Result: exit-code) since Tue 2021-06-15 08:46:16 MDT; 56s ago
  Process: 265 ExecStart=/home/jhass/zwavejs2mqtt/zwavejs2mqtt (code=exited, status=1/FAILURE)
 Main PID: 265 (code=exited, status=1/FAILURE)

Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at Object.Module._extensions..node (internal/modules/cjs/loader.js:1248:18)
Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at Module.load (internal/modules/cjs/loader.js:1047:32)
Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at Function.Module._load (internal/modules/cjs/loader.js:935:14)
Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at Module.require (internal/modules/cjs/loader.js:1087:19)
Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at Module.require (pkg/prelude/bootstrap.js:1338:31)
Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at require (internal/modules/cjs/helpers.js:73:18)
Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at bindings (/snapshot/zwavejs2mqtt/node_modules/bindings/bindings.js:112:48)
Jun 15 08:46:16 hass zwavejs2mqtt[265]:     at Object.<anonymous> (/snapshot/zwavejs2mqtt/node_modules/@serialport/bindings/lib/linux.js:2:36)
Jun 15 08:46:16 hass systemd[1]: zwavejs2mqtt.service: Main process exited, code=exited, status=1/FAILURE
Jun 15 08:46:16 hass systemd[1]: zwavejs2mqtt.service: Failed with result 'exit-code'.

robertsLando · 2021-06-15T15:17:46Z

@yajrendrag Could you please share the complete log output of the service?

yajrendrag · 2021-06-15T15:37:31Z

sure, but need a little help understanding what you mean by "complete log output" - are you looking for store/zwavejs_[date].log or something else/additional? if just that file, then above output was the complete output in the logfile after upgrading, but am happy to do it again... there are other log files in the store directory, but am thinking some may be old... as well as additional content in syslog..

robertsLando · 2021-06-15T15:52:08Z

Actually also the outupt you attached in your previous command is trimmed. I mean systemctl status zwavejs2mqtt command. I'm working on this btw in #1346 but as you can see it needs a fix from pkg package

yajrendrag · 2021-06-15T16:00:57Z

ok, will be back in front of it in about 6 hours from now and will send then..

yajrendrag · 2021-06-15T23:40:27Z

ok, here's a shell session which should show everything you're looking for...

root@hass:/home/jhass/zwavejs2mqtt# systemctl stop zwavejs2mqtt
root@hass:/home/jhass/zwavejs2mqtt# date
Tue Jun 15 17:32:13 MDT 2021
root@hass:/home/jhass/zwavejs2mqtt# pgrep zwavejs2mqtt
root@hass:/home/jhass/zwavejs2mqtt# unzip zwavejs2mqtt-v5.0.1.zip
Archive:  zwavejs2mqtt-v5.0.1.zip
replace bindings.node? [y]es, [n]o, [A]ll, [N]one, [r]ename: y
  inflating: bindings.node           
replace zwavejs2mqtt? [y]es, [n]o, [A]ll, [N]one, [r]ename: y
  inflating: zwavejs2mqtt            
root@hass:/home/jhass/zwavejs2mqtt# systemctl start zwavejs2mqtt
root@hass:/home/jhass/zwavejs2mqtt# pgrep zwavejs2mqtt
root@hass:/home/jhass/zwavejs2mqtt# systemctl status zwavejs2mqtt
● zwavejs2mqtt.service - zwavejs2mqtt server
   Loaded: loaded (/etc/systemd/system/zwavejs2mqtt.service; enabled; vendor preset: enabled)
   Active: failed (Result: exit-code) since Tue 2021-06-15 17:32:57 MDT; 18s ago
  Process: 5859 ExecStart=/home/jhass/zwavejs2mqtt/zwavejs2mqtt (code=exited, status=1/FAILURE)
 Main PID: 5859 (code=exited, status=1/FAILURE)

Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Object.Module._extensions..node (internal/modules/cjs/loader.js:1248:18)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Module.load (internal/modules/cjs/loader.js:1047:32)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Function.Module._load (internal/modules/cjs/loader.js:935:14)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Module.require (internal/modules/cjs/loader.js:1087:19)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Module.require (pkg/prelude/bootstrap.js:1338:31)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at require (internal/modules/cjs/helpers.js:73:18)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at bindings (/snapshot/zwavejs2mqtt/node_modules/bindings/bindings.js:112:48)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Object.<anonymous> (/snapshot/zwavejs2mqtt/node_modules/@serialport/bindings/lib/linux.js:2:36)
Jun 15 17:32:57 hass systemd[1]: zwavejs2mqtt.service: Main process exited, code=exited, status=1/FAILURE
Jun 15 17:32:57 hass systemd[1]: zwavejs2mqtt.service: Failed with result 'exit-code'.
root@hass:/home/jhass/zwavejs2mqtt# 
root@hass:/home/jhass/zwavejs2mqtt# 
root@hass:/home/jhass/zwavejs2mqtt# date
Tue Jun 15 17:33:24 MDT 2021
root@hass:/home/jhass/zwavejs2mqtt#

There are a couple of lines in syslog from the same time as the systemctl status command - that don't appear in the output of systemctl status - here's the full list from syslog - it's the 1st 8 lines that don't appear in the systemctl status command:

Jun 15 17:32:57 hass systemd[1]: zwavejs2mqtt.service: Failed to reset devices.list: Operation not permitted
Jun 15 17:32:57 hass systemd[1]: Started zwavejs2mqtt server.
Jun 15 17:32:57 hass zwavejs2mqtt[5859]: pkg/prelude/bootstrap.js:1359
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:       throw error;
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:       ^
Jun 15 17:32:57 hass zwavejs2mqtt[5859]: Error: /lib/x86_64-linux-gnu/libc.so.6: version `GLIBC_2.28' not found (required by /home/jhass/zwavejs2mqtt/bindings.node)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at tryImporting (pkg/prelude/bootstrap.js:1736:37)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at process.dlopen (pkg/prelude/bootstrap.js:1762:5)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Object.Module._extensions..node (internal/modules/cjs/loader.js:1248:18)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Module.load (internal/modules/cjs/loader.js:1047:32)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Function.Module._load (internal/modules/cjs/loader.js:935:14)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Module.require (internal/modules/cjs/loader.js:1087:19)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Module.require (pkg/prelude/bootstrap.js:1338:31)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at require (internal/modules/cjs/helpers.js:73:18)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at bindings (/snapshot/zwavejs2mqtt/node_modules/bindings/bindings.js:112:48)
Jun 15 17:32:57 hass zwavejs2mqtt[5859]:     at Object.<anonymous> (/snapshot/zwavejs2mqtt/node_modules/@serialport/bindings/lib/linux.js:2:36)
Jun 15 17:32:57 hass systemd[1]: zwavejs2mqtt.service: Main process exited, code=exited, status=1/FAILURE
Jun 15 17:32:57 hass systemd[1]: zwavejs2mqtt.service: Failed with result 'exit-code'.

robertsLando · 2021-06-16T06:55:16Z

@yajrendrag Ok so this is a problem with serialport binidings, we are speaking about it with serialport authors. BTW did you also try with 5.0.2?

yajrendrag · 2021-06-16T13:40:36Z

same problem with 5.0.2

robertsLando · 2021-06-16T15:11:46Z

Wait until #1346 is fixed

yajrendrag · 2021-07-10T02:35:52Z

Evidently, i can't update hass now as #1346 is still open and homeassistant 2021.7.x needs a newer version of zwavejs than i'm able to run using the pkg version... so, wondering if it's possible/easy to migrate to a docker version? can i just map my store directory that i use w/ the pkg version to the /usr/src/app/store directory in the container?

robertsLando · 2021-07-12T12:05:18Z

Fixed, release is coming

yajrendrag added the bug Something isn't working label Jun 10, 2021

yajrendrag assigned robertsLando Jun 10, 2021

yajrendrag mentioned this issue Jun 10, 2021

Error running new version of zwavejs via zwavejs2mqtt home-assistant/core#51731

Closed

robertsLando added a commit that referenced this issue Jun 11, 2021

fix: downgrade [email protected]

87e4f97

Fixes #1326

robertsLando mentioned this issue Jun 11, 2021

fix: downgrade [email protected] #1334

Merged

robertsLando closed this as completed in #1334 Jun 11, 2021

robertsLando added a commit that referenced this issue Jun 11, 2021

fix: downgrade [email protected] (#1334)

07568fc

Fixes #1326

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

[bug] zwavejs 5.0.0 doesn't run #1326

[bug] zwavejs 5.0.0 doesn't run #1326

yajrendrag commented Jun 10, 2021 •

edited

Loading

RualdOrdelman commented Jun 11, 2021 •

edited

Loading

robertsLando commented Jun 11, 2021 •

edited

Loading

robertsLando commented Jun 11, 2021

yajrendrag commented Jun 11, 2021

yajrendrag commented Jun 15, 2021

robertsLando commented Jun 15, 2021 •

edited

Loading

yajrendrag commented Jun 15, 2021

robertsLando commented Jun 15, 2021

yajrendrag commented Jun 15, 2021

yajrendrag commented Jun 15, 2021

robertsLando commented Jun 16, 2021

yajrendrag commented Jun 16, 2021

robertsLando commented Jun 16, 2021

yajrendrag commented Jul 10, 2021 •

edited

Loading

robertsLando commented Jul 12, 2021

[bug] zwavejs 5.0.0 doesn't run #1326

[bug] zwavejs 5.0.0 doesn't run #1326

Comments

yajrendrag commented Jun 10, 2021 • edited Loading

Version

Describe the bug

RualdOrdelman commented Jun 11, 2021 • edited Loading

robertsLando commented Jun 11, 2021 • edited Loading

robertsLando commented Jun 11, 2021

yajrendrag commented Jun 11, 2021

yajrendrag commented Jun 15, 2021

robertsLando commented Jun 15, 2021 • edited Loading

yajrendrag commented Jun 15, 2021

robertsLando commented Jun 15, 2021

yajrendrag commented Jun 15, 2021

yajrendrag commented Jun 15, 2021

robertsLando commented Jun 16, 2021

yajrendrag commented Jun 16, 2021

robertsLando commented Jun 16, 2021

yajrendrag commented Jul 10, 2021 • edited Loading

robertsLando commented Jul 12, 2021

yajrendrag commented Jun 10, 2021 •

edited

Loading

RualdOrdelman commented Jun 11, 2021 •

edited

Loading

robertsLando commented Jun 11, 2021 •

edited

Loading

robertsLando commented Jun 15, 2021 •

edited

Loading

yajrendrag commented Jul 10, 2021 •

edited

Loading