Connect sonoff dongle

switch · February 12, 2025, 1:44am

I gave up on the Raspberry Pi 3. Starting from scratch with new Raspberry Pi 5 and openHAB 4.3.2

Based on this: Serial Port Configuration | openHAB
I used openhabian-config to prepare serial port

Rebooted

Added the ZigBee Binding. Manually added the Ember Controller thing.

Still initializing>

settings for thing:

Not sure exactly what to set to debug

[02:43:42] root@openhabian:/# cat ./var/log/openhab/openhab.log | grep DEBUG
2025-02-12 02:41:23.315 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 02:43:35.090 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-12 02:43:35.090 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-12 02:43:35.090 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown

also started on this route:

files no longer exist in this location.

going back over everything

I set this to High RAM and save. It reverts back to Low RAM

and did I chose the right port, assumed I want the one that says Sonoff

chris · February 12, 2025, 3:34am

set com.zsmartsystems.zigbee also to debug.

You should view the log using the log file itself - othewise the system will only “display” the last 100 entries - so your grep will show any zigbee log entries in the last 100 entries, and here we see nothing useful - just the shutdown - not the reason or what happened.

switch · February 13, 2025, 4:50am

     at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:569) ~[?:?]
        at org.apache.cxf.service.invoker.AbstractInvoker.performInvocation(AbstractInvoker.java:179) ~[bundleFile:3.6.2]
        at org.apache.cxf.service.invoker.AbstractInvoker.invoke(AbstractInvoker.java:96) ~[bundleFile:3.6.2]
        at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:201) ~[bundleFile:3.6.2]
        at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:104) ~[bundleFile:3.6.2]
        at org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInterceptor.java:59) ~[bundleFile:3.6.2]
        at org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceInvokerInterceptor.java:96) ~[bundleFile:3.6.2]
        at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:307) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:304) ~[bundleFile:3.6.2]
        at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPut(AbstractHTTPServlet.java:234) ~[bundleFile:3.6.2]
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:520) ~[bundleFile:4.0.4]
        at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:279) ~[bundleFile:3.6.2]
        at org.ops4j.pax.web.service.spi.servlet.OsgiInitializedServlet.service(OsgiInitializedServlet.java:102) ~[bundleFile:?]
        at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[bundleFile:9.4.54.v20240208]
        at org.ops4j.pax.web.service.spi.servlet.OsgiFilterChain.doFilter(OsgiFilterChain.java:113) ~[bundleFile:?]
        at org.ops4j.pax.web.service.jetty.internal.PaxWebServletHandler.doHandle(PaxWebServletHandler.java:334) ~[bundleFile:?]
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[bundleFile:9.4.54.v20240208]
        at org.ops4j.pax.web.service.jetty.internal.PrioritizedHandlerCollection.handle(PrioritizedHandlerCollection.java:96) ~[bundleFile:?]
        at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:722) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.54.v20240208]
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.54.v20240208]
        at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: org.xml.sax.SAXParseException: Premature end of file.
        at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(DOMParser.java:262) ~[?:?]
        at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(DocumentBuilderImpl.java:342) ~[?:?]
        at org.apache.karaf.log.core.internal.LogServiceLog4j2XmlImpl.loadConfig(LogServiceLog4j2XmlImpl.java:229) ~[?:?]
        at org.apache.karaf.log.core.internal.LogServiceLog4j2XmlImpl.loadConfig(LogServiceLog4j2XmlImpl.java:209) ~[?:?]
        at org.apache.karaf.log.core.internal.LogServiceLog4j2XmlImpl.setLevel(LogServiceLog4j2XmlImpl.java:98) ~[?:?]
        ... 61 more
