hassio-zigbee2mqtt icon indicating copy to clipboard operation
hassio-zigbee2mqtt copied to clipboard

Error while starting zigbee-herdsman. failed to start zigbee

Open kathodion opened this issue 2 years ago • 24 comments

Description of problem: after installing and edit the config. the conbee2 does not properly start-up with the zigbee2mqtt addon. There is no specific error code show in the logs.

Zigbee2mqtt add-on version (if edge, please report commit hash):

Zigbee2mqtt Current version: 1.25.0-1 (Changelog)

Your entire configuration from the frontend (with sensitive fields redacted):

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:
  server: mqtt://localhost:1883
  base_topic: zigbee2mqtt
  ca: /etc/ssl/mqtt-ca.crt
  key: /etc/ssl/mqtt-client.key
  cert: /etc/ssl/mqtt-client.crt
  include_device_information: true
serial:
  port: /dev/ttyACM0
  adapter: deconz
  disable_led: false
permit_join: true
frontend: true
availability:
  active:
    timeout: 10
  passive:
    timeout: 1500

![image](https://user-images.githubusercontent.com/10939437/162909801-7488422f-da22-4223-b7e3-c737c1dbd262.png)

Running platform (details from Suypervisor > System)

Home Assistant 2022.4.2
Core: core-2022.4.2
Supervisor: supervisor-2022.04.0
Operating System: Home Assistant OS 7.6

Installation Type | Home Assistant OS on an odroid n2+

Your logs from Home Assistant Supervisor

info  2022-04-12 09:51:58: Logging to console and directory: '/config/zigbee2mqtt/log/2022-04-12.09-51-56' filename: log.txt
info  2022-04-12 09:51:58: Starting Zigbee2MQTT version 1.25.0 (commit #unknown)
info  2022-04-12 09:51:58: Starting zigbee-herdsman (0.14.20)
error 2022-04-12 09:52:08: Error while starting zigbee-herdsman
error 2022-04-12 09:52:08: Failed to start zigbee
error 2022-04-12 09:52:08: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions
error 2022-04-12 09:52:08: Exiting...
error 2022-04-12 09:52:08: undefined

kathodion avatar Apr 12 '22 07:04 kathodion

Same issue here https://github.com/Koenkk/zigbee2mqtt/issues/12067

dyarfaradj avatar Apr 12 '22 22:04 dyarfaradj

I got same exact issue running Current version: 1.25.0-1 Version core-2022.4.2 Version supervisor-2022.04.0

[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] socat.sh: executing... 
[20:48:28] INFO: Socat not enabled, marking service as down
[cont-init.d] socat.sh: exited 0.
[cont-init.d] zigbee2mqtt.sh: executing... 
[cont-init.d] zigbee2mqtt.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
[20:48:39] INFO: Handing over control to Zigbee2mqtt Core ...
> [email protected] start
> node index.js
Zigbee2MQTT:error 2022-04-12 20:49:53: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2022-04-12 20:49:53: Failed to start zigbee
Zigbee2MQTT:error 2022-04-12 20:49:53: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions
Zigbee2MQTT:error 2022-04-12 20:49:53: Exiting...
Zigbee2MQTT:error 2022-04-12 20:49:55: Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms)
    at ZStackAdapter.start (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/zStackAdapter.ts:102:27)
    at Controller.start (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:123:29)
    at Zigbee.start (/app/lib/zigbee.ts:58:27)
    at Controller.start (/app/lib/controller.ts:100:27)
    at start (/app/index.js:101:5)

dyarfaradj avatar Apr 12 '22 22:04 dyarfaradj

I have the same problem...

chongjae avatar Apr 13 '22 02:04 chongjae

It looks database.db file has some problem. I want to open the file and edit it. But, I cannot open it. Is there the way to edit it?

chongjae avatar Apr 13 '22 08:04 chongjae

any update on this?

kathodion avatar Apr 19 '22 15:04 kathodion

I have the same issue. Can we help somehow with the problem solving?

esjani avatar Apr 21 '22 09:04 esjani

Also same issue here

jonwest avatar Apr 21 '22 18:04 jonwest

same issue

hauntedhill avatar Apr 23 '22 16:04 hauntedhill

I have the same issue. Can we help somehow with the problem solving?

I made a dumb mistake: tried to use ZHA and Z2M paralel. I’ve set up a new installation with Z2M only and it works.

esjani avatar Apr 23 '22 16:04 esjani

same issue. have not ZHA

...4 hours later solved!!! (in my case) with just edit the z2m config:

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:
  server: mqtt://core-mosquitto
  user: formqtt
  password: formqtt
serial:
  port: /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0
permit_join: true
frontend: true
  • port is the same as dev/ttyUSB0 in my case I think both will works
  • I have HAOS on nettop Chatreey AC1-Z with intel Celeron J4125
  • also I tried to reflash my zigbee stick cc2652p (https://aliexpress.ru/item/1005002875838019.html) but wasted time
  • user and password is the same in mosquitto add-on, mqtt integration and the additional HA user (didn't check if it matters or not)

Myaflick avatar Apr 25 '22 20:04 Myaflick

Question, I have paired a non supported Zigbee light is there any way to get it working in z2m, it works in zha...?

csweinberg avatar May 14 '22 01:05 csweinberg

This issue is stale because it has been open 30 days with no activity. Remove stale label or comment or this will be closed in 7 days

github-actions[bot] avatar Jun 14 '22 01:06 github-actions[bot]

any update on this? I still waiting for a fix :)

kathodion avatar Jun 15 '22 06:06 kathodion

@kathodion can you provide the herdsman debug logging when starting z2m?

See https://www.zigbee2mqtt.io/guide/usage/debug.html on how to enable the herdsman debug logging. Note that this is only logged to STDOUT and not to log files.

Koenkk avatar Jun 15 '22 15:06 Koenkk

@Koenkk I would need a few days but I will provide the information

kathodion avatar Jun 16 '22 07:06 kathodion

@Koenkk I believe I have the same issue although I'm not seeing the Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms) error like in @dyarfaradj's log. @kathodion's error log looks very much the same as posted in https://github.com/zigbee2mqtt/hassio-zigbee2mqtt/issues/348#issue-1264053913 , maybe related? I'm using haos_ova-8.2.vmdk running in VMware workstation, the adapter is a "CC2652P2 Based Zigbee to PoE Coordinator 2022" and works fine with Z2H but yet to see Zigbee2MQTT start successfully on this installation. Home Assistant Core 2022.6.7 Home Assistant Supervisor 2022.05.3 Home Assistant OS 8.2

I experience the same issue with both the current and edge branch, below is using Zigbee2MQTT Edge Config:

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: {}
serial:
  serial:
    port: tcp://tubeszb-cc2652-poe-2022.local:6638 #mDNS is working but have also tried with IP and get same result
zigbee_herdsman_debug: true
advanced:
  log_level: debug

Log:

s6-rc: info: service s6rc-oneshot-runner: starting
s6-rc: info: service s6rc-oneshot-runner successfully started
s6-rc: info: service fix-attrs: starting
s6-rc: info: service fix-attrs successfully started
s6-rc: info: service legacy-cont-init: starting
cont-init: info: running /etc/cont-init.d/socat.sh
[08:46:52] INFO: Socat not enabled, marking service as down
cont-init: info: /etc/cont-init.d/socat.sh exited 0
cont-init: info: running /etc/cont-init.d/zigbee2mqtt.sh
cont-init: info: /etc/cont-init.d/zigbee2mqtt.sh exited 0
s6-rc: info: service legacy-cont-init successfully started
s6-rc: info: service legacy-services: starting
services-up: info: copying legacy longrun socat (no readiness notification)
services-up: info: copying legacy longrun zigbee2mqtt (no readiness notification)
[08:46:52] INFO: Zigbee Herdsman debug logging enabled
[08:46:53] INFO: Handing over control to Zigbee2MQTT Core ...
> [email protected] start
> node index.js
Zigbee2MQTT:debug 2022-07-01 08:46:55: Can't load state from file /config/zigbee2mqtt/state.json (doesn't exist)
Zigbee2MQTT:info  2022-07-01 08:46:55: Logging to console and directory: '/config/zigbee2mqtt/log/2022-07-01.08-46-55' filename: log.txt
Zigbee2MQTT:debug 2022-07-01 08:46:55: Removing old log directory '/config/zigbee2mqtt/log/2022-07-01.08-43-20'
Zigbee2MQTT:info  2022-07-01 08:46:55: Starting Zigbee2MQTT version 1.25.2-dev (commit #3d406c3)
Zigbee2MQTT:info  2022-07-01 08:46:55: Starting zigbee-herdsman (0.14.40)
Zigbee2MQTT:debug 2022-07-01 08:46:55: Using zigbee-herdsman with settings: '{"adapter":{"concurrent":null,"delay":null,"disableLED":false},"backupPath":"/config/zigbee2mqtt/coordinator_backup.json","databaseBackupPath":"/config/zigbee2mqtt/database.db.backup","databasePath":"/config/zigbee2mqtt/database.db","network":{"channelList":[11],"extendedPanID":[221,221,221,221,221,221,221,221],"networkKey":"HIDDEN","panID":6754},"serialPort":{}}'
2022-06-30T20:46:56.055Z zigbee-herdsman:adapter No path provided, auto detecting path
Zigbee2MQTT:error 2022-07-01 08:46:56: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2022-07-01 08:46:56: Failed to start zigbee
Zigbee2MQTT:error 2022-07-01 08:46:56: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions
Zigbee2MQTT:error 2022-07-01 08:46:56: Exiting...
Zigbee2MQTT:error 2022-07-01 08:46:56: Error: spawn udevadm ENOENT
    at Process.ChildProcess._handle.onexit (node:internal/child_process:283:19)
    at onErrorNT (node:internal/child_process:478:16)
    at processTicksAndRejections (node:internal/process/task_queues:83:21)

I'm not sure if this is relevant but I notice in the log multiple files are mentioned in /config/zigbee2mqtt/ e.g. /config/zigbee2mqtt/database.db but I only see two files in this folder

image

Ricardo-0101 avatar Jun 30 '22 20:06 Ricardo-0101

@Ricardo-0101 your config looks wrong to me, under the serial section you have to fill in just:

port: tcp://tubeszb-cc2652-poe-2022.local:6638

Koenkk avatar Jul 01 '22 20:07 Koenkk

@Ricardo-0101 your config looks wrong to me, under the serial section you have to fill in just:


port: tcp://tubeszb-cc2652-poe-2022.local:6638

@Koenkk You are exactly right, can't believe I didn't notice this in the yaml, Tubes GitHub references adding below to the yaml however as I had added it via the serial field in the config page serial: was duplicated.

serial:
  port: tcp://tubeszb-cc2652-poe-2022.local:6638

Interestingly after correcting this I got an error similar to @dyarfaradj i.e. Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms) but this cleared once I had disabled ZHA in the integrations so all is well now. Thank you.

Ricardo-0101 avatar Jul 02 '22 11:07 Ricardo-0101

I have got version 1.26.0-1 installed. However, still getting the same issue.

[15:33:17] INFO: Handing over control to Zigbee2MQTT Core ...
> [email protected] start
> node index.js
Zigbee2MQTT:info  2022-07-06 15:33:24: Logging to console and directory: '/config/zigbee2mqtt/log/2022-07-06.15-33-24' filename: log.txt
Zigbee2MQTT:info  2022-07-06 15:33:25: Starting Zigbee2MQTT version 1.26.0 (commit #unknown)
Zigbee2MQTT:info  2022-07-06 15:33:25: Starting zigbee-herdsman (0.14.40)
Zigbee2MQTT:error 2022-07-06 15:34:29: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2022-07-06 15:34:29: Failed to start zigbee
Zigbee2MQTT:error 2022-07-06 15:34:29: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions
Zigbee2MQTT:error 2022-07-06 15:34:29: Exiting...
Zigbee2MQTT:error 2022-07-06 15:34:29: Error: network commissioning timed out - most likely network with the same panId or extendedPanId already exists nearby
    at ZnpAdapterManager.beginCommissioning (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/manager.ts:347:23)
    at ZnpAdapterManager.start (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/manager.ts:92:17)
    at Controller.start (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:123:29)
    at Zigbee.start (/app/lib/zigbee.ts:58:27)
    at Controller.start (/app/lib/controller.ts:101:27)
    at start (/app/index.js:109:5)

kaizersoje avatar Jul 06 '22 05:07 kaizersoje

I've got version 1.26.0-1 installed.

INFO: Handing over control to Zigbee2MQTT Core ...

[email protected] start node index.js Zigbee2MQTT:info 2022-07-26 17:45:46: Logging to console and directory: '/config/zigbee2mqtt/log/2022-07-26.17-45-46' filename: log.txt Zigbee2MQTT:info 2022-07-26 17:45:46: Starting Zigbee2MQTT version 1.26.0 (commit #unknown) Zigbee2MQTT:info 2022-07-26 17:45:46: Starting zigbee-herdsman (0.14.40) Zigbee2MQTT:error 2022-07-26 17:45:47: Error while starting zigbee-herdsman Zigbee2MQTT:error 2022-07-26 17:45:47: Failed to start zigbee Zigbee2MQTT:error 2022-07-26 17:45:47: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions Zigbee2MQTT:error 2022-07-26 17:45:47: Exiting... Zigbee2MQTT:error 2022-07-26 17:45:47: Error: Struct length mismatch (expected=16/16, got=32) at Struct.build (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/structs/struct.ts:334:23) at nwkKey (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/structs/entries/nwk-key.ts:12:6) at AdapterNvMemory.readItem (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/adapter-nv-memory.ts:73:21) at AdapterBackup.createBackup (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/adapter-backup.ts:104:67) at Controller.backup (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:302:28) at Controller.start (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:158:13) at Zigbee.start (/app/lib/zigbee.ts:58:27) at Controller.start (/app/lib/controller.ts:101:27) at start (/app/index.js:109:5)

McYars avatar Jul 26 '22 14:07 McYars

Have the same issue... :|

Zigbee2mqtt version: 1.26.0

My config:

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:
  server: mqtt://core-mosquitto
  base_topic: zigbee2mqtt
  user: formqtt
  password: formqtt
serial:
  port: /dev/ttyUSB0
  adapter: deconz
permit_join: true
frontend: true
zigbee_herdsman_debug: true
advanced:
  log_level: debug

Debug:

[20:58:42] INFO: Zigbee Herdsman debug logging enabled
[20:58:44] INFO: Handing over control to Zigbee2MQTT Core ...
> [email protected] start
> node index.js
Zigbee2MQTT:debug 2022-07-28 20:58:51: Can't load state from file /config/zigbee2mqtt/state.json (doesn't exist)
Zigbee2MQTT:info  2022-07-28 20:58:51: Logging to console and directory: '/config/zigbee2mqtt/log/2022-07-28.20-58-50' filename: log.txt
Zigbee2MQTT:debug 2022-07-28 20:58:51: Removing old log directory '/config/zigbee2mqtt/log/2022-07-28.20-55-42'
Zigbee2MQTT:info  2022-07-28 20:58:51: Starting Zigbee2MQTT version 1.26.0 (commit #unknown)
Zigbee2MQTT:info  2022-07-28 20:58:51: Starting zigbee-herdsman (0.14.40)
Zigbee2MQTT:debug 2022-07-28 20:58:51: Using zigbee-herdsman with settings: '{"adapter":{"concurrent":null,"delay":null,"disableLED":false},"backupPath":"/config/zigbee2mqtt/coordinator_backup.json","databaseBackupPath":"/config/zigbee2mqtt/database.db.backup","databasePath":"/config/zigbee2mqtt/database.db","network":{"channelList":[11],"extendedPanID":[221,221,221,221,221,221,221,221],"networkKey":"HIDDEN","panID":6754},"serialPort":{"adapter":"deconz","path":"/dev/ttyUSB0"}}'
2022-07-28T18:58:51.544Z zigbee-herdsman:adapter Failed to validate path: 'Error: spawn udevadm ENOENT'
2022-07-28T18:58:51.550Z zigbee-herdsman:deconz:driver Set delay to 0
2022-07-28T18:58:51.552Z zigbee-herdsman:controller:log Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13],"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"path":"/dev/ttyUSB0","adapter":"deconz"},"databasePath":"/config/zigbee2mqtt/database.db","databaseBackupPath":"/config/zigbee2mqtt/database.db.backup","backupPath":"/config/zigbee2mqtt/coordinator_backup.json","adapter":{"disableLED":false,"concurrent":null,"delay":null}}'
2022-07-28T18:58:51.553Z zigbee-herdsman:deconz:driver Opening with /dev/ttyUSB0
2022-07-28T18:58:51.572Z zigbee-herdsman:deconz:driver Serialport opened
2022-07-28T18:58:51.574Z zigbee-herdsman:controller:log Started with result 'resumed'
2022-07-28T18:58:51.575Z zigbee-herdsman:controller:log Injected database: true, adapter: true
2022-07-28T18:58:51.586Z zigbee-herdsman:deconz:driver send read parameter request from queue. seqNr: 1 paramId: 1
2022-07-28T18:58:54.554Z zigbee-herdsman:deconz:adapter checking coordinator simple descriptor
2022-07-28T18:58:54.564Z zigbee-herdsman:deconz:driver DATA_REQUEST - destAddr: 0x0 EP:0 SeqNr. 2 request id: 1
2022-07-28T18:59:01.587Z zigbee-herdsman:deconz:driver Timeout for request - CMD: 0xa seqNr: 1
Zigbee2MQTT:error 2022-07-28 20:59:01: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2022-07-28 20:59:01: Failed to start zigbee
Zigbee2MQTT:error 2022-07-28 20:59:01: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions
Zigbee2MQTT:error 2022-07-28 20:59:01: Exiting...
Zigbee2MQTT:error 2022-07-28 20:59:01: undefined
2022-07-28T18:59:01.617Z zigbee-herdsman:deconz:driver send read device state from queue. seqNr: 3

kanapka94 avatar Jul 28 '22 19:07 kanapka94

Upgraded to version 1.27.0-1. Still getting the same issue.

[11:05:52] INFO: Handing over control to Zigbee2MQTT Core ...
> [email protected] start
> node index.js
Zigbee2MQTT:info  2022-08-02 11:06:00: Logging to console and directory: '/config/zigbee2mqtt/log/2022-08-02.11-06-00' filename: log.txt
Zigbee2MQTT:info  2022-08-02 11:06:00: Starting Zigbee2MQTT version 1.27.0 (commit #unknown)
Zigbee2MQTT:info  2022-08-02 11:06:00: Starting zigbee-herdsman (0.14.46)
Zigbee2MQTT:error 2022-08-02 11:07:06: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2022-08-02 11:07:06: Failed to start zigbee
Zigbee2MQTT:error 2022-08-02 11:07:06: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start.html for possible solutions
Zigbee2MQTT:error 2022-08-02 11:07:06: Exiting...
Zigbee2MQTT:error 2022-08-02 11:07:07: Error: network commissioning timed out - most likely network with the same panId or extendedPanId already exists nearby
    at ZnpAdapterManager.beginCommissioning (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/manager.ts:347:23)
    at ZnpAdapterManager.start (/app/node_modules/zigbee-herdsman/src/adapter/z-stack/adapter/manager.ts:92:17)
    at Controller.start (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:131:29)
    at Zigbee.start (/app/lib/zigbee.ts:58:27)
    at Controller.start (/app/lib/controller.ts:101:27)
    at start (/app/index.js:109:5)

kaizersoje avatar Aug 02 '22 01:08 kaizersoje

Hi @Koenkk, I turned on the zigbee-herdsman debugging and this is what I got.

2022-08-02T01:43:05.155Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":71}
2022-08-02T01:43:05.155Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,71,0,119]
2022-08-02T01:43:05.166Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,8,0,120]
2022-08-02T01:43:05.166Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,8,0,120]
2022-08-02T01:43:05.166Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [8,0] - 120
2022-08-02T01:43:05.167Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":8}
2022-08-02T01:43:05.167Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.167Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":71,"offset":0}
2022-08-02T01:43:05.168Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,71,0,0,0,126]
2022-08-02T01:43:05.183Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,10,97,28,0,8,0,0,0,0,0,0,0,0,127]
2022-08-02T01:43:05.184Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,10,97,28,0,8,0,0,0,0,0,0,0,0,127]
2022-08-02T01:43:05.184Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 10 - 3 - 1 - 28 - [0,8,0,0,0,0,0,0,0,0] - 127
2022-08-02T01:43:05.184Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":0,"len":8,"value":{"type":"Buffer","data":[0,0,0,0,0,0,0,0]}}
2022-08-02T01:43:05.184Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.185Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":71}
2022-08-02T01:43:05.185Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,71,0,119]
2022-08-02T01:43:05.195Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,8,0,120]
2022-08-02T01:43:05.196Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,8,0,120]
2022-08-02T01:43:05.196Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [8,0] - 120
2022-08-02T01:43:05.196Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":8}
2022-08-02T01:43:05.196Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.197Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvWriteExt - {"id":71,"offset":0,"len":8,"value":{"type":"Buffer","data":[27,73,193,36,0,75,18,0]}}
2022-08-02T01:43:05.197Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,33,29,71,0,0,0,8,0,27,73,193,36,0,75,18,0,147]
2022-08-02T01:43:05.213Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,97,29,0,125]
2022-08-02T01:43:05.214Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,97,29,0,125]
2022-08-02T01:43:05.214Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 1 - 29 - [0] - 125
2022-08-02T01:43:05.214Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvWriteExt - {"status":0}
2022-08-02T01:43:05.214Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.215Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":99}
2022-08-02T01:43:05.215Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,99,0,83]
2022-08-02T01:43:05.225Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,1,0,113]
2022-08-02T01:43:05.226Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,1,0,113]
2022-08-02T01:43:05.226Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [1,0] - 113
2022-08-02T01:43:05.226Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":1}
2022-08-02T01:43:05.227Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.227Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":99,"offset":0}
2022-08-02T01:43:05.227Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,99,0,0,0,90]
2022-08-02T01:43:05.243Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,97,28,0,1,0,127]
2022-08-02T01:43:05.244Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,97,28,0,1,0,127]
2022-08-02T01:43:05.244Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 3 - 1 - 28 - [0,1,0] - 127
2022-08-02T01:43:05.245Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":0,"len":1,"value":{"type":"Buffer","data":[0]}}
2022-08-02T01:43:05.245Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.245Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":98}
2022-08-02T01:43:05.246Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,98,0,82]
2022-08-02T01:43:05.252Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,16,0,96]
2022-08-02T01:43:05.252Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,16,0,96]
2022-08-02T01:43:05.252Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [16,0] - 96
2022-08-02T01:43:05.252Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":16}
2022-08-02T01:43:05.253Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.253Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvReadExt - {"id":98,"offset":0}
2022-08-02T01:43:05.254Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,33,28,98,0,0,0,91]
2022-08-02T01:43:05.262Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,18,97,28,0,16,27,212,251,224,235,195,181,112,128,124,33,66,56,49,43,33,218]
2022-08-02T01:43:05.262Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,18,97,28,0,16,27,212,251,224,235,195,181,112,128,124,33,66,56,49,43,33,218]
2022-08-02T01:43:05.262Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 18 - 3 - 1 - 28 - [0,16,27,212,251,224,235,195,181,112,128,124,33,66,56,49,43,33] - 218
2022-08-02T01:43:05.263Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvReadExt - {"status":0,"len":16,"value":{"type":"Buffer","data":[27,212,251,224,235,195,181,112,128,124,33,66,56,49,43,33]}}
2022-08-02T01:43:05.263Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.264Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvLength - {"id":98}
2022-08-02T01:43:05.264Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,2,33,19,98,0,82]
2022-08-02T01:43:05.270Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,19,16,0,96]
2022-08-02T01:43:05.270Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,19,16,0,96]
2022-08-02T01:43:05.270Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 19 - [16,0] - 96
2022-08-02T01:43:05.270Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvLength - {"length":16}
2022-08-02T01:43:05.271Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.271Z zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvWriteExt - {"id":98,"offset":0,"len":16,"value":{"type":"Buffer","data":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13]}}
2022-08-02T01:43:05.272Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,22,33,29,98,0,0,0,16,0,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,91]
2022-08-02T01:43:05.280Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,97,29,0,125]
2022-08-02T01:43:05.281Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,97,29,0,125]
2022-08-02T01:43:05.281Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 1 - 29 - [0] - 125
2022-08-02T01:43:05.281Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvWriteExt - {"status":0}
2022-08-02T01:43:05.281Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.281Z zigbee-herdsman:adapter:zStack:startup:commissioning beginning network commissioning
2022-08-02T01:43:05.282Z zigbee-herdsman:adapter:zStack:znp:SREQ --> APP_CNF - bdbSetChannel - {"isPrimary":1,"channel":2048}
2022-08-02T01:43:05.283Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,47,8,1,0,8,0,0,43]
2022-08-02T01:43:05.288Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,111,8,0,102]
2022-08-02T01:43:05.292Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,111,8,0,102]
2022-08-02T01:43:05.292Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 15 - 8 - [0] - 102
2022-08-02T01:43:05.292Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- APP_CNF - bdbSetChannel - {"status":0}
2022-08-02T01:43:05.292Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.293Z zigbee-herdsman:adapter:zStack:znp:SREQ --> APP_CNF - bdbSetChannel - {"isPrimary":0,"channel":0}
2022-08-02T01:43:05.293Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,47,8,0,0,0,0,0,34]
2022-08-02T01:43:05.299Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,111,8,0,102]
2022-08-02T01:43:05.300Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,111,8,0,102]
2022-08-02T01:43:05.300Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 15 - 8 - [0] - 102
2022-08-02T01:43:05.300Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- APP_CNF - bdbSetChannel - {"status":0}
2022-08-02T01:43:05.300Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:05.302Z zigbee-herdsman:adapter:zStack:znp:SREQ --> APP_CNF - bdbStartCommissioning - {"mode":4}
2022-08-02T01:43:05.303Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,1,47,5,4,47]
2022-08-02T01:43:08.030Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,111,5,0,107,254,3,79,128,1,2,4,203]
2022-08-02T01:43:08.031Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,111,5,0,107,254,3,79,128,1,2,4,203]
2022-08-02T01:43:08.031Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 15 - 5 - [0] - 107
2022-08-02T01:43:08.031Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- APP_CNF - bdbStartCommissioning - {"status":0}
2022-08-02T01:43:08.034Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,79,128,1,2,4,203]
2022-08-02T01:43:08.034Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 15 - 128 - [1,2,4] - 203
2022-08-02T01:43:08.035Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- APP_CNF - bdbComissioningNotifcation - {"status":1}
2022-08-02T01:43:08.037Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-08-02T01:43:08.355Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,69,192,8,140]
2022-08-02T01:43:08.355Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,69,192,8,140]
2022-08-02T01:43:08.356Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 2 - 5 - 192 - [8] - 140
2022-08-02T01:43:08.356Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - stateChangeInd - {"state":8}
2022-08-02T01:43:08.357Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

kaizersoje avatar Aug 02 '22 01:08 kaizersoje

@kaizersoje see https://github.com/zigbee2mqtt/hassio-zigbee2mqtt/issues/366#issuecomment-1203009430 (this is not a bug of z2m)

Koenkk avatar Aug 02 '22 17:08 Koenkk

This issue is stale because it has been open 30 days with no activity. Remove stale label or comment or this will be closed in 7 days

github-actions[bot] avatar Sep 02 '22 01:09 github-actions[bot]

I have the same issue as reported by all here..... Any insight from the developer of this add-on?

brantmacga avatar Nov 24 '22 19:11 brantmacga

@brantmacga I fixed my problem by upgrading soft on dongle. Instructions from this video https://www.youtube.com/watch?v=4jqQCxjlRDU

kanapka94 avatar Nov 25 '22 06:11 kanapka94

I had the same "undefined" error. In my case it was actually the cheap usb charger I have used for my Raspberry Pi. After switching to a proper power supply everything started successfully.

Xitee1 avatar May 06 '23 14:05 Xitee1