2018-09-20 00:44:14.842 [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-20 00:44:14.869 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STARTING - org.openhab.binding.zigbee 2018-09-20 00:44:14.872 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STARTED - org.openhab.binding.zigbee 2018-09-20 00:44:14.912 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService 2018-09-20 00:44:14.968 [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-20 00:44:14.972 [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-20 00:44:14.997 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee 2018-09-20 00:44:15.007 [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-20 00:44:15.012 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531 2018-09-20 00:44:15.016 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531 2018-09-20 00:44:15.024 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531 2018-09-20 00:44:15.039 [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-20 00:44:15.044 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember 2018-09-20 00:44:15.048 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember 2018-09-20 00:44:15.061 [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-20 00:44:15.064 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember 2018-09-20 00:44:15.078 [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-20 00:44:15.083 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis 2018-09-20 00:44:15.087 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis 2018-09-20 00:44:15.096 [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-20 00:44:15.100 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis 2018-09-20 00:44:15.111 [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-20 00:44:15.117 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee 2018-09-20 00:44:15.120 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee 2018-09-20 00:44:15.128 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee 2018-09-20 00:44:17.695 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder 2018-09-20 00:44:17.754 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel 2018-09-20 00:44:18.114 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.178.51:8080 2018-09-20 00:44:18.117 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.178.51:8443 2018-09-20 00:44:30.819 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007 2018-09-20 00:44:33.207 [DEBUG] [com.zsmartsystems.zigbee ] - BundleEvent STARTING - com.zsmartsystems.zigbee 2018-09-20 00:44:33.221 [DEBUG] [com.zsmartsystems.zigbee ] - BundleEvent STARTED - com.zsmartsystems.zigbee 2018-09-20 00:44:33.242 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.cc2531 2018-09-20 00:44:33.249 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.cc2531 2018-09-20 00:44:33.260 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.ember 2018-09-20 00:44:33.271 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.ember 2018-09-20 00:44:33.284 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.telegesis 2018-09-20 00:44:33.289 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.telegesis 2018-09-20 00:44:33.295 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.xbee 2018-09-20 00:44:33.300 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.xbee 2018-09-20 00:44:33.522 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:coordinator_ember:012420A5' to inbox. 2018-09-20 00:44:33.973 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app 2018-09-20 00:44:34.078 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui 2018-09-20 00:46:05.995 [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-20 00:46:06.012 [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-20 00:46:06.065 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler. 2018-09-20 00:46:06.069 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:012420A5]. 2018-09-20 00:46:06.073 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel -1 2018-09-20 00:46:06.076 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 0 2018-09-20 00:46:06.079 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 0000000000000000 2018-09-20 00:46:06.084 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2018-09-20 00:46:06.086 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key null 2018-09-20 00:46:06.090 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network 2018-09-20 00:46:06.093 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee PAN ID [CA9B]. 2018-09-20 00:46:06.112 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee extended PAN ID [FDE2E5995B0A2452]. 2018-09-20 00:46:06.134 [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-20 00:46:06.140 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key initialised E5B37FE2E9190750F6F8281643AA84A2 2018-09-20 00:46:06.143 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array E5B37FE2E9190750F6F8281643AA84A2 2018-09-20 00:46:06.146 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 2018-09-20 00:46:06.148 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String has invalid format. Revert to default key. 2018-09-20 00:46:06.151 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039 2018-09-20 00:46:06.250 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:ca9b, EPAN:FDE2E5995B0A2452, Channel:-1 2018-09-20 00:46:06.262 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start 2018-09-20 00:46:07.272 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting 2018-09-20 00:46:07.291 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator 2018-09-20 00:46:07.236 [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-20 00:46:07.393 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280 2018-09-20 00:46:07.423 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting 2018-09-20 00:46:07.554 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE 2018-09-20 00:46:07.557 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25 2018-09-20 00:46:07.558 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE 2018-09-20 00:46:07.562 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize with protocol ASH2. 2018-09-20 00:46:07.568 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF. 2018-09-20 00:46:07.589 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized. 2018-09-20 00:46:07.658 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - AshFrameHandler thread started 2018-09-20 00:46:07.664 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst [] 2018-09-20 00:46:07.758 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:08.825 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameRstAck [version=2, resetCode=11, Reset: Software] 2018-09-20 00:46:08.828 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Connected 2018-09-20 00:46:08.831 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspVersionRequest [desiredProtocolVersion=4] 2018-09-20 00:46:08.835 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=01 00 00 04] 2018-09-20 00:46:08.948 [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-20 00:46:08.953 [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-20 00:46:08.965 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-20 00:46:08.973 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-20 00:46:08.977 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-20 00:46:08.977 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:08.985 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:08.989 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE] 2018-09-20 00:46:08.993 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=02 00 52 1A] 2018-09-20 00:46:09.103 [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-20 00:46:09.107 [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-20 00:46:09.122 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:09.124 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:09.124 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:09.128 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:09.131 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL] 2018-09-20 00:46:09.134 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=03 00 52 0D] 2018-09-20 00:46:09.245 [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-20 00:46:09.249 [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-20 00:46:09.252 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-20 00:46:09.255 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:09.256 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-20 00:46:09.258 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:09.260 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE] 2018-09-20 00:46:09.263 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=04 00 52 05] 2018-09-20 00:46:09.374 [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-20 00:46:09.376 [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-20 00:46:09.378 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-20 00:46:09.380 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:09.381 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-20 00:46:09.385 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:09.387 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE] 2018-09-20 00:46:09.391 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=05 00 52 19] 2018-09-20 00:46:09.501 [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-20 00:46:09.504 [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-20 00:46:09.507 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:09.510 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:09.510 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:09.513 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE] 2018-09-20 00:46:09.513 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:09.516 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=06 00 52 0C] 2018-09-20 00:46:09.627 [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-20 00:46:09.630 [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-20 00:46:09.632 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:09.634 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:09.634 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:09.637 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:09.640 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT] 2018-09-20 00:46:09.643 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=07 00 52 12] 2018-09-20 00:46:09.756 [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-20 00:46:09.759 [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-20 00:46:09.761 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000] 2018-09-20 00:46:09.763 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:09.763 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000] 2018-09-20 00:46:09.766 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:09.766 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS] 2018-09-20 00:46:09.769 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=08 00 52 10] 2018-09-20 00:46:09.880 [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-20 00:46:09.883 [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-20 00:46:09.886 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-20 00:46:09.888 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:09.888 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-20 00:46:09.891 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:09.891 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE] 2018-09-20 00:46:09.894 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=09 00 52 17] 2018-09-20 00:46:10.006 [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-20 00:46:10.010 [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-20 00:46:10.014 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:10.017 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:10.017 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:10.021 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.022 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS] 2018-09-20 00:46:10.026 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=0A 00 52 2D] 2018-09-20 00:46:10.137 [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-20 00:46:10.141 [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-20 00:46:10.143 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:10.146 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:10.149 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:10.153 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.156 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE] 2018-09-20 00:46:10.161 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=0B 00 52 1E] 2018-09-20 00:46:10.273 [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-20 00:46:10.277 [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-20 00:46:10.280 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6] 2018-09-20 00:46:10.284 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6] 2018-09-20 00:46:10.286 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.286 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:10.289 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS] 2018-09-20 00:46:10.293 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=0C 00 52 2A] 2018-09-20 00:46:10.404 [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-20 00:46:10.407 [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-20 00:46:10.411 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:10.413 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:10.413 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-20 00:46:10.416 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.416 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN] 2018-09-20 00:46:10.420 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=0D 00 52 11] 2018-09-20 00:46:10.532 [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-20 00:46:10.535 [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-20 00:46:10.538 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32] 2018-09-20 00:46:10.540 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:10.540 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32] 2018-09-20 00:46:10.543 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.546 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT] 2018-09-20 00:46:10.549 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=0E 00 52 03] 2018-09-20 00:46:10.660 [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-20 00:46:10.663 [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-20 00:46:10.665 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-20 00:46:10.668 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:10.668 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-20 00:46:10.670 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.671 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE] 2018-09-20 00:46:10.674 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=0F 00 52 2B] 2018-09-20 00:46:10.785 [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-20 00:46:10.788 [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-20 00:46:10.792 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-20 00:46:10.795 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:10.795 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-20 00:46:10.803 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.807 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE] 2018-09-20 00:46:10.812 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=10 00 52 02] 2018-09-20 00:46:10.924 [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-20 00:46:10.927 [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-20 00:46:10.930 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:10.932 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:10.935 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:10.937 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:10.939 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT] 2018-09-20 00:46:10.942 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=11 00 52 01] 2018-09-20 00:46:11.052 [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-20 00:46:11.055 [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-20 00:46:11.059 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75] 2018-09-20 00:46:11.062 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:11.062 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75] 2018-09-20 00:46:11.065 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 0 2018-09-20 00:46:11.068 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 0 2018-09-20 00:46:11.070 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 75 2018-09-20 00:46:11.073 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 0 2018-09-20 00:46:11.075 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8 2018-09-20 00:46:11.077 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 3000 2018-09-20 00:46:11.080 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 0 2018-09-20 00:46:11.082 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16 2018-09-20 00:46:11.084 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 6 2018-09-20 00:46:11.087 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 32 2018-09-20 00:46:11.089 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5 2018-09-20 00:46:11.091 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10 2018-09-20 00:46:11.094 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 0 2018-09-20 00:46:11.096 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30 2018-09-20 00:46:11.098 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15 2018-09-20 00:46:11.101 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1 2018-09-20 00:46:11.104 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:11.106 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY] 2018-09-20 00:46:11.109 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=12 00 56 00] 2018-09-20 00:46:11.220 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=12 80 56 00 01] 2018-09-20 00:46:11.222 [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-20 00:46:11.225 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-20 00:46:11.228 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:11.228 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-20 00:46:11.230 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:11.231 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY] 2018-09-20 00:46:11.234 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=13 00 56 01] 2018-09-20 00:46:11.343 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=13 80 56 00 10] 2018-09-20 00:46:11.346 [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-20 00:46:11.349 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION] 2018-09-20 00:46:11.351 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:11.352 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION] 2018-09-20 00:46:11.355 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:11.357 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY] 2018-09-20 00:46:11.360 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=14 00 56 04] 2018-09-20 00:46:11.471 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=14 80 56 00 40] 2018-09-20 00:46:11.473 [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-20 00:46:11.476 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-20 00:46:11.478 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-20 00:46:11.478 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:11.481 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:11.483 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY] 2018-09-20 00:46:11.485 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=15 00 56 05] 2018-09-20 00:46:11.595 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=15 80 56 00 50] 2018-09-20 00:46:11.598 [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-20 00:46:11.600 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS] 2018-09-20 00:46:11.603 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS] 2018-09-20 00:46:11.606 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:11.605 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS 2018-09-20 00:46:11.608 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS 2018-09-20 00:46:11.610 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_DISALLOW_BINDING_MODIFICATION 2018-09-20 00:46:11.613 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK 2018-09-20 00:46:11.617 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16] 2018-09-20 00:46:11.619 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:11.621 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16] 2018-09-20 00:46:11.625 [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-20 00:46:11.741 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=16 80 53 00] 2018-09-20 00:46:11.744 [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-20 00:46:11.748 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:11.750 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:11.750 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:11.752 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5] 2018-09-20 00:46:11.754 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:11.758 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5] 2018-09-20 00:46:11.762 [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-20 00:46:11.872 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=17 80 53 00] 2018-09-20 00:46:11.876 [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-20 00:46:11.878 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:11.881 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:11.881 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:11.883 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8] 2018-09-20 00:46:11.886 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:11.889 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8] 2018-09-20 00:46:11.893 [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-20 00:46:12.008 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=18 80 53 00] 2018-09-20 00:46:12.011 [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-20 00:46:12.014 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.015 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:12.016 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.018 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2] 2018-09-20 00:46:12.020 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:12.023 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2] 2018-09-20 00:46:12.026 [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-20 00:46:12.141 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=19 80 53 00] 2018-09-20 00:46:12.144 [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-20 00:46:12.146 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.148 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:12.149 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.151 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2] 2018-09-20 00:46:12.154 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:12.156 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2] 2018-09-20 00:46:12.161 [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-20 00:46:12.270 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=1A 80 53 00] 2018-09-20 00:46:12.274 [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-20 00:46:12.278 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.281 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:12.281 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.284 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680] 2018-09-20 00:46:12.288 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:12.291 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680] 2018-09-20 00:46:12.295 [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-20 00:46:12.406 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=1B 80 53 00] 2018-09-20 00:46:12.410 [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-20 00:46:12.481 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.484 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:12.486 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.489 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30] 2018-09-20 00:46:12.491 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:12.493 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30] 2018-09-20 00:46:12.496 [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-20 00:46:12.606 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=1C 80 53 00] 2018-09-20 00:46:12.608 [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-20 00:46:12.610 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.613 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.615 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=1] 2018-09-20 00:46:12.617 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:12.618 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:12.621 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=1] 2018-09-20 00:46:12.625 [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-20 00:46:12.737 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=1D 80 53 00] 2018-09-20 00:46:12.741 [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-20 00:46:12.744 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.748 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.751 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1] 2018-09-20 00:46:12.751 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:12.755 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:12.756 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1] 2018-09-20 00:46:12.759 [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-20 00:46:12.874 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=1E 80 53 00] 2018-09-20 00:46:12.878 [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-20 00:46:12.880 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.883 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:12.885 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4] 2018-09-20 00:46:12.886 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:12.888 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:12.893 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4] 2018-09-20 00:46:12.897 [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-20 00:46:13.013 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=1F 80 53 00] 2018-09-20 00:46:13.016 [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-20 00:46:13.019 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.021 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.023 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1] 2018-09-20 00:46:13.022 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:13.026 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.027 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1] 2018-09-20 00:46:13.029 [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-20 00:46:13.139 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=20 80 53 00] 2018-09-20 00:46:13.141 [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-20 00:46:13.145 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.147 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.149 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16] 2018-09-20 00:46:13.148 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:13.151 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.152 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16] 2018-09-20 00:46:13.155 [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-20 00:46:13.270 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=21 80 53 00] 2018-09-20 00:46:13.273 [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-20 00:46:13.276 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.279 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.281 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10] 2018-09-20 00:46:13.280 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:13.283 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.284 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10] 2018-09-20 00:46:13.287 [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-20 00:46:13.401 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=22 80 53 00] 2018-09-20 00:46:13.404 [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-20 00:46:13.406 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.409 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.411 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15] 2018-09-20 00:46:13.410 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:13.413 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.415 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15] 2018-09-20 00:46:13.419 [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-20 00:46:13.529 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=23 80 53 36] 2018-09-20 00:46:13.533 [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-20 00:46:13.535 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE] 2018-09-20 00:46:13.538 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE] 2018-09-20 00:46:13.540 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16] 2018-09-20 00:46:13.541 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:13.543 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.545 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16] 2018-09-20 00:46:13.548 [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-20 00:46:13.663 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=24 80 53 00] 2018-09-20 00:46:13.665 [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-20 00:46:13.668 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.670 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.670 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:13.672 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255] 2018-09-20 00:46:13.675 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.677 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255] 2018-09-20 00:46:13.680 [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-20 00:46:13.794 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=25 80 53 00] 2018-09-20 00:46:13.798 [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-20 00:46:13.802 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.805 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:13.805 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:13.808 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.810 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE] 2018-09-20 00:46:13.813 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=26 00 52 1A] 2018-09-20 00:46:13.924 [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-20 00:46:13.926 [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-20 00:46:13.928 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:13.930 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:13.931 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:13.934 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:13.936 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL] 2018-09-20 00:46:13.939 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=27 00 52 0D] 2018-09-20 00:46:14.049 [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-20 00:46:14.052 [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-20 00:46:14.054 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-20 00:46:14.057 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:14.058 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-20 00:46:14.059 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.059 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE] 2018-09-20 00:46:14.062 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=28 00 52 05] 2018-09-20 00:46:14.173 [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-20 00:46:14.176 [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-20 00:46:14.178 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-20 00:46:14.180 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-20 00:46:14.180 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:14.183 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.183 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE] 2018-09-20 00:46:14.186 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=29 00 52 19] 2018-09-20 00:46:14.312 [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-20 00:46:14.315 [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-20 00:46:14.318 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-20 00:46:14.320 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:14.320 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-20 00:46:14.322 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE] 2018-09-20 00:46:14.322 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.325 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=2A 00 52 0C] 2018-09-20 00:46:14.436 [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-20 00:46:14.441 [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-20 00:46:14.443 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-20 00:46:14.446 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:14.446 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-20 00:46:14.450 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.452 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT] 2018-09-20 00:46:14.454 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=2B 00 52 12] 2018-09-20 00:46:14.565 [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-20 00:46:14.568 [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-20 00:46:14.571 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680] 2018-09-20 00:46:14.574 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:14.575 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680] 2018-09-20 00:46:14.581 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.583 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS] 2018-09-20 00:46:14.586 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=2C 00 52 10] 2018-09-20 00:46:14.698 [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-20 00:46:14.701 [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-20 00:46:14.704 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-20 00:46:14.705 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:14.706 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-20 00:46:14.710 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.712 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE] 2018-09-20 00:46:14.714 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=2D 00 52 17] 2018-09-20 00:46:14.825 [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-20 00:46:14.828 [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-20 00:46:14.830 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:14.833 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:14.833 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:14.835 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.838 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS] 2018-09-20 00:46:14.840 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=2E 00 52 2D] 2018-09-20 00:46:14.951 [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-20 00:46:14.954 [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-20 00:46:14.956 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:14.958 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:14.958 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:14.960 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE] 2018-09-20 00:46:14.960 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:14.962 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=2F 00 52 1E] 2018-09-20 00:46:15.076 [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-20 00:46:15.079 [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-20 00:46:15.081 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4] 2018-09-20 00:46:15.083 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:15.083 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4] 2018-09-20 00:46:15.085 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS] 2018-09-20 00:46:15.085 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.088 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=30 00 52 2A] 2018-09-20 00:46:15.198 [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-20 00:46:15.201 [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-20 00:46:15.203 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:15.205 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:15.205 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-20 00:46:15.207 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN] 2018-09-20 00:46:15.207 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.209 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=31 00 52 11] 2018-09-20 00:46:15.320 [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-20 00:46:15.322 [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-20 00:46:15.334 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:15.337 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:15.337 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:15.340 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.342 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT] 2018-09-20 00:46:15.345 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=32 00 52 03] 2018-09-20 00:46:15.454 [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-20 00:46:15.457 [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-20 00:46:15.459 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-20 00:46:15.461 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:15.462 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-20 00:46:15.465 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.468 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE] 2018-09-20 00:46:15.472 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=33 00 52 2B] 2018-09-20 00:46:15.583 [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-20 00:46:15.585 [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-20 00:46:15.586 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-20 00:46:15.587 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:15.587 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-20 00:46:15.589 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.590 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE] 2018-09-20 00:46:15.592 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=34 00 52 02] 2018-09-20 00:46:15.704 [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-20 00:46:15.705 [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-20 00:46:15.707 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:15.708 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:15.708 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-20 00:46:15.709 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.711 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT] 2018-09-20 00:46:15.713 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=35 00 52 01] 2018-09-20 00:46:15.825 [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-20 00:46:15.826 [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-20 00:46:15.828 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255] 2018-09-20 00:46:15.829 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:15.829 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255] 2018-09-20 00:46:15.830 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 2 2018-09-20 00:46:15.832 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 1 2018-09-20 00:46:15.834 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 255 2018-09-20 00:46:15.835 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 16 2018-09-20 00:46:15.837 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8 2018-09-20 00:46:15.838 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 2018-09-20 00:46:15.840 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 1 2018-09-20 00:46:15.841 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16 2018-09-20 00:46:15.843 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 4 2018-09-20 00:46:15.844 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 16 2018-09-20 00:46:15.846 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5 2018-09-20 00:46:15.847 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10 2018-09-20 00:46:15.849 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 2018-09-20 00:46:15.851 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30 2018-09-20 00:46:15.853 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15 2018-09-20 00:46:15.854 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1 2018-09-20 00:46:15.856 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.858 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-20 00:46:15.860 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=36 00 55 00 01] 2018-09-20 00:46:15.967 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=36 80 55 00] 2018-09-20 00:46:15.969 [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-20 00:46:15.971 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:15.972 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:15.972 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:15.974 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:15.976 [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-20 00:46:15.978 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=37 00 55 01 12] 2018-09-20 00:46:16.088 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=37 80 55 00] 2018-09-20 00:46:16.090 [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-20 00:46:16.091 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:16.092 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:16.092 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:16.094 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.096 [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-20 00:46:16.098 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=38 00 55 04 40] 2018-09-20 00:46:16.210 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=38 80 55 00] 2018-09-20 00:46:16.211 [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-20 00:46:16.213 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:16.214 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:16.214 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:16.216 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.217 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-20 00:46:16.219 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=39 00 55 05 52] 2018-09-20 00:46:16.332 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=39 80 55 00] 2018-09-20 00:46:16.334 [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-20 00:46:16.336 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:16.338 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:16.339 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:16.342 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.344 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY] 2018-09-20 00:46:16.348 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=3A 00 56 00] 2018-09-20 00:46:16.458 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=3A 80 56 00 01] 2018-09-20 00:46:16.461 [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-20 00:46:16.466 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-20 00:46:16.469 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:16.469 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-20 00:46:16.471 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.472 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY] 2018-09-20 00:46:16.475 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=3B 00 56 01] 2018-09-20 00:46:16.585 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=3B 80 56 00 12] 2018-09-20 00:46:16.588 [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-20 00:46:16.590 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-20 00:46:16.593 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:16.593 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-20 00:46:16.595 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.595 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY] 2018-09-20 00:46:16.597 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=3C 00 56 04] 2018-09-20 00:46:16.707 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=3C 80 56 00 40] 2018-09-20 00:46:16.711 [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-20 00:46:16.714 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-20 00:46:16.717 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:16.717 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-20 00:46:16.721 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.721 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY] 2018-09-20 00:46:16.723 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=3D 00 56 05] 2018-09-20 00:46:16.833 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=3D 80 56 00 52] 2018-09-20 00:46:16.835 [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-20 00:46:16.838 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-20 00:46:16.841 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:16.841 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-20 00:46:16.843 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS 2018-09-20 00:46:16.845 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_GENERATE_NEW_TC_LINK_KEY 2018-09-20 00:46:16.847 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS 2018-09-20 00:46:16.849 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK 2018-09-20 00:46:16.853 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.855 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetEui64Request [] 2018-09-20 00:46:16.857 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=3E 00 26] 2018-09-20 00:46:16.968 [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-20 00:46:16.970 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=3E 00 26] 2018-09-20 00:46:16.977 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetEui64Response [eui64=000D6F000DD1C4C1] 2018-09-20 00:46:16.981 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember local IEEE Address is 000D6F000DD1C4C1 2018-09-20 00:46:16.983 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:16.980 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:16.987 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-20 00:46:16.990 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=3F 00 28] 2018-09-20 00:46:17.104 [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-20 00:46:17.105 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=3F 00 28] 2018-09-20 00:46:17.112 [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-20 00:46:17.115 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:17.117 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspAddEndpointRequest [endpoint=1, profileId=260, deviceId=0, appFlags=0, inputClusterList=00, outputClusterList=00] 2018-09-20 00:46:17.119 [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-20 00:46:17.121 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:17.229 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=7, notRdy=false] 2018-09-20 00:46:17.230 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Retry Sent Queue Length 1 2018-09-20 00:46:17.231 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=true, data=40 00 02 01 04 01 00 00 00 01 01 00 00 00 00] 2018-09-20 00:46:17.349 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:17.351 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=true, data=40 00 02 01 04 01 00 00 00 01 01 00 00 00 00] 2018-09-20 00:46:17.353 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=40 80 02 00] 2018-09-20 00:46:17.354 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspAddEndpointResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:17.356 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspAddEndpointResponse [status=EZSP_SUCCESS] 2018-09-20 00:46:17.358 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:17.358 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:17.360 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspNetworkInitRequest [] 2018-09-20 00:46:17.362 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=41 00 17] 2018-09-20 00:46:17.470 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=41 80 17 93] 2018-09-20 00:46:17.472 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=41 00 17] 2018-09-20 00:46:17.474 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkInitResponse [status=EMBER_NOT_JOINED] 2018-09-20 00:46:17.476 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspNetworkInitResponse [status=EMBER_NOT_JOINED] 2018-09-20 00:46:17.476 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:17.477 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:17.477 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-20 00:46:17.479 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=42 00 28] 2018-09-20 00:46:17.593 [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-20 00:46:17.594 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=42 00 28] 2018-09-20 00:46:17.596 [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-20 00:46:17.597 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:17.598 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:17.599 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetCurrentSecurityStateRequest [] 2018-09-20 00:46:17.601 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=43 00 69] 2018-09-20 00:46:17.713 [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-20 00:46:17.715 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=43 00 69] 2018-09-20 00:46:17.721 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-20 00:46:17.722 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:17.722 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-20 00:46:17.724 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - Error during retrieval of security parameters: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-20 00:46:17.725 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize done: Initialised true 2018-09-20 00:46:17.727 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start. 2018-09-20 00:46:17.730 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: File does not exist 2018-09-20 00:46:17.732 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000DD1C4C1: Adding local coordinator node to network 2018-09-20 00:46:17.757 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000DD1C4C1: Node 0 added to the network 2018-09-20 00:46:17.759 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task starting 2018-09-20 00:46:17.763 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key initialise 5A6967426565416C6C69616E63653039 2018-09-20 00:46:17.765 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key initialise E5B37FE2E9190750F6F8281643AA84A2 2018-09-20 00:46:17.769 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle startup. 2018-09-20 00:46:17.772 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:17.774 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspNetworkStateRequest [] 2018-09-20 00:46:17.776 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=44 00 18] 2018-09-20 00:46:17.884 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=44 80 18 00] 2018-09-20 00:46:17.887 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=44 00 18] 2018-09-20 00:46:17.894 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkStateResponse [status=EMBER_NO_NETWORK] 2018-09-20 00:46:17.895 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:17.895 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspNetworkStateResponse [status=EMBER_NO_NETWORK] 2018-09-20 00:46:17.898 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP networkStateResponse EMBER_NO_NETWORK 2018-09-20 00:46:17.915 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Reinitialising Ember NCP network. 2018-09-20 00:46:17.918 [DEBUG] [.internal.EmberNetworkInitialisation] - Initialising Ember network with configuration EmberNetworkParameters [extendedPanId=FDE2E5995B0A2452, panId=51867, radioTxPower=255, radioChannel=-1, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=65535, nwkUpdateId=0, channels=00000000] 2018-09-20 00:46:17.920 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:17.922 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspNetworkStateRequest [] 2018-09-20 00:46:17.925 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=45 00 18] 2018-09-20 00:46:18.033 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=45 80 18 00] 2018-09-20 00:46:18.035 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=45 00 18] 2018-09-20 00:46:18.038 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkStateResponse [status=EMBER_NO_NETWORK] 2018-09-20 00:46:18.039 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:18.039 [DEBUG] [.internal.EmberNetworkInitialisation] - EzspNetworkStateResponse [status=EMBER_NO_NETWORK] 2018-09-20 00:46:18.042 [DEBUG] [.internal.EmberNetworkInitialisation] - EZSP networkStateResponse EMBER_NO_NETWORK 2018-09-20 00:46:18.050 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:18.052 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspStartScanRequest [scanType=EZSP_ENERGY_SCAN, channelMask=134215680, duration=1] 2018-09-20 00:46:18.055 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=46 00 1A 00 00 F8 FF 07 01] 2018-09-20 00:46:18.474 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=46 80 1A 00] 2018-09-20 00:46:18.477 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=46 00 1A 00 00 F8 FF 07 01] 2018-09-20 00:46:18.480 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspStartScanResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:18.482 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspStartScanResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:18.484 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspStartScanResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:18.486 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:18.493 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=46 90 48 0B CE] 2018-09-20 00:46:18.498 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=11, maxRssiValue=-50] 2018-09-20 00:46:18.502 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=11, maxRssiValue=-50] 2018-09-20 00:46:18.506 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=11, maxRssiValue=-50] 2018-09-20 00:46:18.509 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:18.627 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=46 90 48 0C D2] 2018-09-20 00:46:18.630 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=12, maxRssiValue=-46] 2018-09-20 00:46:18.634 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=12, maxRssiValue=-46] 2018-09-20 00:46:18.637 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=12, maxRssiValue=-46] 2018-09-20 00:46:18.641 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:18.645 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=46 90 48 0D C6] 2018-09-20 00:46:18.647 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=13, maxRssiValue=-58] 2018-09-20 00:46:18.649 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=13, maxRssiValue=-58] 2018-09-20 00:46:18.651 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=13, maxRssiValue=-58] 2018-09-20 00:46:18.652 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:18.780 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=46 90 48 0E C7] 2018-09-20 00:46:18.782 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=14, maxRssiValue=-57] 2018-09-20 00:46:18.784 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=14, maxRssiValue=-57] 2018-09-20 00:46:18.786 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=14, maxRssiValue=-57] 2018-09-20 00:46:18.788 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:18.791 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=46 90 48 0F B8] 2018-09-20 00:46:18.793 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=15, maxRssiValue=-72] 2018-09-20 00:46:18.796 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=15, maxRssiValue=-72] 2018-09-20 00:46:18.799 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=15, maxRssiValue=-72] 2018-09-20 00:46:18.801 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:18.933 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=46 90 48 10 AF] 2018-09-20 00:46:18.935 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=16, maxRssiValue=-81] 2018-09-20 00:46:18.937 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=16, maxRssiValue=-81] 2018-09-20 00:46:18.940 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=16, maxRssiValue=-81] 2018-09-20 00:46:18.941 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:18.945 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=46 90 48 11 BD] 2018-09-20 00:46:18.947 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=17, maxRssiValue=-67] 2018-09-20 00:46:18.950 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=17, maxRssiValue=-67] 2018-09-20 00:46:18.952 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=17, maxRssiValue=-67] 2018-09-20 00:46:18.954 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:19.085 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=46 90 48 12 D1] 2018-09-20 00:46:19.088 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=18, maxRssiValue=-47] 2018-09-20 00:46:19.092 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=18, maxRssiValue=-47] 2018-09-20 00:46:19.094 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=18, maxRssiValue=-47] 2018-09-20 00:46:19.097 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:19.101 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=46 90 48 13 D2] 2018-09-20 00:46:19.104 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=19, maxRssiValue=-46] 2018-09-20 00:46:19.106 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=19, maxRssiValue=-46] 2018-09-20 00:46:19.109 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=19, maxRssiValue=-46] 2018-09-20 00:46:19.112 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:19.238 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=46 90 48 14 A2] 2018-09-20 00:46:19.241 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=20, maxRssiValue=-94] 2018-09-20 00:46:19.243 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=20, maxRssiValue=-94] 2018-09-20 00:46:19.244 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=20, maxRssiValue=-94] 2018-09-20 00:46:19.246 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:19.254 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=46 90 48 15 B1] 2018-09-20 00:46:19.257 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=21, maxRssiValue=-79] 2018-09-20 00:46:19.260 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=21, maxRssiValue=-79] 2018-09-20 00:46:19.262 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=21, maxRssiValue=-79] 2018-09-20 00:46:19.265 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:19.372 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=46 90 48 16 B8] 2018-09-20 00:46:19.375 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=22, maxRssiValue=-72] 2018-09-20 00:46:19.378 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=22, maxRssiValue=-72] 2018-09-20 00:46:19.380 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=22, maxRssiValue=-72] 2018-09-20 00:46:19.382 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:19.386 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=46 90 48 17 C3] 2018-09-20 00:46:19.388 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=23, maxRssiValue=-61] 2018-09-20 00:46:19.390 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=23, maxRssiValue=-61] 2018-09-20 00:46:19.392 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=23, maxRssiValue=-61] 2018-09-20 00:46:19.394 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:19.494 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=46 90 48 18 B1] 2018-09-20 00:46:19.496 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=24, maxRssiValue=-79] 2018-09-20 00:46:19.499 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=24, maxRssiValue=-79] 2018-09-20 00:46:19.500 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=24, maxRssiValue=-79] 2018-09-20 00:46:19.502 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:19.506 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=46 90 48 19 A5] 2018-09-20 00:46:19.508 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=25, maxRssiValue=-91] 2018-09-20 00:46:19.510 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=25, maxRssiValue=-91] 2018-09-20 00:46:19.512 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=25, maxRssiValue=-91] 2018-09-20 00:46:19.514 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:19.518 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=46 94 48 1A B6] 2018-09-20 00:46:19.520 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspEnergyScanResultHandler [channel=26, maxRssiValue=-74] 2018-09-20 00:46:19.522 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspEnergyScanResultHandler [channel=26, maxRssiValue=-74] 2018-09-20 00:46:19.524 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspEnergyScanResultHandler [channel=26, maxRssiValue=-74] 2018-09-20 00:46:19.526 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:19.530 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=46 90 1C 09 00] 2018-09-20 00:46:19.533 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspScanCompleteHandler [channel=9, status=EMBER_SUCCESS] 2018-09-20 00:46:19.549 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:19.549 [DEBUG] [.internal.EmberNetworkInitialisation] - EzspScanCompleteHandler [channel=9, status=EMBER_SUCCESS] 2018-09-20 00:46:19.551 [DEBUG] [.internal.EmberNetworkInitialisation] - Energy scan completed: EzspScanCompleteHandler [channel=9, status=EMBER_SUCCESS] 2018-09-20 00:46:19.554 [DEBUG] [.internal.EmberNetworkInitialisation] - Energy scan reports quietest channel is 20 2018-09-20 00:46:19.556 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:19.558 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspStartScanRequest [scanType=EZSP_ACTIVE_SCAN, channelMask=134215680, duration=1] 2018-09-20 00:46:19.562 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=47 00 1A 01 00 F8 FF 07 01] 2018-09-20 00:46:19.679 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=47 80 1A 00] 2018-09-20 00:46:19.681 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=7, reTx=false, data=47 00 1A 01 00 F8 FF 07 01] 2018-09-20 00:46:19.682 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspStartScanResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:19.684 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspStartScanResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:19.685 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspStartScanResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:19.687 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:19.690 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=47 90 1B 0B 14 75 7F D3 59 03 39 A2 3D EA 00 02 00 DA A4] 2018-09-20 00:46:19.695 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=11, panId=29972, extendedPanId={7F D3 59 03 39 A2 3D EA}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=218, lastHopRssi=-92] 2018-09-20 00:46:19.696 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=11, panId=29972, extendedPanId={7F D3 59 03 39 A2 3D EA}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=218, lastHopRssi=-92] 2018-09-20 00:46:19.699 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=11, panId=29972, extendedPanId={7F D3 59 03 39 A2 3D EA}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=218, lastHopRssi=-92] 2018-09-20 00:46:19.700 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:20.138 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=7, reTx=false, data=47 90 1B 14 06 99 CB F0 6C C6 8B 31 D1 BA 00 02 00 FF C1] 2018-09-20 00:46:20.140 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=20, panId=39174, extendedPanId={CB F0 6C C6 8B 31 D1 BA}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=255, lastHopRssi=-63] 2018-09-20 00:46:20.142 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=20, panId=39174, extendedPanId={CB F0 6C C6 8B 31 D1 BA}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=255, lastHopRssi=-63] 2018-09-20 00:46:20.143 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=20, panId=39174, extendedPanId={CB F0 6C C6 8B 31 D1 BA}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=255, lastHopRssi=-63] 2018-09-20 00:46:20.145 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:20.460 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=47 90 1B 19 A2 0A A0 FA B5 14 00 4B 12 00 00 02 00 FD C5] 2018-09-20 00:46:20.464 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=25, panId=2722, extendedPanId={A0 FA B5 14 00 4B 12 00}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=253, lastHopRssi=-59] 2018-09-20 00:46:20.466 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=25, panId=2722, extendedPanId={A0 FA B5 14 00 4B 12 00}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=253, lastHopRssi=-59] 2018-09-20 00:46:20.470 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspNetworkFoundHandler [networkFound=EmberZigbeeNetwork [channel=25, panId=2722, extendedPanId={A0 FA B5 14 00 4B 12 00}, allowingJoin=false, stackProfile=2, nwkUpdateId=0], lastHopLqi=253, lastHopRssi=-59] 2018-09-20 00:46:20.472 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:20.476 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=false, data=47 90 1C FF 00] 2018-09-20 00:46:20.478 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspScanCompleteHandler [channel=255, status=EMBER_SUCCESS] 2018-09-20 00:46:20.484 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:20.484 [DEBUG] [.internal.EmberNetworkInitialisation] - EzspScanCompleteHandler [channel=255, status=EMBER_SUCCESS] 2018-09-20 00:46:20.486 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-20 00:46:20.486 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:20.489 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=4, reTx=false, data=48 00 28] 2018-09-20 00:46:20.604 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=0, reTx=false, data=48 80 28 93 9D 00 00 00 00 00 00 00 00 FF FF FF 00 00 FF FF 00 00 00 00 00] 2018-09-20 00:46:20.607 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=4, reTx=false, data=48 00 28] 2018-09-20 00:46:20.610 [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-20 00:46:20.612 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-20 00:46:20.613 [DEBUG] [.internal.EmberNetworkInitialisation] - 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-20 00:46:20.616 [DEBUG] [.internal.EmberNetworkInitialisation] - Error during retrieval of network parameters: 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-20 00:46:20.629 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:20.634 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetInitialSecurityStateRequest [state=EmberInitialSecurityState [bitmask=[EMBER_REQUIRE_ENCRYPTED_KEY, EMBER_HAVE_PRECONFIGURED_KEY, EMBER_HAVE_NETWORK_KEY, EMBER_TRUST_CENTER_GLOBAL_LINK_KEY], preconfiguredKey=EmberKeyData [contents={5A 69 67 42 65 65 41 6C 6C 69 61 6E 63 65 30 39}], networkKey=EmberKeyData [contents={E5 B3 7F E2 E9 19 07 50 F6 F8 28 16 43 AA 84 A2}], networkKeySequenceNumber=0, preconfiguredTrustCenterEui64=0000000000000000]] 2018-09-20 00:46:20.641 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=5, reTx=false, data=49 00 68 04 0B 5A 69 67 42 65 65 41 6C 6C 69 61 6E 63 65 30 39 E5 B3 7F E2 E9 19 07 50 F6 F8 28 16 43 AA 84 A2 00 00 00 00 00 00 00 00 00] 2018-09-20 00:46:20.760 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=1, reTx=false, data=49 80 68 00] 2018-09-20 00:46:20.764 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=5, reTx=false, data=49 00 68 04 0B 5A 69 67 42 65 65 41 6C 6C 69 61 6E 63 65 30 39 E5 B3 7F E2 E9 19 07 50 F6 F8 28 16 43 AA 84 A2 00 00 00 00 00 00 00 00 00] 2018-09-20 00:46:20.772 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetInitialSecurityStateResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:20.774 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-20 00:46:20.776 [DEBUG] [.internal.EmberNetworkInitialisation] - EzspSetInitialSecurityStateResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:20.779 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:20.783 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspFormNetworkRequest [parameters=EmberNetworkParameters [extendedPanId=FDE2E5995B0A2452, panId=51867, radioTxPower=255, radioChannel=-1, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=65535, nwkUpdateId=0, channels=80000000]] 2018-09-20 00:46:20.787 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=4A 00 1E 52 24 0A 5B 99 E5 E2 FD 9B CA FF FF 00 FF FF 00 00 00 00 80] 2018-09-20 00:46:20.900 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=false, data=4A 80 1E 8A] 2018-09-20 00:46:20.903 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=6, reTx=false, data=4A 00 1E 52 24 0A 5B 99 E5 E2 FD 9B CA FF FF 00 FF FF 00 00 00 00 80] 2018-09-20 00:46:20.907 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspFormNetworkResponse [status=EMBER_PHY_INVALID_CHANNEL] 2018-09-20 00:46:20.910 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-20 00:46:20.910 [DEBUG] [.internal.EmberNetworkInitialisation] - EzspFormNetworkResponse [status=EMBER_PHY_INVALID_CHANNEL] 2018-09-20 00:46:20.912 [DEBUG] [.internal.EmberNetworkInitialisation] - Error forming network: EzspFormNetworkResponse [status=EMBER_PHY_INVALID_CHANNEL] 2018-09-20 00:46:20.914 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:20.916 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-20 00:46:20.918 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=4B 00 28] 2018-09-20 00:46:21.033 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=4B 80 28 93 8A 00 00 00 00 00 00 00 00 FF FF FF 00 00 FF FF 00 00 00 00 00] 2018-09-20 00:46:21.036 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=7, reTx=false, data=4B 00 28] 2018-09-20 00:46:21.039 [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-20 00:46:21.042 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:21.043 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-20 00:46:21.046 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspNetworkStateRequest [] 2018-09-20 00:46:21.048 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=false, data=4C 00 18] 2018-09-20 00:46:21.156 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=false, data=4C 80 18 00] 2018-09-20 00:46:21.158 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=0, reTx=false, data=4C 00 18] 2018-09-20 00:46:21.160 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkStateResponse [status=EMBER_NO_NETWORK] 2018-09-20 00:46:21.162 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspNetworkStateResponse [status=EMBER_NO_NETWORK] 2018-09-20 00:46:21.162 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-20 00:46:21.164 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP networkStateResponse EMBER_NO_NETWORK 2018-09-20 00:46:21.167 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:21.169 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetCurrentSecurityStateRequest [] 2018-09-20 00:46:21.172 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=false, data=4D 00 69] 2018-09-20 00:46:21.284 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=false, data=4D 80 69 93 00 00 00 00 00 00 00 00 FF FF] 2018-09-20 00:46:21.286 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=1, reTx=false, data=4D 00 69] 2018-09-20 00:46:21.289 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-20 00:46:21.291 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-20 00:46:21.292 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-20 00:46:21.294 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - Error during retrieval of security parameters: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-20 00:46:21.296 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Current Security State = null 2018-09-20 00:46:21.299 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:21.302 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetParentChildParametersRequest [] 2018-09-20 00:46:21.305 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=4E 00 29] 2018-09-20 00:46:21.416 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=4E 80 29 00 00 00 00 00 00 00 00 00 00 00] 2018-09-20 00:46:21.420 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=2, reTx=false, data=4E 00 29] 2018-09-20 00:46:21.423 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetParentChildParametersResponse [childCount=0, parentEui64=0000000000000000, parentNodeId=0] 2018-09-20 00:46:21.426 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-20 00:46:21.426 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Current Parent Child Information = EzspGetParentChildParametersResponse [childCount=0, parentEui64=0000000000000000, parentNodeId=0] 2018-09-20 00:46:21.430 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-20 00:46:21.433 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetRadioPowerRequest [power=255] 2018-09-20 00:46:21.436 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=4F 00 99 FF] 2018-09-20 00:46:21.546 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=false, data=4F 80 99 00] 2018-09-20 00:46:21.549 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=3, reTx=false, data=4F 00 99 FF] 2018-09-20 00:46:21.552 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetRadioPowerResponse [status=EMBER_SUCCESS] 2018-09-20 00:46:21.556 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-20 00:46:21.556 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle startup done. 2018-09-20 00:46:21.559 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise done. channel=UNKNOWN, PanId=51867 EPanId=FDE2E5995B0A2452 2018-09-20 00:46:21.572 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280 2018-09-20 00:46:21.574 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE 2018-09-20 00:47:31.776 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_ember:012420A5 2018-09-20 00:47:31.782 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee coordinator is offline - aborted scan for zigbee:coordinator_ember:012420A5 2018-09-20 00:48:20.362 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_ember:012420A5 2018-09-20 00:48:20.366 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee coordinator is offline - aborted scan for zigbee:coordinator_ember:012420A5