2025-02-12 02:41:23.315 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 02:43:35.090 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-12 02:43:35.090 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-12 02:43:35.090 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 02:50:40.370 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 02:50:40.371 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 02:50:40.372 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 02:50:40.372 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 02:50:40.373 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Processing zigbee_concentrator -> 1
2025-02-12 02:50:40.373 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 02:50:40.373 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 02:50:40.374 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 02:50:40.374 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 02:50:40.374 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 02:50:40.375 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 02:50:40.375 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 02:50:40.375 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 02:50:40.376 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 02:50:40.376 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 02:50:40.376 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 02:50:40.376 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 02:50:40.377 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2025-02-12 02:50:40.377 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_concentrator -> 1
2025-02-12 02:50:40.377 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Unhandled configuration parameter zigbee_concentrator >> 1.
2025-02-12 02:50:50.558 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 02:50:50.559 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 02:50:50.559 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 02:50:50.559 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 02:50:50.560 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-12 02:50:50.560 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 02:50:50.560 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 02:50:50.561 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 02:50:50.561 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 02:50:50.561 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 02:50:50.562 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 02:50:50.562 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 02:50:50.562 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 02:50:50.562 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 02:50:50.563 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 02:50:50.563 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 02:50:50.563 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 02:51:20.772 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 02:51:20.773 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 02:51:20.773 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 02:51:20.773 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 02:51:20.774 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-12 02:51:20.774 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 02:51:20.774 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 02:51:20.774 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 02:51:20.775 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 02:51:20.775 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 02:51:20.775 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 02:51:20.775 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 02:51:20.776 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 02:51:20.776 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 02:51:20.776 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 02:51:20.776 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 02:51:20.777 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 02:51:59.722 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 02:51:59.722 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-12 02:51:59.722 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-12 02:51:59.722 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - UNINITIALIZED: Ignoring OFFLINE status: Thing is {}
2025-02-12 02:52:04.722 [WARN ] [core.thing.internal.ThingManagerImpl] - Disposing handler for thing 'zigbee:coordinator_ember:9439d84ac9' takes more than 5000ms.
2025-02-12 02:52:04.726 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 5 MultipleDynamic removed {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=545, service.bundleid=279, service.scope=singleton} (enter)
2025-02-12 02:52:04.727 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : getting unbind: removeZigBeeCoordinatorHandler
2025-02-12 02:52:04.727 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : Locating method removeZigBeeCoordinatorHandler in class org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService
2025-02-12 02:52:04.728 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : doFindMethod: Looking for method org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService.removeZigBeeCoordinatorHandler
2025-02-12 02:52:04.728 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : Declared Method org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService.removeZigBeeCoordinatorHandler([interface org.osgi.framework.ServiceReference]) not found
2025-02-12 02:52:04.729 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : Declared Method org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService.removeZigBeeCoordinatorHandler([interface org.osgi.service.component.ComponentServiceObjects]) not found
2025-02-12 02:52:04.729 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler through loader of org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService
2025-02-12 02:52:04.729 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : getClassFromComponentClassLoader: Found class org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler
2025-02-12 02:52:04.730 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler
2025-02-12 02:52:04.730 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : doFindMethod: Found Method protected void org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService.removeZigBeeCoordinatorHandler(org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler)
2025-02-12 02:52:04.730 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : Found unbind method: protected void org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService.removeZigBeeCoordinatorHandler(org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler)
2025-02-12 02:52:04.731 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoking unbind: removeZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.ember.handler.EmberHandler]
2025-02-12 02:52:04.731 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoked unbind: removeZigBeeCoordinatorHandler
2025-02-12 02:52:04.732 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 5 MultipleDynamic removed (unbind) {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=545, service.bundleid=279, service.scope=singleton}
2025-02-12 02:52:09.178 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 02:52:09.178 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 02:52:09.179 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 02:52:09.179 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 02:52:09.180 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-12 02:52:09.180 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 02:52:09.180 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 02:52:09.180 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 02:52:09.181 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 02:52:09.181 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 02:52:09.181 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 02:52:09.182 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 02:52:09.182 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 02:52:09.182 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 02:52:09.183 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 02:52:09.183 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 02:52:09.183 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 02:52:12.944 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-12 02:52:12.944 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 02:52:12.944 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-12 02:52:12.945 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - UNINITIALIZED: Ignoring OFFLINE status: Thing is {}
2025-02-12 02:52:15.646 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 02:52:15.647 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 02:52:15.647 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 02:52:15.648 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 02:52:15.648 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-12 02:52:15.649 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 02:52:15.649 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 02:52:15.649 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 02:52:15.649 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 02:52:15.650 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 02:52:15.650 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 02:52:15.650 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 02:52:15.651 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 02:52:15.651 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 02:52:15.651 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 02:52:15.651 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 02:52:15.652 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 02:52:17.945 [WARN ] [core.thing.internal.ThingManagerImpl] - Disposing handler for thing 'zigbee:coordinator_ember:9439d84ac9' takes more than 5000ms.
2025-02-12 02:53:59.482 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 02:53:59.482 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 02:53:59.483 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 02:53:59.483 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 02:53:59.483 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-12 02:53:59.484 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 02:53:59.484 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 02:53:59.484 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 02:53:59.485 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 02:53:59.485 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 02:53:59.485 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 02:53:59.485 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 02:53:59.486 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 02:53:59.486 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 02:53:59.486 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 02:53:59.487 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 02:53:59.487 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 03:04:59.329 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 03:04:59.329 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 03:04:59.330 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 03:04:59.330 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 03:04:59.331 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-12 03:04:59.331 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 03:04:59.331 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 03:04:59.331 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 03:04:59.332 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 03:04:59.332 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 03:04:59.332 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 03:04:59.333 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 03:04:59.333 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 03:04:59.333 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 03:04:59.333 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 03:04:59.334 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 03:04:59.334 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 03:08:02.909 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 6 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=547, service.bundleid=279, service.scope=singleton} (enter)
2025-02-12 03:08:02.910 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 6 MultipleDynamic already active, binding {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=547, service.bundleid=279, service.scope=singleton}
2025-02-12 03:08:02.911 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoking bind: addZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.ember.handler.EmberHandler]
2025-02-12 03:08:02.911 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Adding ZigBeeCoordinatorHandler to discovery: zigbee:coordinator_ember:c47e634285
2025-02-12 03:08:02.911 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoked bind: addZigBeeCoordinatorHandler
2025-02-12 03:08:02.912 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 6 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=547, service.bundleid=279, service.scope=singleton} (exit)
2025-02-12 03:08:02.920 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:c47e634285].
2025-02-12 03:08:02.920 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 0
2025-02-12 03:08:02.921 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 0
2025-02-12 03:08:02.921 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 0000000000000000
2025-02-12 03:08:02.921 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2025-02-12 03:08:02.921 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A 69 67 42 65 65 41 6C 6C 69 61 6E 63 65 30 39
2025-02-12 03:08:02.922 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=true
2025-02-12 03:08:02.922 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ExtendedPanId or PanId not set: initializeNetwork=true
2025-02-12 03:08:02.922 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key initialised EB4086116E2278EF11765685448CF202
2025-02-12 03:08:02.923 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network
2025-02-12 03:08:02.923 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel set to 11.
2025-02-12 03:08:02.929 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee PAN ID [BAFF].
2025-02-12 03:08:02.936 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee extended PAN ID [5693AD6F53079380].
2025-02-12 03:08:02.948 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2025-02-12 03:08:02.949 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:baff, EPAN:5693AD6F53079380, Channel:11
2025-02-12 03:08:02.949 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2025-02-12 03:08:02.950 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2025-02-12 03:08:03.950 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2025-02-12 03:08:03.951 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2025-02-12 03:08:03.951 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - Creating ZigBee persistence folder /var/lib/openhab/zigbee/zigbee_coordinator_ember_c47e634285/
2025-02-12 03:08:03.952 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Initialize with protocol ASH2.
2025-02-12 03:08:03.952 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2025-02-12 03:08:03.952 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2025-02-12 03:08:03.954 [ERROR] [nding.zigbee.serial.ZigBeeSerialPort] - Serial Error: Port [/dev/ttyUSB0] does not exist.
2025-02-12 03:08:03.955 [ERROR] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Unable to open serial port
2025-02-12 03:08:03.956 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-12 03:08:04.963 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2025-02-12 03:08:04.964 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 03:08:04.964 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-12 03:08:04.964 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Waiting for reconnect lock
2025-02-12 03:08:04.965 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reconnect lock passed
2025-02-12 03:08:04.965 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2025-02-12 03:08:04.965 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:baff, EPAN:5693AD6F53079380, Channel:11
2025-02-12 03:08:04.966 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2025-02-12 03:08:04.966 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2025-02-12 03:08:05.966 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2025-02-12 03:08:05.967 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2025-02-12 03:08:05.968 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Initialize with protocol ASH2.
2025-02-12 03:08:05.968 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2025-02-12 03:08:05.968 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2025-02-12 03:08:05.970 [ERROR] [nding.zigbee.serial.ZigBeeSerialPort] - Serial Error: Port [/dev/ttyUSB0] does not exist.
2025-02-12 03:08:05.971 [ERROR] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Unable to open serial port
2025-02-12 03:08:05.971 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-12 03:08:10.972 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2025-02-12 03:08:10.973 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 03:08:10.973 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-12 03:08:10.974 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Waiting for reconnect lock
2025-02-12 03:08:10.974 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reconnect lock passed
2025-02-12 03:08:10.974 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2025-02-12 03:08:10.975 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:baff, EPAN:5693AD6F53079380, Channel:11
2025-02-12 03:08:10.975 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2025-02-12 03:08:10.975 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2025-02-12 03:08:11.975 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2025-02-12 03:08:11.976 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2025-02-12 03:08:11.978 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Initialize with protocol ASH2.
2025-02-12 03:08:11.978 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2025-02-12 03:08:11.979 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2025-02-12 03:08:11.981 [ERROR] [nding.zigbee.serial.ZigBeeSerialPort] - Serial Error: Port [/dev/ttyUSB0] does not exist.
2025-02-12 03:08:11.982 [ERROR] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Unable to open serial port
2025-02-12 03:08:11.982 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-12 03:08:15.715 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-12 03:08:15.715 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown
2025-02-12 03:08:15.716 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - Deleting ZigBee network state
2025-02-12 03:08:15.717 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_ember:c47e634285] closed.
2025-02-12 03:08:15.720 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 7 MultipleDynamic removed {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=547, service.bundleid=279, service.scope=singleton} (enter)
2025-02-12 03:08:15.721 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoking unbind: removeZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.ember.handler.EmberHandler]
2025-02-12 03:08:15.721 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoked unbind: removeZigBeeCoordinatorHandler
2025-02-12 03:08:15.722 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 7 MultipleDynamic removed (unbind) {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=547, service.bundleid=279, service.scope=singleton}
2025-02-12 03:13:17.652 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-12 03:13:17.653 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-12 03:13:17.654 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-12 03:13:17.654 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-12 03:13:17.654 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-12 03:13:17.654 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-12 03:13:17.655 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-12 03:13:17.655 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-12 03:13:17.655 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-12 03:13:17.655 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-12 03:13:17.656 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-12 03:13:17.656 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-12 03:13:17.656 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-12 03:13:17.656 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-12 03:13:17.657 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-12 03:13:17.657 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-12 03:13:17.657 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-12 04:48:41.845 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - AshReceiveParserThread exited.
2025-02-13 05:10:50.062 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-13 05:10:50.063 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-13 05:10:50.064 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-13 05:10:50.064 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-13 05:10:50.064 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Processing zigbee_concentrator -> 0
2025-02-13 05:10:50.065 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-13 05:10:50.065 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-13 05:10:50.065 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Processing zigbee_flowcontrol -> 2
2025-02-13 05:10:50.065 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-13 05:10:50.066 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-13 05:10:50.066 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-13 05:10:50.066 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-13 05:10:50.066 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-13 05:10:50.067 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-13 05:10:50.067 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-13 05:10:50.067 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-13 05:10:50.067 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-13 05:10:50.068 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2025-02-13 05:10:50.068 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_flowcontrol -> 2
2025-02-13 05:10:50.068 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_concentrator -> 0
2025-02-13 05:10:50.069 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Unhandled configuration parameter zigbee_concentrator >> 0.
2025-02-13 05:10:56.905 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-13 05:10:56.906 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-13 05:10:56.906 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-13 05:10:56.906 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-13 05:10:56.907 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Processing zigbee_concentrator -> 1
2025-02-13 05:10:56.907 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-13 05:10:56.907 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-13 05:10:56.907 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-13 05:10:56.908 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-13 05:10:56.908 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-13 05:10:56.908 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-13 05:10:56.908 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-13 05:10:56.909 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-13 05:10:56.909 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-13 05:10:56.909 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-13 05:10:56.909 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-13 05:10:56.910 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2025-02-13 05:10:56.910 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2025-02-13 05:10:56.910 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_concentrator -> 1
2025-02-13 05:10:56.910 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Unhandled configuration parameter zigbee_concentrator >> 1.
2025-02-13 05:11:16.462 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2025-02-13 05:11:16.463 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2025-02-13 05:11:16.463 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2025-02-13 05:11:16.464 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2025-02-13 05:11:16.464 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2025-02-13 05:11:16.464 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2025-02-13 05:11:16.465 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2025-02-13 05:11:16.465 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_flowcontrol as no change
2025-02-13 05:11:16.465 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2025-02-13 05:11:16.465 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2025-02-13 05:11:16.466 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2025-02-13 05:11:16.466 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2025-02-13 05:11:16.466 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2025-02-13 05:11:16.466 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2025-02-13 05:11:16.467 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2025-02-13 05:11:16.467 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2025-02-13 05:11:16.467 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change

