Latest z2m update prevents z2m from starting

mick · May 2, 2024, 4:43pm

I have just run an upgrade for zigbee2mqtt and now it wont start, nothing else has changed for months. Are there any obvious reason for this before I restore the previous version from last night?

Im running on a pi 4 boot from ssd and its a conbee 3 stick.

All I get when I open the z2m if is 502: bad gateway.

Tail end of logs are as follows:

Can anyone suggest what may have happened?

[2024-05-02 17:28:54] debug: 	zh:zstack:znp: SREQ: --> SYS - nvRead - {"sysid":1,"itemid":7,"subid":4,"offset":0,"len":12}
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:writer: --> frame [254,8,33,51,1,7,0,4,0,0,0,12,20]
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: <-- [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: --- parseNext [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: --> parsed 14 - 3 - 1 - 51 - [0,12,0,0,0,0,0,0,0,0,0,0,0,0] - 80
[2024-05-02 17:28:54] debug: 	zh:zstack:znp: SRSP: <-- SYS - nvRead - {"status":0,"len":12,"value":{"type":"Buffer","data":[0,0,0,0,0,0,0,0,0,0,0,0]}}
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: --- parseNext []
[2024-05-02 17:28:54] debug: 	zh:zstack:znp: SREQ: --> SYS - nvLength - {"sysid":1,"itemid":7,"subid":5}
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:writer: --> frame [254,5,33,50,1,7,0,5,0,21]
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: <-- [254,4,97,50,0,0,0,0,87]
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: --- parseNext [254,4,97,50,0,0,0,0,87]
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [0,0,0,0] - 87
[2024-05-02 17:28:54] debug: 	zh:zstack:znp: SRSP: <-- SYS - nvLength - {"len":0}
[2024-05-02 17:28:54] debug: 	zh:zstack:unpi:parser: --- parseNext []
[2024-05-02 17:28:54] debug: 	zh:zstack:backup: fetched adapter network security material table (capacity=1, used=1)
[2024-05-02 17:28:54] debug: 	zh:zstack:backup: Following devices with link key are missing from new backup but present in old backup and database, adding them back: 
[2024-05-02 17:28:54] info: 	zh:controller: Wrote coordinator backup to '/config/zigbee2mqtt/coordinator_backup.json'
[2024-05-02 17:28:54] info: 	zh:zstack:znp: closing
[2024-05-02 17:28:54] info: 	zh:zstack:znp: Port closed
[2024-05-02 17:28:54] info: 	z2m: Stopped zigbee-herdsman

francisp · May 2, 2024, 4:52pm

Show your Zigbee2MQTT configuration, Zigbee2MQTT thinks it is a zstack adapter

mick · May 2, 2024, 4:56pm

Here it is, its actually a sonoff!!!


data_path: /config/zigbee2mqtt
socat:
  enabled: false
  master: pty,raw,echo=0,link=/tmp/ttyZ2M,mode=777
  slave: tcp-listen:8485,keepalive,nodelay,reuseaddr,keepidle=1,keepintvl=1,keepcnt=5
  options: "-d -d"
  log: false
mqtt:
  base_topic: zigbee2mqtt
  broker: core-mosquitto
  user: m
  password: p
  server: http://192.168.0.35:1883
  permit_join: true
serial:
  port: >-
    /dev/serial/by-id/usb-Silicon_Labs_Sonoff_Zigbee_3.0_USB_Dongle_Plus_0001-if00-port0
permit_join: true
homeassistant: true

francisp · May 2, 2024, 4:58pm

That should work. Maybe show the whole log.

francisp · May 2, 2024, 4:59pm

although the permit join should not be there, nor the broker

mick · May 2, 2024, 5:42pm

So, can the permit_join or broker stop it from starting in this release?

francisp · May 2, 2024, 5:44pm

No idea, the logs are needed to see why it does not start.

mick · May 2, 2024, 7:22pm

Here is the ful log.



|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [20,0,0,0] - 67|
|---|---|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:20}|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvRead - {sysid:1,itemid:4,subid:199,offset:0,len:20}|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,8,33,51,1,4,0,199,0,0,0,20,204]|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,22,97,51,0,20,97,10,0,0,178,0,0,0,43,0,27,254,255,129,246,140,2,0,9,0,72]|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,22,97,51,0,20,97,10,0,0,178,0,0,0,43,0,27,254,255,129,246,140,2,0,9,0,72]|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 22 - 3 - 1 - 51 - [0,20,97,10,0,0,178,0,0,0,43,0,27,254,255,129,246,140,2,0,9,0] - 72|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvRead - {status:0,len:20,value:{type:Buffer,data:[97,10,0,0,178,0,0,0,43,0,27,254,255,129,246,140,2,0,9,0]}}|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvLength - {sysid:1,itemid:4,subid:200}|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,5,33,50,1,4,0,200,0,219]|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,4,97,50,0,0,0,0,87]|
|[2024-05-02 20:15:03] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,4,97,50,0,0,0,0,87]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [0,0,0,0] - 87|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:0}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:backup: fetched adapter tclk table (capacity=20, used=20)|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvLength - {sysid:1,itemid:7,subid:0}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,5,33,50,1,7,0,0,0,16]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [12,0,0,0] - 91|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvRead - {sysid:1,itemid:7,subid:0,offset:0,len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,8,33,51,1,7,0,0,0,0,0,12,16]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,14,97,51,0,12,108,28,77,0,163,18,227,38,0,75,18,0,64]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,14,97,51,0,12,108,28,77,0,163,18,227,38,0,75,18,0,64]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 14 - 3 - 1 - 51 - [0,12,108,28,77,0,163,18,227,38,0,75,18,0] - 64|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvRead - {status:0,len:12,value:{type:Buffer,data:[108,28,77,0,163,18,227,38,0,75,18,0]}}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvLength - {sysid:1,itemid:7,subid:1}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,5,33,50,1,7,0,1,0,17]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [12,0,0,0] - 91|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvRead - {sysid:1,itemid:7,subid:1,offset:0,len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,8,33,51,1,7,0,1,0,0,0,12,17]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 14 - 3 - 1 - 51 - [0,12,0,0,0,0,0,0,0,0,0,0,0,0] - 80|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvRead - {status:0,len:12,value:{type:Buffer,data:[0,0,0,0,0,0,0,0,0,0,0,0]}}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvLength - {sysid:1,itemid:7,subid:2}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,5,33,50,1,7,0,2,0,18]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [12,0,0,0] - 91|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvRead - {sysid:1,itemid:7,subid:2,offset:0,len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,8,33,51,1,7,0,2,0,0,0,12,18]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 14 - 3 - 1 - 51 - [0,12,0,0,0,0,0,0,0,0,0,0,0,0] - 80|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvRead - {status:0,len:12,value:{type:Buffer,data:[0,0,0,0,0,0,0,0,0,0,0,0]}}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvLength - {sysid:1,itemid:7,subid:3}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,5,33,50,1,7,0,3,0,19]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [12,0,0,0] - 91|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvRead - {sysid:1,itemid:7,subid:3,offset:0,len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,8,33,51,1,7,0,3,0,0,0,12,19]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 14 - 3 - 1 - 51 - [0,12,0,0,0,0,0,0,0,0,0,0,0,0] - 80|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvRead - {status:0,len:12,value:{type:Buffer,data:[0,0,0,0,0,0,0,0,0,0,0,0]}}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvLength - {sysid:1,itemid:7,subid:4}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,5,33,50,1,7,0,4,0,20]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,4,97,50,12,0,0,0,91]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [12,0,0,0] - 91|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvRead - {sysid:1,itemid:7,subid:4,offset:0,len:12}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,8,33,51,1,7,0,4,0,0,0,12,20]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,14,97,51,0,12,0,0,0,0,0,0,0,0,0,0,0,0,80]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 14 - 3 - 1 - 51 - [0,12,0,0,0,0,0,0,0,0,0,0,0,0] - 80|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvRead - {status:0,len:12,value:{type:Buffer,data:[0,0,0,0,0,0,0,0,0,0,0,0]}}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SREQ: --> SYS - nvLength - {sysid:1,itemid:7,subid:5}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:writer: --> frame [254,5,33,50,1,7,0,5,0,21]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: <-- [254,4,97,50,0,0,0,0,87]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext [254,4,97,50,0,0,0,0,87]|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --> parsed 4 - 3 - 1 - 50 - [0,0,0,0] - 87|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:znp: SRSP: <-- SYS - nvLength - {len:0}|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:unpi:parser: --- parseNext []|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:backup: fetched adapter network security material table (capacity=1, used=1)|
|[2024-05-02 20:15:04] e[34mdebuge[39m: |zh:zstack:backup: Following devices with link key are missing from new backup but present in old backup and database, adding them back: |
|[2024-05-02 20:15:04] e[32minfoe[39m: |zh:controller: Wrote coordinator backup to '/config/zigbee2mqtt/coordinator_backup.json'|
|[2024-05-02 20:15:04] e[32minfoe[39m: |zh:zstack:znp: closing|
|[2024-05-02 20:15:04] e[32minfoe[39m: |zh:zstack:znp: Port closed|
|[2024-05-02 20:15:04] e[32minfoe[39m: |z2m: Stopped zigbee-herdsman|

oliversweb · May 2, 2024, 10:35pm

My ZigBee2MQTT has just stopped working after update as well, can’t restart the add-on, hope this is temp issue

francisp · May 3, 2024, 3:47am

I would create an issue here:

Nick4 · May 3, 2024, 8:47am

FWIW, from the Z2M 1.37.0 release notes:

For Home Assistant users: this release requires at least Home Assistant 2024.4.

mick · May 3, 2024, 12:58pm

Ah well, reverted to previous version, all OK now.

mick · May 3, 2024, 4:17pm

After some experimentation I have had to use “mqtt://homeassistant.local:1883”. It wont accept an IP address!

So all working

Melapi · October 21, 2024, 11:05am

Same problem and same logs here