SOLVED Zigbee adapter nach upgrade gelb

[steimi]

Ich habe ein ähnliches Problem. Vorhin die VM (in Proxmox) neu gestartet und alle Instanzen kamen hoch, bis auf Zigbee - Meldung: 'You need save and run adapter before pairing'. Dann nochmals versucht die Instanz zu starten, später klappte es dann. Hier mal die Debug-Logs.

2020-02-20 17:30:01.598 - error: zigbee.0 (1445) Failed to start Zigbee
2020-02-20 17:30:01.598 - error: zigbee.0 (1445) Error: AREQ - SYS - resetInd after 30000ms
at Timeout.object.timer.setTimeout [as _onTimeout] (/opt/iobroker/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
at ontimeout (timers.js:436:11)
at tryOnTimeout (timers.js:300:5)
at listOnTimeout (timers.js:263:5)
at Timer.processTimers (timers.js:223:10)
2020-02-20 17:30:04.020 - info: host.iobroker-01 instance system.adapter.weatherunderground.0 started with pid 1543
2020-02-20 17:30:08.026 - info: host.iobroker-01 instance system.adapter.daswetter.0 started with pid 1570
2020-02-20 17:30:08.918 - info: host.iobroker-01 instance system.adapter.weatherunderground.0 terminated with code 0 (NO_ERROR)
2020-02-20 17:30:10.990 - info: host.iobroker-01 instance system.adapter.ical.0 terminated with code 0 (NO_ERROR)
2020-02-20 17:30:11.599 - info: zigbee.0 (1445) Try to reconnect. 1 attempts left
2020-02-20 17:30:11.599 - info: zigbee.0 (1445) Starting Zigbee...
2020-02-20 17:30:11.677 - error: zigbee.0 (1445) Failed to start Zigbee
2020-02-20 17:30:11.677 - error: zigbee.0 (1445) Error: Error while opening serialport 'Error: Error Resource temporarily unavailable Cannot lock port'
at Znp. (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:113:32)
at Generator.next ()
at /opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:8:71
at new Promise ()
at __awaiter (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:4:12)
at SerialPort.serialPort.open (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:111:49)
at SerialPort._error (/opt/iobroker/node_modules/@serialport/stream/lib/index.js:198:14)
at binding.open.then.err (/opt/iobroker/node_modules/@serialport/stream/lib/index.js:242:12)
2020-02-20 17:31:09.623 - warn: node-red.0 (1728) 20 Feb 17:31:09 - [warn] Projekte inaktiviert: editorTheme.projects.enabled=false
2020-02-20 17:31:29.051 - info: javascript.0 (497) script.js.common.Heimnetz.var_Anwesenheit_Eltern_radar2: var_Anwesenheit_Eltern getriggert...
2020-02-20 17:31:41.758 - info: javascript.0 (497) script.js.common.Heimnetz.var_Anwesenheit_Eltern_radar2: var_Anwesenheit_Eltern getriggert...
2020-02-20 17:32:59.051 - info: javascript.0 (497) script.js.common.Heimnetz.var_Anwesenheit_Eltern_radar2: Variable Anwesenheit_Eltern = true
2020-02-20 17:32:59.056 - info: javascript.0 (497) script.js.common.Heimnetz.HWR_Zirkulationspumpe: Zirkulationspumpe eingeschaltet
2020-02-20 17:33:11.759 - info: javascript.0 (497) script.js.common.Heimnetz.var_Anwesenheit_Eltern_radar2: Variable Anwesenheit_Eltern = true
2020-02-20 17:33:11.760 - info: javascript.0 (497) script.js.common.Heimnetz.HWR_Zirkulationspumpe: Zirkulationspumpe eingeschaltet
2020-02-20 17:34:13.209 - info: tankerkoenig.0 (1416) Reading data from tankerkoenig ...
2020-02-20 17:34:47.838 - info: javascript.0 (497) script.js.common.Heimnetz.Wassertank_Fuellstand: Abweichung: 0
2020-02-20 17:35:00.009 - info: host.iobroker-01 instance system.adapter.dwd.0 started with pid 2080
2020-02-20 17:35:04.017 - info: host.iobroker-01 instance system.adapter.weatherunderground.0 started with pid 2095
2020-02-20 17:35:05.455 - info: host.iobroker-01 instance system.adapter.dwd.0 terminated with code 0 (NO_ERROR)
2020-02-20 17:35:09.844 - info: host.iobroker-01 instance system.adapter.weatherunderground.0 terminated with code 0 (NO_ERROR)
2020-02-20 17:35:28.148 - info: host.iobroker-01 "system.adapter.zigbee.0" disabled
2020-02-20 17:35:28.148 - info: host.iobroker-01 stopInstance system.adapter.zigbee.0 (force=false, process=true)
2020-02-20 17:35:28.148 - info: host.iobroker-01 stopInstance system.adapter.zigbee.0 send kill signal
2020-02-20 17:35:28.150 - info: zigbee.0 (1445) Got terminate signal TERMINATE_YOURSELF
2020-02-20 17:35:28.150 - info: zigbee.0 (1445) cleaned everything up...
2020-02-20 17:35:28.152 - info: zigbee.0 (1445) Zigbee: disabling joining new devices.
2020-02-20 17:35:28.152 - error: zigbee.0 (1445) Failed to stop zigbee (TypeError: Cannot read property 'getEntries' of null
at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:64:55)
at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:76:15)
at Controller. (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:173:49)
at Generator.next ()
at /opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:8:71
at new Promise ()
at __awaiter (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:4:12)
at Controller.stop (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:169:16)
at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:297:33)
at process._tickCallback (internal/process/next_tick.js:68:7))
2020-02-20 17:35:28.153 - info: zigbee.0 (1445) terminating
2020-02-20 17:35:28.153 - info: zigbee.0 (1445) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: (node:1445) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'getEntries' of null
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:135:55)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:155:16)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at Controller.getDevices (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:218:31)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.getClients (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:195:49)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at DeviceAvailability.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zbDeviceAvailability.js:123:43)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.callExtensionMethod (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:181:42)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:293:20)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at Zigbee.onUnload (/opt/iobroker/node_modules/iobroker.zigbee/main.js:317:41)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at Zigbee.emit (events.js:198:13)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[0]: at stop (/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:7226:22)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[1]: (node:1445) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 2)
2020-02-20 17:35:28.687 - error: host.iobroker-01 Caught by controller[1]: (node:1445) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
2020-02-20 17:35:28.687 - info: host.iobroker-01 instance system.adapter.zigbee.0 terminated with code 156 (156)
2020-02-20 17:35:34.758 - info: host.iobroker-01 "system.adapter.zigbee.0" enabled
2020-02-20 17:35:34.766 - info: host.iobroker-01 instance system.adapter.zigbee.0 started with pid 2157
2020-02-20 17:35:36.066 - info: zigbee.0 (2157) starting. Version 1.0.3 in /opt/iobroker/node_modules/iobroker.zigbee, node: v10.19.0
2020-02-20 17:35:36.079 - info: zigbee.0 (2157) Starting Zigbee...
2020-02-20 17:35:48.374 - info: host.iobroker-01 stopInstance system.adapter.zigbee.0 (force=false, process=true)
2020-02-20 17:35:48.375 - info: host.iobroker-01 stopInstance system.adapter.zigbee.0 send kill signal
2020-02-20 17:35:48.377 - info: zigbee.0 (2157) Got terminate signal TERMINATE_YOURSELF
2020-02-20 17:35:48.377 - info: zigbee.0 (2157) cleaned everything up...
2020-02-20 17:35:48.380 - info: zigbee.0 (2157) Zigbee: disabling joining new devices.
2020-02-20 17:35:48.381 - error: zigbee.0 (2157) Failed to stop zigbee (TypeError: Cannot read property 'getEntries' of null
at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:64:55)
at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:76:15)
at Controller. (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:173:49)
at Generator.next ()
at /opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:8:71
at new Promise ()
at __awaiter (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:4:12)
at Controller.stop (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:169:16)
at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:297:33)
at process._tickCallback (internal/process/next_tick.js:68:7))
2020-02-20 17:35:48.381 - info: zigbee.0 (2157) terminating
2020-02-20 17:35:48.381 - info: zigbee.0 (2157) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: (node:2157) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'getEntries' of null
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:135:55)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:155:16)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at Controller.getDevices (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:218:31)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.getClients (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:195:49)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at DeviceAvailability.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zbDeviceAvailability.js:123:43)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.callExtensionMethod (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:181:42)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:293:20)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at Zigbee.onUnload (/opt/iobroker/node_modules/iobroker.zigbee/main.js:317:41)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at Zigbee.emit (events.js:198:13)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[0]: at stop (/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:7226:22)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[1]: (node:2157) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 2)
2020-02-20 17:35:48.915 - error: host.iobroker-01 Caught by controller[1]: (node:2157) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
2020-02-20 17:35:48.915 - info: host.iobroker-01 instance system.adapter.zigbee.0 terminated with code 156 (156)
2020-02-20 17:35:50.882 - info: host.iobroker-01 instance system.adapter.zigbee.0 started with pid 2174
2020-02-20 17:35:52.207 - info: zigbee.0 (2174) starting. Version 1.0.3 in /opt/iobroker/node_modules/iobroker.zigbee, node: v10.19.0
2020-02-20 17:35:52.224 - info: zigbee.0 (2174) Starting Zigbee...
2020-02-20 17:36:41.327 - error: zigbee.0 (2174) Failed to start Zigbee
2020-02-20 17:36:41.327 - error: zigbee.0 (2174) Error: AREQ - SYS - resetInd after 30000ms
at Timeout.object.timer.setTimeout [as _onTimeout] (/opt/iobroker/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
at ontimeout (timers.js:436:11)
at tryOnTimeout (timers.js:300:5)
at listOnTimeout (timers.js:263:5)
at Timer.processTimers (timers.js:223:10)
2020-02-20 17:36:51.328 - info: zigbee.0 (2174) Try to reconnect. 1 attempts left
2020-02-20 17:36:51.328 - info: zigbee.0 (2174) Starting Zigbee...
2020-02-20 17:36:51.392 - error: zigbee.0 (2174) Failed to start Zigbee
2020-02-20 17:36:51.393 - error: zigbee.0 (2174) Error: Error while opening serialport 'Error: Error Resource temporarily unavailable Cannot lock port'
at Znp. (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:113:32)
at Generator.next ()
at /opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:8:71
at new Promise ()
at __awaiter (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:4:12)
at SerialPort.serialPort.open (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:111:49)
at SerialPort._error (/opt/iobroker/node_modules/@serialport/stream/lib/index.js:198:14)
at binding.open.then.err (/opt/iobroker/node_modules/@serialport/stream/lib/index.js:242:12)
2020-02-20 17:38:22.089 - info: pushover.0 (936) Send pushover notification: {"message":"Vanessa zu Hause angekommen um 2020-02-20@17:38:22","sound":"none","title":"Vanessa@Home (BtT)","device":"Handy-Annett","priority":"0"}
2020-02-20 17:39:13.212 - info: tankerkoenig.0 (1416) Reading data from tankerkoenig ...
2020-02-20 17:39:47.890 - info: javascript.0 (497) script.js.common.Heimnetz.Wassertank_Fuellstand: Abweichung: 0
2020-02-20 17:40:00.009 - info: host.iobroker-01 instance system.adapter.weatherunderground.0 started with pid 2541
2020-02-20 17:40:04.017 - info: host.iobroker-01 instance system.adapter.dwd.0 started with pid 2556
2020-02-20 17:40:04.996 - info: host.iobroker-01 instance system.adapter.weatherunderground.0 terminated with code 0 (NO_ERROR)
2020-02-20 17:40:13.717 - info: host.iobroker-01 instance system.adapter.dwd.0 terminated with code 0 (NO_ERROR)
2020-02-20 17:42:11.398 - info: host.iobroker-01 stopInstance system.adapter.zigbee.0 (force=false, process=true)
2020-02-20 17:42:11.398 - info: host.iobroker-01 stopInstance system.adapter.zigbee.0 send kill signal
2020-02-20 17:42:11.400 - info: zigbee.0 (2174) Got terminate signal TERMINATE_YOURSELF
2020-02-20 17:42:11.401 - info: zigbee.0 (2174) cleaned everything up...
2020-02-20 17:42:11.403 - info: zigbee.0 (2174) Zigbee: disabling joining new devices.
2020-02-20 17:42:11.404 - error: zigbee.0 (2174) Failed to stop zigbee (TypeError: Cannot read property 'getEntries' of null
at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:64:55)
at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:76:15)
at Controller. (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:173:49)
at Generator.next ()
at /opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:8:71
at new Promise ()
at __awaiter (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:4:12)
at Controller.stop (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:169:16)
at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:297:33)
at process._tickCallback (internal/process/next_tick.js:68:7))
2020-02-20 17:42:11.404 - info: zigbee.0 (2174) terminating
2020-02-20 17:42:11.404 - info: zigbee.0 (2174) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason
2020-02-20 17:42:11.937 - error: host.iobroker-01 Caught by controller[0]: (node:2174) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'getEntries' of null
2020-02-20 17:42:11.937 - error: host.iobroker-01 Caught by controller[0]: at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:135:55)
2020-02-20 17:42:11.937 - error: host.iobroker-01 Caught by controller[0]: at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:155:16)
2020-02-20 17:42:11.937 - error: host.iobroker-01 Caught by controller[0]: at Controller.getDevices (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:218:31)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.getClients (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:195:49)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[0]: at DeviceAvailability.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zbDeviceAvailability.js:123:43)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.callExtensionMethod (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:181:42)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[0]: at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:293:20)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[0]: at Zigbee.onUnload (/opt/iobroker/node_modules/iobroker.zigbee/main.js:317:41)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[0]: at Zigbee.emit (events.js:198:13)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[0]: at stop (/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:7226:22)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[1]: (node:2174) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 2)
2020-02-20 17:42:11.938 - error: host.iobroker-01 Caught by controller[1]: (node:2174) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
2020-02-20 17:42:11.938 - info: host.iobroker-01 instance system.adapter.zigbee.0 terminated with code 156 (156)
2020-02-20 17:42:13.905 - info: host.iobroker-01 instance system.adapter.zigbee.0 started with pid 2731
2020-02-20 17:42:14.277 - debug: zigbee.0 (2731) Redis Objects: Use Redis connection: 127.0.0.1:9001
2020-02-20 17:42:14.291 - debug: zigbee.0 (2731) Objects client ready ... initialize now
2020-02-20 17:42:14.292 - debug: zigbee.0 (2731) Objects create System PubSub Client
2020-02-20 17:42:14.293 - debug: zigbee.0 (2731) Objects create User PubSub Client
2020-02-20 17:42:14.293 - debug: zigbee.0 (2731) Objects client initialize lua scripts
2020-02-20 17:42:14.300 - debug: zigbee.0 (2731) Objects connected to redis: 127.0.0.1:9001
2020-02-20 17:42:14.302 - debug: zigbee.0 (2731) objectDB connected
2020-02-20 17:42:14.303 - debug: zigbee.0 (2731) Redis States: Use Redis connection: 127.0.0.1:9000
2020-02-20 17:42:14.307 - debug: zigbee.0 (2731) States create User PubSub Client
2020-02-20 17:42:14.308 - debug: zigbee.0 (2731) States create System PubSub Client
2020-02-20 17:42:14.313 - debug: zigbee.0 (2731) States connected to redis: 127.0.0.1:9000
2020-02-20 17:42:14.313 - debug: zigbee.0 (2731) statesDB connected
2020-02-20 17:42:15.245 - info: zigbee.0 (2731) starting. Version 1.0.3 in /opt/iobroker/node_modules/iobroker.zigbee, node: v10.19.0
2020-02-20 17:42:15.257 - info: zigbee.0 (2731) Starting Zigbee...
2020-02-20 17:42:15.258 - debug: zigbee.0 (2731) Starting zigbee-herdsman...
2020-02-20 17:42:17.107 - debug: zigbee.0 (2731) system.adapter.admin.0: logging true
2020-02-20 17:42:41.069 - debug: zigbee.0 (2731) zigbee-herdsman started
2020-02-20 17:42:41.069 - info: zigbee.0 (2731) Coordinator firmware version: {"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":0,"maintrel":0,"revision":""}}
2020-02-20 17:42:41.075 - debug: zigbee.0 (2731) Zigbee network parameters: {"panID":6754,"extendedPanID":"0xdddddddddddddddd","channel":11}
2020-02-20 17:42:41.076 - info: zigbee.0 (2731) Disable LED
2020-02-20 17:42:41.081 - info: zigbee.0 (2731) --> transmitPower : normal
2020-02-20 17:42:41.087 - debug: zigbee.0 (2731) Publish available for 0x00158d0003499ad7 = true
2020-02-20 17:42:41.088 - debug: zigbee.0 (2731) Publish available for 0x00158d00036d347a = true
2020-02-20 17:42:41.088 - debug: zigbee.0 (2731) Publish available for 0x00158d0002e99611 = true
2020-02-20 17:42:41.088 - debug: zigbee.0 (2731) Publish available for 0x00158d0002ea75fd = true
2020-02-20 17:42:41.089 - debug: zigbee.0 (2731) Publish available for 0x00158d0003499a1b = true
2020-02-20 17:42:41.089 - debug: zigbee.0 (2731) Publish available for 0x84182600000fc87e = true
2020-02-20 17:42:41.089 - debug: zigbee.0 (2731) Publish available for 0x00158d000309a256 = true
2020-02-20 17:42:41.090 - info: zigbee.0 (2731) Currently 7 devices are joined:
2020-02-20 17:42:41.090 - debug: zigbee.0 (2731) shouldConfigure? 0x00158d0003499ad7 lumi.weather
2020-02-20 17:42:41.090 - debug: zigbee.0 (2731) shouldConfigure? 0x00158d00036d347a lumi.weather
2020-02-20 17:42:41.090 - debug: zigbee.0 (2731) shouldConfigure? 0x00158d0002e99611 lumi.weather
2020-02-20 17:42:41.090 - debug: zigbee.0 (2731) shouldConfigure? 0x00158d0002ea75fd lumi.weather
2020-02-20 17:42:41.090 - debug: zigbee.0 (2731) shouldConfigure? 0x00158d0003499a1b lumi.weather
2020-02-20 17:42:41.090 - debug: zigbee.0 (2731) shouldConfigure? 0x84182600000fc87e Plug 01
2020-02-20 17:42:41.091 - debug: zigbee.0 (2731) shouldConfigure? 0x00158d000309a256 lumi.weather
2020-02-20 17:42:41.091 - debug: zigbee.0 (2731) New device event: {"type":"device","device":{"ID":2,"_type":"EndDevice","_ieeeAddr":"0x00158d0003499ad7","_networkAddress":44062,"_manufacturerID":4151,"_endpoints":[{"ID":1,"profileID":260,"deviceID":24321,"inputClusters":[0,3,65535,1026,1027,1029],"outputClusters":[0,4,65535],"deviceNetworkAddress":44062,"deviceIeeeAddress":"0x00158d0003499ad7","clusters":{"msTemperatureMeasurement":{"attributes":{"measuredValue":2262}},"msRelativeHumidity":{"attributes":{"measuredValue":4004}},"msPressureMeasurement":{"attributes":{"16":9963,"20":-1,"measuredValue":996}},"genBasic":{"attributes":{"65281":{"1":2995,"4":5032,"5":2,"6":[1,16777218],"10":0,"100":2269,"101":4034,"102":99693},"modelId":"lumi.weather","manufacturerName":"LUMI","powerSource":3,"zclVersion":1,"appVersion":3,"stackVersion":2,"hwVersion":30,"dateCode":"20161129","swBuildId":"3000-0001"}}},"_binds":[]}],"_manufacturerName":"LUMI","_powerSource":"Battery","_modelID":"lumi.weather","_applicationVersion":3,"_stackVersion":2,"_zclVersion":1,"_hardwareVersion":30,"_dateCode":"20161129","_softwareBuildID":"3000-0001","_interviewCompleted":true,"_interviewing":false,"meta":{},"_lastSeen":1582211158629},"mapped":{"zigbeeModel":["lumi.weather"],"model":"WSDCGQ11LM","vendor":"Xiaomi","description":"Aqara temperature, humidity and pressure sensor","supports":"temperature, humidity and pressure","fromZigbee":[{"cluster":"genBasic","type":["attributeReport","readResponse"]},{"cluster":"msTemperatureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"msRelativeHumidity","type":["attributeReport","readResponse"]},{"cluster":"msPressureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"genBasic","type":["attributeReport","readResponse"]}],"toZigbee":[]},"name":"0x00158d0003499ad7"}
2020-02-20 17:42:41.091 - info: zigbee.0 (2731) 0x00158d0003499ad7 (addr 44062): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
2020-02-20 17:42:41.092 - debug: zigbee.0 (2731) New device event: {"type":"device","device":{"ID":3,"_type":"EndDevice","_ieeeAddr":"0x00158d00036d347a","_networkAddress":49623,"_manufacturerID":4151,"_endpoints":[{"ID":1,"profileID":260,"deviceID":24321,"inputClusters":[0,3,65535,1026,1027,1029],"outputClusters":[0,4,65535],"deviceNetworkAddress":49623,"deviceIeeeAddress":"0x00158d00036d347a","clusters":{"msTemperatureMeasurement":{"attributes":{"measuredValue":2274}},"msRelativeHumidity":{"attributes":{"measuredValue":3780}},"msPressureMeasurement":{"attributes":{"16":9948,"20":-1,"measuredValue":994}},"genBasic":{"attributes":{"65281":{"1":2995,"4":5032,"5":6,"6":[0,1],"10":0,"100":2278,"101":3831,"102":99506},"modelId":"lumi.weather","manufacturerName":"LUMI"}}},"_binds":[]}],"_manufacturerName":"LUMI","_powerSource":"Battery","_modelID":"lumi.weather","_applicationVersion":3,"_interviewCompleted":true,"_interviewing":false,"meta":{},"_lastSeen":1582215718787},"mapped":{"zigbeeModel":["lumi.weather"],"model":"WSDCGQ11LM","vendor":"Xiaomi","description":"Aqara temperature, humidity and pressure sensor","supports":"temperature, humidity and pressure","fromZigbee":[{"cluster":"genBasic","type":["attributeReport","readResponse"]},{"cluster":"msTemperatureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"msRelativeHumidity","type":["attributeReport","readResponse"]},{"cluster":"msPressureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"genBasic","type":["attributeReport","readResponse"]}],"toZigbee":[]},"name":"0x00158d00036d347a"}
2020-02-20 17:42:41.092 - info: zigbee.0 (2731) 0x00158d00036d347a (addr 49623): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
2020-02-20 17:42:41.092 - debug: zigbee.0 (2731) New device event: {"type":"device","device":{"ID":4,"_type":"EndDevice","_ieeeAddr":"0x00158d0002e99611","_networkAddress":62904,"_manufacturerID":4151,"_endpoints":[{"ID":1,"profileID":260,"deviceID":24321,"inputClusters":[0,3,65535,1026,1027,1029],"outputClusters":[0,4,65535],"deviceNetworkAddress":62904,"deviceIeeeAddress":"0x00158d0002e99611","clusters":{"msTemperatureMeasurement":{"attributes":{"measuredValue":737}},"msRelativeHumidity":{"attributes":{"measuredValue":7709}},"msPressureMeasurement":{"attributes":{"16":9953,"20":-1,"measuredValue":995}},"genBasic":{"attributes":{"65281":{"1":2945,"4":5032,"5":23,"6":[0,1],"10":0,"100":741,"101":7723,"102":99554},"modelId":"lumi.weather","manufacturerName":"LUMI","powerSource":3,"zclVersion":1,"appVersion":3,"stackVersion":2}}},"_binds":[]}],"_manufacturerName":"LUMI","_powerSource":"Battery","_modelID":"lumi.weather","_applicationVersion":3,"_stackVersion":2,"_zclVersion":1,"_interviewCompleted":true,"_interviewing":false,"meta":{},"_lastSeen":1582215439055},"mapped":{"zigbeeModel":["lumi.weather"],"model":"WSDCGQ11LM","vendor":"Xiaomi","description":"Aqara temperature, humidity and pressure sensor","supports":"temperature, humidity and pressure","fromZigbee":[{"cluster":"genBasic","type":["attributeReport","readResponse"]},{"cluster":"msTemperatureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"msRelativeHumidity","type":["attributeReport","readResponse"]},{"cluster":"msPressureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"genBasic","type":["attributeReport","readResponse"]}],"toZigbee":[]},"name":"0x00158d0002e99611"}
2020-02-20 17:42:41.092 - info: zigbee.0 (2731) 0x00158d0002e99611 (addr 62904): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
2020-02-20 17:42:41.093 - debug: zigbee.0 (2731) New device event: {"type":"device","device":{"ID":5,"_type":"EndDevice","_ieeeAddr":"0x00158d0002ea75fd","_networkAddress":9722,"_manufacturerID":4151,"_endpoints":[{"ID":1,"profileID":260,"deviceID":24321,"inputClusters":[0,3,65535,1026,1027,1029],"outputClusters":[0,4,65535],"deviceNetworkAddress":9722,"deviceIeeeAddress":"0x00158d0002ea75fd","clusters":{"msTemperatureMeasurement":{"attributes":{"measuredValue":864}},"msRelativeHumidity":{"attributes":{"measuredValue":6987}},"msPressureMeasurement":{"attributes":{"16":9950,"20":-1,"measuredValue":995}},"genBasic":{"attributes":{"65281":{"1":2955,"4":5032,"5":43,"6":[0,1],"10":0,"100":974,"101":6662,"102":99582},"modelId":"lumi.weather","manufacturerName":"LUMI","powerSource":3,"zclVersion":1}}},"_binds":[]}],"_manufacturerName":"LUMI","_powerSource":"Battery","_modelID":"lumi.weather","_applicationVersion":3,"_zclVersion":1,"_interviewCompleted":true,"_interviewing":false,"meta":{},"_lastSeen":1582215732064},"mapped":{"zigbeeModel":["lumi.weather"],"model":"WSDCGQ11LM","vendor":"Xiaomi","description":"Aqara temperature, humidity and pressure sensor","supports":"temperature, humidity and pressure","fromZigbee":[{"cluster":"genBasic","type":["attributeReport","readResponse"]},{"cluster":"msTemperatureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"msRelativeHumidity","type":["attributeReport","readResponse"]},{"cluster":"msPressureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"genBasic","type":["attributeReport","readResponse"]}],"toZigbee":[]},"name":"0x00158d0002ea75fd"}
2020-02-20 17:42:41.093 - info: zigbee.0 (2731) 0x00158d0002ea75fd (addr 9722): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
2020-02-20 17:42:41.093 - debug: zigbee.0 (2731) New device event: {"type":"device","device":{"ID":6,"_type":"EndDevice","_ieeeAddr":"0x00158d0003499a1b","_networkAddress":16023,"_manufacturerID":4151,"_endpoints":[{"ID":1,"profileID":260,"deviceID":24321,"inputClusters":[0,3,65535,1026,1027,1029],"outputClusters":[0,4,65535],"deviceNetworkAddress":16023,"deviceIeeeAddress":"0x00158d0003499a1b","clusters":{"msTemperatureMeasurement":{"attributes":{"measuredValue":1971}},"msRelativeHumidity":{"attributes":{"measuredValue":4519}},"msPressureMeasurement":{"attributes":{"16":9946,"20":-1,"measuredValue":994}},"genBasic":{"attributes":{"65281":{"1":2965,"4":5032,"5":6,"6":[0,1],"10":0,"100":1973,"101":4521,"102":99462},"modelId":"lumi.weather","manufacturerName":"LUMI","powerSource":3,"zclVersion":1,"appVersion":3,"stackVersion":2,"hwVersion":30,"dateCode":"20161129","swBuildId":"3000-0001"}}},"_binds":[]}],"_manufacturerName":"LUMI","_powerSource":"Battery","_modelID":"lumi.weather","_applicationVersion":3,"_stackVersion":2,"_zclVersion":1,"_hardwareVersion":30,"_dateCode":"20161129","_softwareBuildID":"3000-0001","_interviewCompleted":true,"_interviewing":false,"meta":{},"_lastSeen":1582215741312},"mapped":{"zigbeeModel":["lumi.weather"],"model":"WSDCGQ11LM","vendor":"Xiaomi","description":"Aqara temperature, humidity and pressure sensor","supports":"temperature, humidity and pressure","fromZigbee":[{"cluster":"genBasic","type":["attributeReport","readResponse"]},{"cluster":"msTemperatureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"msRelativeHumidity","type":["attributeReport","readResponse"]},{"cluster":"msPressureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"genBasic","type":["attributeReport","readResponse"]}],"toZigbee":[]},"name":"0x00158d0003499a1b"}
2020-02-20 17:42:41.093 - info: zigbee.0 (2731) 0x00158d0003499a1b (addr 16023): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
2020-02-20 17:42:41.093 - debug: zigbee.0 (2731) New device event: {"type":"device","device":{"ID":7,"_type":"Router","_ieeeAddr":"0x84182600000fc87e","_networkAddress":17102,"_manufacturerID":48042,"_endpoints":[{"ID":3,"profileID":49246,"deviceID":16,"inputClusters":[4096,0,3,4,5,6,2820,64527],"outputClusters":[25],"deviceNetworkAddress":17102,"deviceIeeeAddress":"0x84182600000fc87e","clusters":{"genBasic":{"attributes":{"modelId":"Plug 01","manufacturerName":"OSRAM","powerSource":1,"zclVersion":1,"appVersion":12,"stackVersion":2,"hwVersion":1,"dateCode":"20140331DEOS****","swBuildId":"V1.04.12"}},"genOnOff":{"attributes":{"onOff":0}}},"_binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b0018e1f888","endpointID":12}]}],"_manufacturerName":"OSRAM","_powerSource":"Mains (single phase)","_modelID":"Plug 01","_applicationVersion":12,"_stackVersion":2,"_zclVersion":1,"_hardwareVersion":1,"_dateCode":"20140331DEOS****","_softwareBuildID":"V1.04.12","_interviewCompleted":true,"_interviewing":false,"meta":{"configured":1},"_lastSeen":1582215737149},"mapped":{"zigbeeModel":["Plug 01"],"model":"AB3257001NJ","description":"Smart+ plug","supports":"on/off","vendor":"OSRAM","fromZigbee":[{"cluster":"genOnOff","type":["attributeReport","readResponse"]}],"toZigbee":[{"key":["state"]}],"meta":{"configureKey":1}},"name":"0x84182600000fc87e"}
2020-02-20 17:42:41.094 - info: zigbee.0 (2731) 0x84182600000fc87e (addr 17102): AB3257001NJ - OSRAM Smart+ plug (Router)
2020-02-20 17:42:41.094 - debug: zigbee.0 (2731) New device event: {"type":"device","device":{"ID":8,"_type":"EndDevice","_ieeeAddr":"0x00158d000309a256","_networkAddress":2947,"_manufacturerID":4151,"_endpoints":[{"ID":1,"inputClusters":[],"outputClusters":[],"deviceNetworkAddress":2947,"deviceIeeeAddress":"0x00158d000309a256","clusters":{"genBasic":{"attributes":{"65281":{"1":2975,"4":5032,"5":5,"6":[0,262145],"10":17102,"100":2243,"101":3943,"102":99773},"modelId":"lumi.weather","appVersion":3}},"msTemperatureMeasurement":{"attributes":{"measuredValue":2259}},"msPressureMeasurement":{"attributes":{"16":9958,"20":-1,"measuredValue":995}},"msRelativeHumidity":{"attributes":{"measuredValue":3878}}},"_binds":[]}],"_manufacturerName":"LUMI","_powerSource":"Battery","_modelID":"lumi.weather","_applicationVersion":3,"_interviewCompleted":true,"_interviewing":false,"meta":{},"_lastSeen":1582215582949},"mapped":{"zigbeeModel":["lumi.weather"],"model":"WSDCGQ11LM","vendor":"Xiaomi","description":"Aqara temperature, humidity and pressure sensor","supports":"temperature, humidity and pressure","fromZigbee":[{"cluster":"genBasic","type":["attributeReport","readResponse"]},{"cluster":"msTemperatureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"msRelativeHumidity","type":["attributeReport","readResponse"]},{"cluster":"msPressureMeasurement","type":["attributeReport","readResponse"]},{"cluster":"genBasic","type":["attributeReport","readResponse"]}],"toZigbee":[]},"name":"0x00158d000309a256"}
2020-02-20 17:42:41.094 - info: zigbee.0 (2731) 0x00158d000309a256 (addr 2947): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
2020-02-20 17:42:41.094 - info: zigbee.0 (2731) Zigbee started
2020-02-20 17:42:59.694 - debug: zigbee.0 (2731) system.adapter.admin.0: logging false
2020-02-20 17:43:34.078 - debug: zigbee.0 (2731) system.adapter.admin.0: logging true

[godlich2019]

Sorry, hat sich wohlmöglich erledigt, zumindest ist er wieder grün.

Serielle Schnittstelle war das Problem.

Mit dem Befehl bin ich drauf gekommen und musste ttyACM0 wieder im Zigbee Adapter hinterlegen.

simon@debian-simon:~$ ls -la /dev/serial/by-id
insgesamt 0
drwxr-xr-x 2 root root 60 Feb  7 07:02 .
drwxr-xr-x 4 root root 80 Feb  7 07:02 ..
lrwxrwxrwx 1 root root 13 Feb  7 07:02 usb-Texas_Instruments_TI_CC2531_USB_CDC__                                    _0X00124B000BE5ADB1-if00 -> ../../ttyACM0

[Asgothian]

@steimi
Die Fehlermeldung deutet darauf hin das die serielle Schnittstelle nicht geöffnet werden kann. Es ist also zu prüfen:

• ist der Stick sauber an die vom weitergereicht
• hat der Nutzer iobroker schreib und leserechte auf den seriellen Port ?

A.

[steimi]

@Asgothian das hatte ich auch gesehen. Das funktioniert aber nach einem Neustart der Instanz. Ich vermute daher eher ein Timing-Problem beim Start der VM und Start des ioBroker bzw der Instanz des Adapters. Wie gesagt reicht ja meist ein Neustart der Instanz. Teilweise muss in der Konfiguartion der Instanz einfach nochmal der /dev/ttyACM0 im Dropdown ausgewählt werden und das Speichern+Beenden in der Konfig und der Adaper startet.

[Asgothian]

@steimi ich denke dann dauert es einfach zu lange bis proxmox die serielle Schnittstelle weiter reicht. Ggf. Kannst du den Start des iobroker bei einem Neustart verzögern ?

A.

[steimi]

Okay, wäre ein Versuch wert. Wie mache ich das?

[Einstein67]

@Asgothian said in Zigbee adapter nach upgrade gelb:

ich denke dann dauert es einfach zu lange bis proxmox die serielle Schnittstelle weiter reicht

Komisch. Ich hab IOBroker mit Zigbee 1.0.3 Adapter in einem Proxmox Container am laufen, und hab überhaupt keine Timing-Probleme beim starten von IOBroker oder des Containers ,,,

[steimi]

mein Zigbee-Adapter lief die letzte Zeit stabil (auch nach Updates auf die 1.1.1). Heute habe ich dann den js-Controller von der 3.0.16 auf die 3.0.18 aktualisiert. danach startete der Adapter nicht mehr - Meldung: need pairing first. Habe daraufhin die ioBroker-VM in Proxmox 2mal neu gestartet und in den Adapter-Einstellungen von Zigbee den Port nochmal eingestellt und gespeichert. Danach funktioniert es nun wieder. Nachfolgend die Logs von heute (wieder der 'can not lock port-Error).

zigbee.0	2020-04-24 06:01:58.871	info	(4581) Zigbee started
zigbee.0	2020-04-24 06:01:58.871	info	(4581) 0x00158d0003499a1b (addr 55984): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
zigbee.0	2020-04-24 06:01:58.870	info	(4581) 0x84182600000fc87e (addr 41677): AB3257001NJ - OSRAM Smart+ plug (Router)
zigbee.0	2020-04-24 06:01:58.870	info	(4581) 0x00158d0002e99611 (addr 41990): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
zigbee.0	2020-04-24 06:01:58.869	info	(4581) 0x00158d0002ea75fd (addr 22009): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
zigbee.0	2020-04-24 06:01:58.868	info	(4581) 0x00158d000309a256 (addr 12940): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
zigbee.0	2020-04-24 06:01:58.868	info	(4581) 0x00158d00036d347a (addr 54068): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
zigbee.0	2020-04-24 06:01:58.867	info	(4581) 0x00158d0003499ad7 (addr 52718): WSDCGQ11LM - Xiaomi Aqara temperature, humidity and pressure sensor (EndDevice)
zigbee.0	2020-04-24 06:01:58.864	info	(4581) Currently 7 devices are joined:
zigbee.0	2020-04-24 06:01:58.860	info	(4581) --> transmitPower : normal
zigbee.0	2020-04-24 06:01:58.854	info	(4581) Coordinator firmware version: {"type":"zStack30x","meta":{"transportrev":2,"product":2,"majorrel":2,"minorrel":7,"maintrel":2,"revision":20200327}}
zigbee.0	2020-04-24 06:01:57.645	info	(4581) Starting Zigbee...
zigbee.0	2020-04-24 06:01:57.631	info	(4581) starting. Version 1.1.1 in /opt/iobroker/node_modules/iobroker.zigbee, node: v12.16.2, js-controller: 3.0.18
zigbee.0	2020-04-24 06:01:53.330	error	at stop (/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:8189:22)
zigbee.0	2020-04-24 06:01:53.330	error	at Zigbee.emit (events.js:310:20)
zigbee.0	2020-04-24 06:01:53.330	error	at Zigbee.onUnload (/opt/iobroker/node_modules/iobroker.zigbee/main.js:320:41)
zigbee.0	2020-04-24 06:01:53.330	error	at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:303:20)
zigbee.0	2020-04-24 06:01:53.330	error	at ZigbeeController.callExtensionMethod (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:186:42)
zigbee.0	2020-04-24 06:01:53.330	error	at DeviceAvailability.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zbDeviceAvailability.js:123:43)
zigbee.0	2020-04-24 06:01:53.330	error	at ZigbeeController.getClients (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:200:49)
zigbee.0	2020-04-24 06:01:53.330	error	at Controller.getDevices (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:223:31)
zigbee.0	2020-04-24 06:01:53.330	error	at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:220:16)
zigbee.0	2020-04-24 06:01:53.330	error	at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/device.js:200:55)
zigbee.0	2020-04-24 06:01:53.330	error	(4531) TypeError: Cannot read property 'getEntries' of null
zigbee.0	2020-04-24 06:01:53.330	error	(4531) uncaught exception: Cannot read property 'getEntries' of null
zigbee.0	2020-04-24 06:01:53.329	info	(4531) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
zigbee.0	2020-04-24 06:01:53.329	info	(4531) terminating
zigbee.0	2020-04-24 06:01:53.329	error	at processTicksAndRejections (internal/process/task_queues.js:97:5))
zigbee.0	2020-04-24 06:01:53.329	error	at ZigbeeController.stop (/opt/iobroker/node_modules/iobroker.zigbee/lib/zigbeecontroller.js:307:33)
zigbee.0	2020-04-24 06:01:53.329	error	at Controller.stop (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:174:16)
zigbee.0	2020-04-24 06:01:53.329	error	at __awaiter (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:4:12)
zigbee.0	2020-04-24 06:01:53.329	error	at new Promise (<anonymous>)
zigbee.0	2020-04-24 06:01:53.329	error	at /opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:8:71
zigbee.0	2020-04-24 06:01:53.329	error	at Generator.next (<anonymous>)
zigbee.0	2020-04-24 06:01:53.329	error	at Controller.<anonymous> (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/controller.js:178:49)
zigbee.0	2020-04-24 06:01:53.329	error	at Function.all (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:76:15)
zigbee.0	2020-04-24 06:01:53.329	error	at Function.loadFromDatabaseIfNecessary (/opt/iobroker/node_modules/zigbee-herdsman/dist/controller/model/group.js:64:55)
zigbee.0	2020-04-24 06:01:53.329	error	(4531) Failed to stop zigbee (TypeError: Cannot read property 'getEntries' of null
zigbee.0	2020-04-24 06:01:53.328	info	(4531) Zigbee: disabling joining new devices.
zigbee.0	2020-04-24 06:01:53.327	info	(4531) cleaned everything up...
zigbee.0	2020-04-24 06:01:53.326	info	(4531) Got terminate signal TERMINATE_YOURSELF
zigbee.0	2020-04-24 06:01:51.594	error	at /opt/iobroker/node_modules/@serialport/stream/lib/index.js:242:12
zigbee.0	2020-04-24 06:01:51.594	error	at SerialPort._error (/opt/iobroker/node_modules/@serialport/stream/lib/index.js:198:14)
zigbee.0	2020-04-24 06:01:51.594	error	at SerialPort.<anonymous> (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:111:49)
zigbee.0	2020-04-24 06:01:51.594	error	at __awaiter (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:4:12)
zigbee.0	2020-04-24 06:01:51.594	error	at new Promise (<anonymous>)
zigbee.0	2020-04-24 06:01:51.594	error	at /opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:8:71
zigbee.0	2020-04-24 06:01:51.594	error	at Generator.next (<anonymous>)
zigbee.0	2020-04-24 06:01:51.594	error	at Znp.<anonymous> (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:113:32)
zigbee.0	2020-04-24 06:01:51.594	error	(4531) Error: Error while opening serialport 'Error: Error Resource temporarily unavailable Cannot lock port'
zigbee.0	2020-04-24 06:01:51.593	error	(4531) Failed to start Zigbee
zigbee.0	2020-04-24 06:01:51.533	info	(4531) Starting Zigbee...
zigbee.0	2020-04-24 06:01:51.533	info	(4531) Try to reconnect. 1 attempts left
zigbee.0	2020-04-24 06:01:41.531	error	at rejected (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:6:65)
zigbee.0	2020-04-24 06:01:41.531	error	at Generator.throw (<anonymous>)
zigbee.0	2020-04-24 06:01:41.531	error	at ZStackAdapter.<anonymous> (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:71:23)
zigbee.0	2020-04-24 06:01:41.531	error	(4531) Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms)
zigbee.0	2020-04-24 06:01:41.531	error	(4531) Failed to start Zigbee
zigbee.0	2020-04-24 06:01:34.425	info	(4531) Starting Zigbee...
zigbee.0	2020-04-24 06:01:34.414	info	(4531) starting. Version 1.1.1 in /opt/iobroker/node_modules/iobroker.zigbee, node: v12.16.2, js-controller: 3.0.18