chris · February 13, 2025, 7:00am

switch:

2025-02-12 03:08:03.954 [ERROR] [nding.zigbee.serial.ZigBeeSerialPort] - Serial Error: Port [/dev/ttyUSB0] does not exist.
2025-02-12 03:08:03.955 [ERROR] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Unable to open serial port

It seems the serial port doesn’t exist.

switch · February 13, 2025, 5:30pm

I think that was a test I did. I saw in the documentation that this port was selected so I tried that and got a comm error. The port I selected originally and the one I’ve been troubleshooting is

which looks right since is says Sonoff

chris · February 13, 2025, 7:51pm

Well, I don’t see this port being used in the log. With this log that you’ve provided, the only port I see trying to be opened, and the only initialisation of the binding, it uses /dev/ttyUSB0.

switch · February 14, 2025, 10:11pm

[23:10:30] root@openhabian:/# cat ./var/log/openhab/openhab.log | grep Sonoff
2025-02-14 20:59:20.934 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_52cb7a1ba64bef11abc7baa079f42d1b-if00-port0' PAN:2825, EPAN:BE2AB86558F0AE87, Channel:11
2025-02-14 20:59:21.959 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_52cb7a1ba64bef11abc7baa079f42d1b-if00-port0] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2025-02-14 20:59:22.079 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [/dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_52cb7a1ba64bef11abc7baa079f42d1b-if00-port0] is initialized.

