2018-09-19 22:30:05.631 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=119, service.bundleid=203, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=8} - org.openhab.binding.zigbee 2018-09-19 22:30:05.656 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STARTING - org.openhab.binding.zigbee 2018-09-19 22:30:05.659 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STARTED - org.openhab.binding.zigbee 2018-09-19 22:30:05.697 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService 2018-09-19 22:30:05.778 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=120, service.bundleid=203, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=9} - org.openhab.binding.zigbee 2018-09-19 22:30:05.783 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=121, service.bundleid=203, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=10} - org.openhab.binding.zigbee 2018-09-19 22:30:05.818 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee 2018-09-19 22:30:05.833 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=122, service.bundleid=204, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=11} - org.openhab.binding.zigbee.cc2531 2018-09-19 22:30:05.838 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531 2018-09-19 22:30:05.843 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531 2018-09-19 22:30:05.882 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531 2018-09-19 22:30:05.898 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=123, service.bundleid=205, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=12} - org.openhab.binding.zigbee.ember 2018-09-19 22:30:06.106 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember 2018-09-19 22:30:06.110 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember 2018-09-19 22:30:06.122 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=124, service.bundleid=205, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=13} - org.openhab.binding.zigbee.ember 2018-09-19 22:30:06.125 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember 2018-09-19 22:30:06.140 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=125, service.bundleid=206, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=14} - org.openhab.binding.zigbee.telegesis 2018-09-19 22:30:06.144 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis 2018-09-19 22:30:06.147 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis 2018-09-19 22:30:06.156 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=126, service.bundleid=206, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=15} - org.openhab.binding.zigbee.telegesis 2018-09-19 22:30:06.158 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis 2018-09-19 22:30:06.168 [DEBUG] [org.openhab.binding.zigbee.xbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=127, service.bundleid=207, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=16} - org.openhab.binding.zigbee.xbee 2018-09-19 22:30:06.172 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee 2018-09-19 22:30:06.175 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee 2018-09-19 22:30:06.181 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee 2018-09-19 22:30:08.134 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder 2018-09-19 22:30:08.602 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel 2018-09-19 22:30:09.049 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.178.51:8080 2018-09-19 22:30:09.053 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.178.51:8443 2018-09-19 22:30:23.185 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007 2018-09-19 22:30:25.641 [DEBUG] [com.zsmartsystems.zigbee ] - BundleEvent STARTING - com.zsmartsystems.zigbee 2018-09-19 22:30:25.663 [DEBUG] [com.zsmartsystems.zigbee ] - BundleEvent STARTED - com.zsmartsystems.zigbee 2018-09-19 22:30:25.671 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.cc2531 2018-09-19 22:30:25.682 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.cc2531 2018-09-19 22:30:25.694 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.ember 2018-09-19 22:30:25.705 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.ember 2018-09-19 22:30:25.711 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.telegesis 2018-09-19 22:30:25.719 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.telegesis 2018-09-19 22:30:25.726 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.xbee 2018-09-19 22:30:25.734 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.xbee 2018-09-19 22:30:26.347 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 2018-09-19 22:30:26.483 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui 2018-09-19 22:32:01.549 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:coordinator_ember:012420A5' to inbox. 2018-09-19 22:32:12.321 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=351, service.bundleid=205, service.scope=singleton} - org.openhab.binding.zigbee.ember 2018-09-19 22:32:12.338 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=352, service.bundleid=205, service.scope=singleton} - org.openhab.binding.zigbee.ember 2018-09-19 22:32:12.392 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler. 2018-09-19 22:32:12.395 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:012420A5]. 2018-09-19 22:32:12.405 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel -1 2018-09-19 22:32:12.407 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 0 2018-09-19 22:32:12.409 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 0000000000000000 2018-09-19 22:32:12.413 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2018-09-19 22:32:12.415 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key null 2018-09-19 22:32:12.417 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network 2018-09-19 22:32:12.419 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee PAN ID [400F]. 2018-09-19 22:32:12.438 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee extended PAN ID [9D56B8434F75EFAB]. 2018-09-19 22:32:12.457 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2018-09-19 22:32:12.462 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key initialised 1FADCE857757B200B641391D770E04E7 2018-09-19 22:32:12.466 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 1FADCE857757B200B641391D770E04E7 2018-09-19 22:32:12.467 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 2018-09-19 22:32:12.469 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String has invalid format. Revert to default key. 2018-09-19 22:32:12.472 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039 2018-09-19 22:32:12.571 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:400f, EPAN:9D56B8434F75EFAB, Channel:-1 2018-09-19 22:32:12.582 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start 2018-09-19 22:32:13.459 [ERROR] [nternal.DiscoveryServiceRegistryImpl] - Cannot trigger scan for thing types '[zigbee:smartthings_motionv4, zigbee:philips_sml001]' on 'ZigBeeDiscoveryService'! java.lang.NullPointerException: null at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.getNodes(ZigBeeCoordinatorHandler.java:710) ~[?:?] at org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService.startScan(ZigBeeDiscoveryService.java:134) ~[?:?] at org.eclipse.smarthome.config.discovery.AbstractDiscoveryService.startScan(AbstractDiscoveryService.java:208) ~[96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.config.discovery.internal.DiscoveryServiceRegistryImpl.startScan(DiscoveryServiceRegistryImpl.java:427) [96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.config.discovery.internal.DiscoveryServiceRegistryImpl.startScans(DiscoveryServiceRegistryImpl.java:403) [96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.config.discovery.internal.DiscoveryServiceRegistryImpl.startScan(DiscoveryServiceRegistryImpl.java:260) [96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.io.rest.core.internal.discovery.DiscoveryResource.scan(DiscoveryResource.java:97) [116:org.eclipse.smarthome.io.rest.core:0.10.0.201809111909] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?] at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [84:org.eclipse.jetty.servlet:9.4.11.v20180605] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [84:org.eclipse.jetty.servlet:9.4.11.v20180605] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [81:org.eclipse.jetty.security:9.4.11.v20180605] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [84:org.eclipse.jetty.servlet:9.4.11.v20180605] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.Server.handle(Server.java:531) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [74:org.eclipse.jetty.io:9.4.11.v20180605] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [74:org.eclipse.jetty.io:9.4.11.v20180605] at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [74:org.eclipse.jetty.io:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [86:org.eclipse.jetty.util:9.4.11.v20180605] at java.lang.Thread.run(Thread.java:748) [?:?] 2018-09-19 22:32:13.588 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting 2018-09-19 22:32:13.591 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator 2018-09-19 22:32:13.628 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280 2018-09-19 22:32:13.641 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting 2018-09-19 22:32:13.775 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE 2018-09-19 22:32:13.778 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25 2018-09-19 22:32:13.780 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE 2018-09-19 22:32:13.785 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize with protocol ASH2. 2018-09-19 22:32:13.792 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF. 2018-09-19 22:32:13.814 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized. 2018-09-19 22:32:13.880 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - AshFrameHandler thread started 2018-09-19 22:32:13.887 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst [] 2018-09-19 22:32:13.982 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.048 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameRstAck [version=2, resetCode=11, Reset: Software] 2018-09-19 22:32:15.052 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Connected 2018-09-19 22:32:15.055 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspVersionRequest [desiredProtocolVersion=4] 2018-09-19 22:32:15.061 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=01 00 00 04] 2018-09-19 22:32:15.174 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=01 80 00 04 02 00 58] 2018-09-19 22:32:15.179 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=01 00 00 04] 2018-09-19 22:32:15.191 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-19 22:32:15.199 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:15.199 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-19 22:32:15.202 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-19 22:32:15.208 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.211 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE] 2018-09-19 22:32:15.214 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=02 00 52 1A] 2018-09-19 22:32:15.326 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=02 80 52 00 00 00] 2018-09-19 22:32:15.331 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=02 00 52 1A] 2018-09-19 22:32:15.360 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:15.365 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:15.365 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:15.370 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.372 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL] 2018-09-19 22:32:15.375 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=03 00 52 0D] 2018-09-19 22:32:15.486 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=03 80 52 00 05 00] 2018-09-19 22:32:15.489 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=03 00 52 0D] 2018-09-19 22:32:15.491 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 22:32:15.493 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 22:32:15.494 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:15.495 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.497 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE] 2018-09-19 22:32:15.500 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=04 00 52 05] 2018-09-19 22:32:15.609 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=04 80 52 00 08 00] 2018-09-19 22:32:15.612 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=04 00 52 05] 2018-09-19 22:32:15.614 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 22:32:15.617 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 22:32:15.617 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:15.619 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.621 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE] 2018-09-19 22:32:15.623 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=05 00 52 19] 2018-09-19 22:32:15.733 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=05 80 52 00 00 00] 2018-09-19 22:32:15.736 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=05 00 52 19] 2018-09-19 22:32:15.738 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:15.740 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:15.741 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:15.743 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.744 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE] 2018-09-19 22:32:15.747 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=06 00 52 0C] 2018-09-19 22:32:15.857 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=06 80 52 00 00 00] 2018-09-19 22:32:15.860 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=06 00 52 0C] 2018-09-19 22:32:15.862 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:15.864 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:15.865 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:15.867 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.868 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT] 2018-09-19 22:32:15.872 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=07 00 52 12] 2018-09-19 22:32:15.983 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=07 80 52 00 B8 0B] 2018-09-19 22:32:15.985 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=07 00 52 12] 2018-09-19 22:32:15.988 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000] 2018-09-19 22:32:15.990 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000] 2018-09-19 22:32:15.991 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:15.993 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:15.994 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS] 2018-09-19 22:32:15.997 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=08 00 52 10] 2018-09-19 22:32:16.108 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=08 80 52 00 1E 00] 2018-09-19 22:32:16.110 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=08 00 52 10] 2018-09-19 22:32:16.112 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 22:32:16.114 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 22:32:16.115 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:16.117 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.119 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE] 2018-09-19 22:32:16.121 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=09 00 52 17] 2018-09-19 22:32:16.233 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=09 80 52 00 00 00] 2018-09-19 22:32:16.236 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=09 00 52 17] 2018-09-19 22:32:16.239 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:16.241 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:16.242 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:16.243 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.245 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS] 2018-09-19 22:32:16.249 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=0A 00 52 2D] 2018-09-19 22:32:16.359 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=0A 80 52 00 01 00] 2018-09-19 22:32:16.361 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=0A 00 52 2D] 2018-09-19 22:32:16.363 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:16.365 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:16.366 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.366 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:16.368 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE] 2018-09-19 22:32:16.370 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=0B 00 52 1E] 2018-09-19 22:32:16.480 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=0B 80 52 00 06 00] 2018-09-19 22:32:16.482 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=0B 00 52 1E] 2018-09-19 22:32:16.483 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6] 2018-09-19 22:32:16.485 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6] 2018-09-19 22:32:16.487 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:16.487 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.488 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS] 2018-09-19 22:32:16.490 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=0C 00 52 2A] 2018-09-19 22:32:16.601 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=0C 80 52 00 00 00] 2018-09-19 22:32:16.602 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=0C 00 52 2A] 2018-09-19 22:32:16.604 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:16.605 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 22:32:16.607 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.607 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:16.609 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN] 2018-09-19 22:32:16.611 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=0D 00 52 11] 2018-09-19 22:32:16.729 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=0D 80 52 00 20 00] 2018-09-19 22:32:16.730 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=0D 00 52 11] 2018-09-19 22:32:16.732 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32] 2018-09-19 22:32:16.734 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32] 2018-09-19 22:32:16.735 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:16.735 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.737 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT] 2018-09-19 22:32:16.739 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=0E 00 52 03] 2018-09-19 22:32:16.850 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=0E 80 52 00 0A 00] 2018-09-19 22:32:16.852 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=0E 00 52 03] 2018-09-19 22:32:16.854 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 22:32:16.857 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 22:32:16.858 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.859 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:16.861 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE] 2018-09-19 22:32:16.863 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=0F 00 52 2B] 2018-09-19 22:32:16.972 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=0F 80 52 00 0F 00] 2018-09-19 22:32:16.974 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=0F 00 52 2B] 2018-09-19 22:32:16.976 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 22:32:16.979 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 22:32:16.980 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:16.980 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:16.982 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE] 2018-09-19 22:32:16.984 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=10 00 52 02] 2018-09-19 22:32:17.094 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=10 80 52 00 10 00] 2018-09-19 22:32:17.098 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=10 00 52 02] 2018-09-19 22:32:17.100 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:17.103 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:17.103 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:17.106 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:17.106 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT] 2018-09-19 22:32:17.111 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=11 00 52 01] 2018-09-19 22:32:17.221 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=11 80 52 00 4B 00] 2018-09-19 22:32:17.225 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=11 00 52 01] 2018-09-19 22:32:17.229 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75] 2018-09-19 22:32:17.232 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:17.232 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75] 2018-09-19 22:32:17.235 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15 2018-09-19 22:32:17.237 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 0 2018-09-19 22:32:17.239 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 0 2018-09-19 22:32:17.242 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8 2018-09-19 22:32:17.246 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 0 2018-09-19 22:32:17.249 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 6 2018-09-19 22:32:17.250 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 75 2018-09-19 22:32:17.254 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5 2018-09-19 22:32:17.256 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 3000 2018-09-19 22:32:17.259 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10 2018-09-19 22:32:17.262 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 0 2018-09-19 22:32:17.264 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30 2018-09-19 22:32:17.267 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 32 2018-09-19 22:32:17.270 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16 2018-09-19 22:32:17.272 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1 2018-09-19 22:32:17.275 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 0 2018-09-19 22:32:17.278 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:17.281 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY] 2018-09-19 22:32:17.284 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=12 00 56 00] 2018-09-19 22:32:17.393 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=12 80 56 00 01] 2018-09-19 22:32:17.396 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=12 00 56 00] 2018-09-19 22:32:17.400 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 22:32:17.403 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 22:32:17.403 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:17.406 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:17.410 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY] 2018-09-19 22:32:17.413 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=13 00 56 01] 2018-09-19 22:32:17.524 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=13 80 56 00 10] 2018-09-19 22:32:17.529 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=13 00 56 01] 2018-09-19 22:32:17.531 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION] 2018-09-19 22:32:17.534 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:17.534 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION] 2018-09-19 22:32:17.537 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:17.540 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY] 2018-09-19 22:32:17.543 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=14 00 56 04] 2018-09-19 22:32:17.655 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=14 80 56 00 40] 2018-09-19 22:32:17.657 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=14 00 56 04] 2018-09-19 22:32:17.660 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 22:32:17.661 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:17.661 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 22:32:17.664 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY] 2018-09-19 22:32:17.664 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:17.667 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=15 00 56 05] 2018-09-19 22:32:17.777 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=15 80 56 00 50] 2018-09-19 22:32:17.779 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=15 00 56 05] 2018-09-19 22:32:17.781 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS] 2018-09-19 22:32:17.783 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS] 2018-09-19 22:32:17.783 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:17.786 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS 2018-09-19 22:32:17.789 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_DISALLOW_BINDING_MODIFICATION 2018-09-19 22:32:17.791 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK 2018-09-19 22:32:17.794 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS 2018-09-19 22:32:17.797 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16] 2018-09-19 22:32:17.799 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:17.801 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16] 2018-09-19 22:32:17.804 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=16 00 53 1A 10 00] 2018-09-19 22:32:17.919 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=16 80 53 00] 2018-09-19 22:32:17.922 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=16 00 53 1A 10 00] 2018-09-19 22:32:17.925 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:17.927 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:17.927 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:17.930 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5] 2018-09-19 22:32:17.932 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:17.934 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5] 2018-09-19 22:32:17.938 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=17 00 53 0D 05 00] 2018-09-19 22:32:18.047 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=17 80 53 00] 2018-09-19 22:32:18.050 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=17 00 53 0D 05 00] 2018-09-19 22:32:18.052 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.054 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:18.054 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.057 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8] 2018-09-19 22:32:18.059 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.061 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8] 2018-09-19 22:32:18.065 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=18 00 53 05 08 00] 2018-09-19 22:32:18.180 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=18 80 53 00] 2018-09-19 22:32:18.183 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=18 00 53 05 08 00] 2018-09-19 22:32:18.185 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.187 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:18.188 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.191 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2] 2018-09-19 22:32:18.194 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.196 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2] 2018-09-19 22:32:18.199 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=19 00 53 19 02 00] 2018-09-19 22:32:18.313 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=19 80 53 00] 2018-09-19 22:32:18.316 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=19 00 53 19 02 00] 2018-09-19 22:32:18.318 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.320 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:18.323 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.326 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2] 2018-09-19 22:32:18.328 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.330 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2] 2018-09-19 22:32:18.333 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=1A 00 53 0C 02 00] 2018-09-19 22:32:18.442 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=1A 80 53 00] 2018-09-19 22:32:18.445 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=1A 00 53 0C 02 00] 2018-09-19 22:32:18.448 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.450 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:18.450 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.452 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680] 2018-09-19 22:32:18.455 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.457 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680] 2018-09-19 22:32:18.461 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=1B 00 53 12 00 1E] 2018-09-19 22:32:18.570 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=1B 80 53 00] 2018-09-19 22:32:18.573 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=1B 00 53 12 00 1E] 2018-09-19 22:32:18.575 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.577 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:18.577 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.579 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30] 2018-09-19 22:32:18.582 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.584 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30] 2018-09-19 22:32:18.587 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=1C 00 53 10 1E 00] 2018-09-19 22:32:18.696 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=1C 80 53 00] 2018-09-19 22:32:18.700 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=1C 00 53 10 1E 00] 2018-09-19 22:32:18.702 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.705 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:18.705 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.707 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=1] 2018-09-19 22:32:18.709 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.711 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=1] 2018-09-19 22:32:18.714 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=1D 00 53 17 01 00] 2018-09-19 22:32:18.824 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=1D 80 53 00] 2018-09-19 22:32:18.827 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=1D 00 53 17 01 00] 2018-09-19 22:32:18.830 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.832 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:18.832 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.834 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1] 2018-09-19 22:32:18.836 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.838 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1] 2018-09-19 22:32:18.841 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=1E 00 53 2D 01 00] 2018-09-19 22:32:18.955 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=1E 80 53 00] 2018-09-19 22:32:18.958 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=1E 00 53 2D 01 00] 2018-09-19 22:32:18.961 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.963 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:18.963 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:18.965 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4] 2018-09-19 22:32:18.968 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:18.970 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4] 2018-09-19 22:32:18.972 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=1F 00 53 1E 04 00] 2018-09-19 22:32:19.087 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=1F 80 53 00] 2018-09-19 22:32:19.090 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=1F 00 53 1E 04 00] 2018-09-19 22:32:19.093 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.095 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:19.095 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.097 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1] 2018-09-19 22:32:19.099 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:19.101 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1] 2018-09-19 22:32:19.104 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=20 00 53 2A 01 00] 2018-09-19 22:32:19.215 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=20 80 53 00] 2018-09-19 22:32:19.217 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=20 00 53 2A 01 00] 2018-09-19 22:32:19.221 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.223 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:19.223 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.225 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16] 2018-09-19 22:32:19.227 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:19.229 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16] 2018-09-19 22:32:19.232 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=21 00 53 11 10 00] 2018-09-19 22:32:19.346 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=21 80 53 00] 2018-09-19 22:32:19.349 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=21 00 53 11 10 00] 2018-09-19 22:32:19.352 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.354 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.354 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:19.356 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10] 2018-09-19 22:32:19.359 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:19.362 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10] 2018-09-19 22:32:19.365 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=22 00 53 03 0A 00] 2018-09-19 22:32:19.481 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=22 80 53 00] 2018-09-19 22:32:19.483 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=22 00 53 03 0A 00] 2018-09-19 22:32:19.486 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.489 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:19.489 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.491 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15] 2018-09-19 22:32:19.493 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:19.495 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15] 2018-09-19 22:32:19.498 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=23 00 53 2B 0F 00] 2018-09-19 22:32:19.607 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=23 80 53 36] 2018-09-19 22:32:19.611 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=23 00 53 2B 0F 00] 2018-09-19 22:32:19.613 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE] 2018-09-19 22:32:19.616 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:19.616 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE] 2018-09-19 22:32:19.618 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16] 2018-09-19 22:32:19.620 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:19.622 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16] 2018-09-19 22:32:19.625 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=24 00 53 02 10 00] 2018-09-19 22:32:19.739 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=24 80 53 00] 2018-09-19 22:32:19.741 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=24 00 53 02 10 00] 2018-09-19 22:32:19.846 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.853 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:19.853 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.855 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255] 2018-09-19 22:32:19.856 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:19.858 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255] 2018-09-19 22:32:19.860 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=25 00 53 01 FF 00] 2018-09-19 22:32:19.973 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=25 80 53 00] 2018-09-19 22:32:19.974 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=25 00 53 01 FF 00] 2018-09-19 22:32:19.977 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.978 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:19.978 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:19.980 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:19.981 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE] 2018-09-19 22:32:19.983 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=26 00 52 1A] 2018-09-19 22:32:20.094 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=26 80 52 00 10 00] 2018-09-19 22:32:20.095 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=26 00 52 1A] 2018-09-19 22:32:20.097 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:20.098 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:20.098 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:20.100 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.100 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL] 2018-09-19 22:32:20.101 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=27 00 52 0D] 2018-09-19 22:32:20.215 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=27 80 52 00 05 00] 2018-09-19 22:32:20.216 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=27 00 52 0D] 2018-09-19 22:32:20.218 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 22:32:20.219 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 22:32:20.219 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:20.221 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.221 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE] 2018-09-19 22:32:20.222 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=28 00 52 05] 2018-09-19 22:32:20.336 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=28 80 52 00 08 00] 2018-09-19 22:32:20.347 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=28 00 52 05] 2018-09-19 22:32:20.348 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 22:32:20.350 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:20.350 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 22:32:20.351 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.351 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE] 2018-09-19 22:32:20.353 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=29 00 52 19] 2018-09-19 22:32:20.461 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=29 80 52 00 02 00] 2018-09-19 22:32:20.462 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=29 00 52 19] 2018-09-19 22:32:20.464 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 22:32:20.465 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:20.465 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 22:32:20.466 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE] 2018-09-19 22:32:20.466 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.468 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=2A 00 52 0C] 2018-09-19 22:32:20.583 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=2A 80 52 00 02 00] 2018-09-19 22:32:20.586 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=2A 00 52 0C] 2018-09-19 22:32:20.588 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 22:32:20.590 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:20.590 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 22:32:20.592 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.593 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT] 2018-09-19 22:32:20.595 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=2B 00 52 12] 2018-09-19 22:32:20.705 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=2B 80 52 00 00 1E] 2018-09-19 22:32:20.708 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=2B 00 52 12] 2018-09-19 22:32:20.710 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680] 2018-09-19 22:32:20.712 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:20.712 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680] 2018-09-19 22:32:20.715 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.715 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS] 2018-09-19 22:32:20.718 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=2C 00 52 10] 2018-09-19 22:32:20.828 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=2C 80 52 00 1E 00] 2018-09-19 22:32:20.831 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=2C 00 52 10] 2018-09-19 22:32:20.833 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 22:32:20.835 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 22:32:20.835 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:20.838 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE] 2018-09-19 22:32:20.837 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.840 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=2D 00 52 17] 2018-09-19 22:32:20.950 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=2D 80 52 00 01 00] 2018-09-19 22:32:20.952 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=2D 00 52 17] 2018-09-19 22:32:20.954 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:20.956 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:20.956 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:20.959 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:20.959 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS] 2018-09-19 22:32:20.961 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=2E 00 52 2D] 2018-09-19 22:32:21.071 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=2E 80 52 00 01 00] 2018-09-19 22:32:21.073 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=2E 00 52 2D] 2018-09-19 22:32:21.076 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:21.078 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:21.078 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:21.081 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.084 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE] 2018-09-19 22:32:21.086 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=2F 00 52 1E] 2018-09-19 22:32:21.196 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=2F 80 52 00 04 00] 2018-09-19 22:32:21.199 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=2F 00 52 1E] 2018-09-19 22:32:21.201 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4] 2018-09-19 22:32:21.203 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:21.204 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4] 2018-09-19 22:32:21.207 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.209 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS] 2018-09-19 22:32:21.212 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=30 00 52 2A] 2018-09-19 22:32:21.321 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=30 80 52 00 01 00] 2018-09-19 22:32:21.325 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=30 00 52 2A] 2018-09-19 22:32:21.327 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:21.329 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:21.329 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 22:32:21.332 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.334 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN] 2018-09-19 22:32:21.337 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=31 00 52 11] 2018-09-19 22:32:21.447 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=31 80 52 00 10 00] 2018-09-19 22:32:21.449 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=31 00 52 11] 2018-09-19 22:32:21.451 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:21.453 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:21.453 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:21.455 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.458 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT] 2018-09-19 22:32:21.461 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=32 00 52 03] 2018-09-19 22:32:21.570 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=32 80 52 00 0A 00] 2018-09-19 22:32:21.572 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=32 00 52 03] 2018-09-19 22:32:21.575 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 22:32:21.577 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:21.577 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 22:32:21.580 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.582 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE] 2018-09-19 22:32:21.586 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=33 00 52 2B] 2018-09-19 22:32:21.698 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=33 80 52 00 0F 00] 2018-09-19 22:32:21.699 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=33 00 52 2B] 2018-09-19 22:32:21.701 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 22:32:21.702 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:21.702 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 22:32:21.704 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.705 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE] 2018-09-19 22:32:21.708 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=34 00 52 02] 2018-09-19 22:32:21.819 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=34 80 52 00 10 00] 2018-09-19 22:32:21.820 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=34 00 52 02] 2018-09-19 22:32:21.821 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:21.822 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:21.822 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 22:32:21.824 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.825 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT] 2018-09-19 22:32:21.827 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=35 00 52 01] 2018-09-19 22:32:21.939 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=35 80 52 00 FF 00] 2018-09-19 22:32:21.941 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=35 00 52 01] 2018-09-19 22:32:21.942 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255] 2018-09-19 22:32:21.943 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:21.943 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255] 2018-09-19 22:32:21.945 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15 2018-09-19 22:32:21.946 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 16 2018-09-19 22:32:21.947 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 1 2018-09-19 22:32:21.948 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8 2018-09-19 22:32:21.950 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 2 2018-09-19 22:32:21.951 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 4 2018-09-19 22:32:21.952 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 255 2018-09-19 22:32:21.953 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5 2018-09-19 22:32:21.955 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 2018-09-19 22:32:21.956 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10 2018-09-19 22:32:21.957 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 2018-09-19 22:32:21.959 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30 2018-09-19 22:32:21.960 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 16 2018-09-19 22:32:21.961 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16 2018-09-19 22:32:21.962 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1 2018-09-19 22:32:21.964 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 1 2018-09-19 22:32:21.966 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:21.967 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 22:32:21.969 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=36 00 55 00 01] 2018-09-19 22:32:22.076 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=36 80 55 00] 2018-09-19 22:32:22.077 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=36 00 55 00 01] 2018-09-19 22:32:22.079 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.080 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.080 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:22.082 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.083 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-19 22:32:22.085 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=37 00 55 01 12] 2018-09-19 22:32:22.197 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=37 80 55 00] 2018-09-19 22:32:22.198 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=37 00 55 01 12] 2018-09-19 22:32:22.199 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.200 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.200 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:22.202 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.202 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 22:32:22.204 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=38 00 55 04 40] 2018-09-19 22:32:22.317 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=38 80 55 00] 2018-09-19 22:32:22.319 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=38 00 55 04 40] 2018-09-19 22:32:22.320 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.321 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.323 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:22.323 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.325 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-19 22:32:22.327 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=39 00 55 05 52] 2018-09-19 22:32:22.439 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=39 80 55 00] 2018-09-19 22:32:22.441 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=39 00 55 05 52] 2018-09-19 22:32:22.444 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.446 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:22.446 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:22.449 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.452 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY] 2018-09-19 22:32:22.455 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=3A 00 56 00] 2018-09-19 22:32:22.566 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=3A 80 56 00 01] 2018-09-19 22:32:22.568 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=3A 00 56 00] 2018-09-19 22:32:22.571 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 22:32:22.574 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 22:32:22.573 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:22.578 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.588 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY] 2018-09-19 22:32:22.594 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=3B 00 56 01] 2018-09-19 22:32:22.711 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=3B 80 56 00 12] 2018-09-19 22:32:22.714 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=3B 00 56 01] 2018-09-19 22:32:22.717 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-19 22:32:22.721 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:22.721 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-19 22:32:22.726 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.728 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY] 2018-09-19 22:32:22.732 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=3C 00 56 04] 2018-09-19 22:32:22.841 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=3C 80 56 00 40] 2018-09-19 22:32:22.843 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=3C 00 56 04] 2018-09-19 22:32:22.846 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 22:32:22.848 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 22:32:22.848 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 22:32:22.851 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.853 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY] 2018-09-19 22:32:22.856 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=3D 00 56 05] 2018-09-19 22:32:22.965 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=3D 80 56 00 52] 2018-09-19 22:32:22.968 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=3D 00 56 05] 2018-09-19 22:32:22.970 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-19 22:32:22.973 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-19 22:32:22.973 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 22:32:22.975 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_GENERATE_NEW_TC_LINK_KEY 2018-09-19 22:32:22.979 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS 2018-09-19 22:32:22.981 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK 2018-09-19 22:32:22.985 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS 2018-09-19 22:32:22.989 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:22.991 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetEui64Request [] 2018-09-19 22:32:22.994 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=3E 00 26] 2018-09-19 22:32:23.104 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=3E 80 26 C1 C4 D1 0D 00 6F 0D 00] 2018-09-19 22:32:23.106 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=3E 00 26] 2018-09-19 22:32:23.111 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetEui64Response [eui64=000D6F000DD1C4C1] 2018-09-19 22:32:23.113 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 22:32:23.113 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember local IEEE Address is 000D6F000DD1C4C1 2018-09-19 22:32:23.116 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-19 22:32:23.116 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:23.117 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=3F 00 28] 2018-09-19 22:32:23.230 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=3F 80 28 93 CD 00 00 00 00 00 00 00 00 FF FF FF 00 00 FF FF 00 00 00 00 00] 2018-09-19 22:32:23.231 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=3F 00 28] 2018-09-19 22:32:23.238 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_NOT_JOINED, nodeType=UNKNOWN, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=65535, radioTxPower=255, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=65535, nwkUpdateId=0, channels=00000000]] 2018-09-19 22:32:23.240 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:23.240 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 22:32:23.243 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspAddEndpointRequest [endpoint=1, profileId=260, deviceId=0, appFlags=0, inputClusterList=00, outputClusterList=00] 2018-09-19 22:32:23.244 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=40 00 02 01 04 01 00 00 00 01 01 00 00 00 00] 2018-09-19 22:32:23.354 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=40 80 02 00] 2018-09-19 22:32:23.356 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=40 00 02 01 04 01 00 00 00 01 01 00 00 00 00] 2018-09-19 22:32:23.358 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspAddEndpointResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:23.360 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspAddEndpointResponse [status=EZSP_SUCCESS] 2018-09-19 22:32:23.361 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:23.361 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 22:32:23.363 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspNetworkInitRequest [] 2018-09-19 22:32:23.364 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=41 00 17] 2018-09-19 22:32:23.475 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=41 80 17 93] 2018-09-19 22:32:23.476 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=41 00 17] 2018-09-19 22:32:23.478 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkInitResponse [status=EMBER_NOT_JOINED] 2018-09-19 22:32:23.480 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspNetworkInitResponse [status=EMBER_NOT_JOINED] 2018-09-19 22:32:23.481 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 22:32:23.481 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:23.482 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-19 22:32:23.484 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=42 00 28] 2018-09-19 22:32:23.598 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=42 80 28 93 CD 00 00 00 00 00 00 00 00 FF FF FF 00 00 FF FF 00 00 00 00 00] 2018-09-19 22:32:23.599 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=42 00 28] 2018-09-19 22:32:23.601 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_NOT_JOINED, nodeType=UNKNOWN, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=65535, radioTxPower=255, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=65535, nwkUpdateId=0, channels=00000000]] 2018-09-19 22:32:23.602 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 22:32:23.602 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 22:32:23.604 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetCurrentSecurityStateRequest [] 2018-09-19 22:32:23.605 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=43 00 69] 2018-09-19 22:32:23.718 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=43 80 69 93 00 00 00 00 00 00 00 00 FF FF] 2018-09-19 22:32:23.720 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=43 00 69] 2018-09-19 22:32:23.725 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-19 22:32:23.727 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-19 22:32:23.729 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 22:32:23.730 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - Error during retrieval of security parameters: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-19 22:32:23.733 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize done: Initialised true 2018-09-19 22:32:23.735 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start. 2018-09-19 22:32:23.739 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: File does not exist 2018-09-19 22:32:23.742 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000DD1C4C1: Adding local coordinator node to network 2018-09-19 22:32:23.786 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000DD1C4C1: Node 0 added to the network 2018-09-19 22:32:23.789 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task starting