[Kueppert]

Hallo zusammen,
ich bin auf JS-Controller 3.0.20 und habe erst seit kurzem (2 Tage?) folgende Fehlermeldungen im LOG

zigbee.0	2020-04-29 08:13:57.448	error	(22760) Error: Timeout - 42681 - 3 - 5 - 0 - 1 after 10000ms at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24) at lis
zigbee.0	2020-04-29 08:13:57.447	error	(22760) uncaught exception: Timeout - 42681 - 3 - 5 - 0 - 1 after 10000ms
zigbee.0	2020-04-29 08:08:06.446	error	at processTimers (internal/timers.js:492:7)
zigbee.0	2020-04-29 08:08:06.446	error	at listOnTimeout (internal/timers.js:549:17)
zigbee.0	2020-04-29 08:08:06.446	error	at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
zigbee.0	2020-04-29 08:08:06.446	error	(10870) Error: Timeout - 1681 - 3 - 11 - 0 - 1 after 10000ms
zigbee.0	2020-04-29 08:08:06.445	error	(10870) uncaught exception: Timeout - 1681 - 3 - 11 - 0 - 1 after 10000ms
zigbee.0	2020-04-29 07:57:16.183	error	at rejected (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65)
zigbee.0	2020-04-29 07:57:16.183	error	at Generator.throw (<anonymous>)
zigbee.0	2020-04-29 07:57:16.183	error	at Endpoint.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:349:23)
zigbee.0	2020-04-29 07:57:16.183	error	(7451) Error on send command to 0x8418260000dad4e7. Error: Error: Command 0x8418260000dad4e7/3 genLevelCtrl.moveToLevelWithOnOff({"level":38,"transtime":0}, {"timeout":10000,"manufacturerCode":null,"
zigbee.0	2020-04-29 07:57:16.070	error	at rejected (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65)
zigbee.0	2020-04-29 07:57:16.070	error	at Generator.throw (<anonymous>)
zigbee.0	2020-04-29 07:57:16.070	error	at Endpoint.<anonymous> (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:349:23)
zigbee.0	2020-04-29 07:57:16.070	error	(7451) Error on send command to 0x8418260000000712. Error: Error: Command 0x8418260000000712/3 genLevelCtrl.moveToLevelWithOnOff({"level":38,"transtime":0}, {"timeout":10000,"manufacturerCode":null,"
zigbee.0	2020-04-29 07:57:15.863	error	at processTimers (internal/timers.js:492:7)
zigbee.0	2020-04-29 07:57:15.863	error	at listOnTimeout (internal/timers.js:549:17)
zigbee.0	2020-04-29 07:57:15.863	error	at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
zigbee.0	2020-04-29 07:57:15.863	error	(7451) Error: Timeout - 30525 - 3 - 3 - 8 - 11 after 10000ms
zigbee.0	2020-04-29 07:57:15.862	error	(7451) uncaught exception: Timeout - 30525 - 3 - 3 - 8 - 11 after 10000ms
zigbee.0	2020-04-29 07:57:15.783	error	at processTimers (internal/timers.js:492:7)
zigbee.0	2020-04-29 07:57:15.783	error	at listOnTimeout (internal/timers.js:549:17)
zigbee.0	2020-04-29 07:57:15.783	error	at Timeout._onTimeout (/opt/iobroker/node_modules/iobroker.zigbee/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24)
zigbee.0	2020-04-29 07:57:15.783	error	(7451) Error: Timeout - 1681 - 3 - 2 - 8 - 11 after 10000ms
zigbee.0	2020-04-29 07:57:15.782	error	(7451) uncaught exception: Timeout - 1681 - 3 - 2 - 8 - 11 after 10000ms

Vielleicht ist auf die genutzte Firmware von Interesse:

zigbee.0	2020-04-29 08:14:31.999	info	(29192) Coordinator firmware version: {"type":"zStack30x","meta":{"transportrev":2,"product":2,"majorrel":2,"minorrel":7,"maintrel":2,"revision":20190523}}

Die sehen anders aus als die bisher gelieferten Logs. Jmd eine Idee, was ich machen muss? Ein Rebuild für den Adapter hab ich bereits gemacht...
Danke euch und vG, Thorsten

[Asgothian]

@Kueppert
Eine kurze Frage:

Funktioniert der Adapter trotz der Meldungen oder ist der Adapter gelb ?

A.

[Kueppert]

@Asgothian der Adapter war trotzdem grün. Ich hab aus Gewohnheit jetzt mal den Stick wieder gezogen im laufenden Betrieb und wieder dran gesteckt. Gerade ist erst mal wieder Ruhe im Karton. Ist mir nur aufgefallen, weil meine Lampen nicht mehr geschaltet haben...Netzwerk baut sich ja nach jedem Restart vom Adapter neu auf.
Danke dir und vG, Thorsten

[Asgothian]

@Kueppert Nach den Meldungen gehe ich davon aus das es einen Timeout beim Senden oder Empfangen gibt. Insgesamt ist das Thema "timeout" und die daraus resultierenden Meldungen im Zigbee-Adapter noch ein weites Feld, es sind nicht alle Eventualitäten abgefangen, so das Fehlermeldungen ggf. bis zum ioBroker-Log durchlaufen obwohl sie eigentlich als behandelt intern abgefangen gehören.

Solange wie diese Meldungen die Folgenden Randbedingungen erfüllen würde ich mir erst einmal wenig sorgen machen.

• Der Adapter bleibt grün
• Es gibt keine offensichtlichen Funktionsausfälle (Lampen die nicht schalten, Sensoren die nicht Melden)
• Stehen im Zusammenhang mit "Timeout after 10000 ms" und "Error on send command to"

In diesem Zusammenhang gehe ich davon aus das ein Endgerät einen Befehl des Coordinators nicht quittiert hat, bzw. das die Quittierung am Coordinator nicht angekommen ist.

A.

[ldittmar]

Ich habe genau den gleichen Fehler:

zigbee.0	2020-04-29 16:06:40.370	error	(4812) Error: Error while opening serialport 'Error: Error Resource temporarily unavailable Cannot lock port' at Znp.<anonymous> (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/zn
zigbee.0	2020-04-29 16:06:40.369	error	(4812) Failed to start Zigbee
zigbee.0	2020-04-29 16:06:40.192	info	(4812) Starting Zigbee...
zigbee.0	2020-04-29 16:06:40.190	info	(4812) Try to reconnect. 1 attempts left
zigbee.0	2020-04-29 16:06:30.187	error	(4812) Error: AREQ - ZDO - activeEpRsp after 10000ms at Timeout.object.timer.setTimeout [as _onTimeout] (/opt/iobroker/node_modules/zigbee-herdsman/dist/utils/waitress.js:44:24) at ontimeout (
zigbee.0	2020-04-29 16:06:30.185	error	(4812) Failed to start Zigbee
zigbee.0	2020-04-29 16:06:18.676	info	(4812) Starting Zigbee...
zigbee.0	2020-04-29 16:06:18.641	info	(4812) starting. Version 1.1.1 in /opt/iobroker/node_modules/iobroker.zigbee, node: v10.18.1, js-controller: 3.0.20
zigbee.0	2020-04-29 16:05:45.850	info	(4564) Terminated (NO_ERROR): Without reason
zigbee.0	2020-04-29 16:05:45.848	info	(4564) terminating
zigbee.0	2020-04-29 16:05:45.341	info	(4564) Zigbee: disabling joining new devices.
zigbee.0	2020-04-29 16:05:45.338	info	(4564) cleaned everything up...
zigbee.0	2020-04-29 16:05:45.334	error	at fulfilled (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:5:58)
zigbee.0	2020-04-29 16:05:45.334	error	at Generator.next (<anonymous>)
zigbee.0	2020-04-29 16:05:45.334	error	at Znp.<anonymous> (/opt/iobroker/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:246:27)
zigbee.0	2020-04-29 16:05:45.334	error	(4564) Error: SREQ '--> AF - interPanCtl - {"cmd":1,"data":[18]}' failed with status '186' (expected '0')
zigbee.0	2020-04-29 16:05:45.334	error	(4564) uncaught exception: SREQ '--> AF - interPanCtl - {"cmd":1,"data":[18]}' failed with status '186' (expected '0')
zigbee.0	2020-04-29 16:05:45.324	info	(4564) Starting interview of '0x00158d00045c13ef'
zigbee.0	2020-04-29 16:05:31.403	info	(4564) Zigbee: allowing new devices to join.

ich wollte ein neuen Gerät anlernen und dann ist es abgestürzt. Ich kann also keine neue Geräte anlernen

Stick raus, dann wieder rein und dann kann der Adapter neu gestartet werden. Nur das Anlernen ist nicht mehr möglich....

---

Model: Intel(R) Celeron(R) CPU J3455 @ 1.50GHz, RAM: 7.64 GB, Node.js: v10.18.1, NPM: 6.13.4, Architecture: x64, Distribution: Debian GNU/Linux, Platform: linux, JS-Controller (1): 3.0.20, Admin: 4.1.1, Zigbee: 1.1.1

[craysee]

Hallo,

hab heute das gleiche Problem festgestellt. Wollte ein neues Gerät anlernen, und dann ging es los wie oben genannt. Habe auch den Stick entfernt, wieder ran und dann den Adapter wieder gestartet. Jetzt läuft es erstmal wieder. Einen weiteren Anlernversuch habe ich dann erstmal unterlassen. (Zigbee 1.1.1)

Gruß

Cray