Also somehow this setting finally changed

chris · February 14, 2025, 10:33pm

So is it working now?

That won’t really change anything wrt initialising the network. It just impacts zigbee network routing between devices so will not be related to your initialisation problems.

switch · February 14, 2025, 11:22pm

Still says “initializing”

most recent from log

 cat ./var/log/openhab/openhab.log | grep ZigBee

2025-02-14 23:38:57.917 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager shutdown: networkState=SHUTDOWN
2025-02-14 23:38:57.917 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
2025-02-14 23:38:57.917 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Deferred Write Time set to 0ms
2025-02-14 23:38:57.917 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2025-02-14 23:38:57.917 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Max Deferred Write Time set to 0ms
2025-02-14 23:38:57.917 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - UNINITIALIZED: Ignoring OFFLINE status: Thing is {}
2025-02-14 23:38:57.917 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to SHUTDOWN
2025-02-14 23:38:57.917 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: Shutdown
2025-02-14 23:38:57.917 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2025-02-14 23:38:57.917 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Shutdown

Not sure how to tell if this applies

Currently the binding is using this port:

Not ttyUSBx

I put Home Assistant on my old Pi and plugged the Sonoff dongle into it. Home Assistant recognized it right away on I confirmed the Baud rate is 115200bps.

So I plugged the dongle back into the Pi 5
More logs

2025-02-15 00:40:34.379 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 6 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=609, service.bundleid=279, service.scope=singleton} (enter)
2025-02-15 00:40:34.379 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 6 MultipleDynamic already active, binding {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=609, service.bundleid=279, service.scope=singleton}
2025-02-15 00:40:34.380 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoking bind: addZigBeeCoordinatorHandler: parameters [org.openhab.binding.zigbee.ember.handler.EmberHandler]
2025-02-15 00:40:34.380 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Adding ZigBeeCoordinatorHandler to discovery: zigbee:coordinator_ember:9439d84ac9
2025-02-15 00:40:34.380 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : invoked bind: addZigBeeCoordinatorHandler
2025-02-15 00:40:34.380 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - bundle org.openhab.binding.zigbee:4.3.2 (274)[org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService(339)] : dm ZigBeeCoordinatorHandler tracking 6 MultipleDynamic added {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=609, service.bundleid=279, service.scope=singleton} (exit)
2025-02-15 00:40:34.662 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:9439d84ac9].
2025-02-15 00:40:34.662 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2025-02-15 00:40:34.662 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 10277
2025-02-15 00:40:34.662 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID BE2AB86558F0AE87
2025-02-15 00:40:34.663 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key D801DDF50DAEE6D2796249FB38C873E3
2025-02-15 00:40:34.663 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2025-02-15 00:40:34.663 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=true
2025-02-15 00:40:34.663 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network
2025-02-15 00:40:35.080 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2025-02-15 00:40:35.081 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_52cb7a1ba64bef11abc7baa079f42d1b-if00-port0' PAN:2825, EPAN:BE2AB86558F0AE87, Channel:11
2025-02-15 00:40:35.081 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2025-02-15 00:40:36.082 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2025-02-15 00:40:36.082 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2025-02-15 00:40:36.082 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Default: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
2025-02-15 00:40:36.083 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=2, interTransactionDelay=4000, maxRetries=0]
2025-02-15 00:40:36.083 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2025-02-15 00:40:36.083 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager initialize: networkState=UNINITIALISED
2025-02-15 00:40:36.083 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
2025-02-15 00:40:36.083 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP Dongle: Initialize with protocol ASH2.
2025-02-15 00:40:36.084 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2025-02-15 00:40:36.084 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_52cb7a1ba64bef11abc7baa079f42d1b-if00-port0] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2025-02-15 00:40:36.339 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [/dev/serial/by-id/usb-Itead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_V2_52cb7a1ba64bef11abc7baa079f42d1b-if00-port0] is initialized.
2025-02-15 00:41:53.390 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_ember:9439d84ac9
2025-02-15 00:41:53.390 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee coordinator is offline - aborted scan for zigbee:coordinator_ember:9439d84ac9
2025-02-15 01:19:20.124 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2025-02-15 01:19:20.124 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_initialise -> true

chris · February 15, 2025, 7:39am

Please do this. It will provide logging so we can see what is going on. At the moment I see very little, and there’s no issue showing up so I can’t really help.

MikeJMajor · February 15, 2025, 9:31am

Have you tried using software flow control?

switch · February 15, 2025, 4:23pm

It was already set to debug

Maybe the entire openhab.log would help so I put on Google drive:

I tried setting to software flow control and 57600 but then I got a com error instead of initializing.

MikeJMajor · February 16, 2025, 9:33am

Did you try those settings rather than using 57600?

switch · February 16, 2025, 4:29pm

Software (XOn/Xoff) and 115200 produces status ONLINE

How to update this documentation?

Also, thank you!

MikeJMajor · February 16, 2025, 6:39pm

The documentation has already been updated in main, I guess we need to wait until OH 5.0 to see it.

sihui · February 16, 2025, 6:50pm

Switch the docs to “Latest 5.0.0” or go directly to
https://next.openhab.org/addons/