2018-09-19 12:01:01.417 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:coordinator_ember:012420A5' to inbox. 2018-09-19 12:01:09.634 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=361, service.bundleid=205, service.scope=singleton} - org.openhab.binding.zigbee.ember 2018-09-19 12:01:09.652 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=362, service.bundleid=205, service.scope=singleton} - org.openhab.binding.zigbee.ember 2018-09-19 12:01:09.683 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler. 2018-09-19 12:01:09.688 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:012420A5]. 2018-09-19 12:01:09.691 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel -1 2018-09-19 12:01:09.693 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 0 2018-09-19 12:01:09.694 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 0000000000000000 2018-09-19 12:01:09.696 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2018-09-19 12:01:09.697 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network 2018-09-19 12:01:09.699 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee PAN ID [DEA1]. 2018-09-19 12:01:09.708 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Created random ZigBee extended PAN ID [A4437A53369E92E3]. 2018-09-19 12:01:09.721 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key String 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2018-09-19 12:01:09.724 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialised 013136BCE4BE39BD5942D85160D74E63 2018-09-19 12:01:09.727 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key final array 013136BCE4BE39BD5942D85160D74E63 2018-09-19 12:01:09.731 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:dea1, EPAN:A4437A53369E92E3, Channel:-1 2018-09-19 12:01:09.734 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start 2018-09-19 12:01:10.095 [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:681) ~[?:?] at org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService.startScan(ZigBeeDiscoveryService.java:134) ~[?:?] at org.eclipse.smarthome.config.discovery.AbstractDiscoveryService.startScan(AbstractDiscoveryService.java:208) ~[96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.config.discovery.internal.DiscoveryServiceRegistryImpl.startScan(DiscoveryServiceRegistryImpl.java:427) [96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.config.discovery.internal.DiscoveryServiceRegistryImpl.startScans(DiscoveryServiceRegistryImpl.java:403) [96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.config.discovery.internal.DiscoveryServiceRegistryImpl.startScan(DiscoveryServiceRegistryImpl.java:260) [96:org.eclipse.smarthome.config.discovery:0.10.0.201809111909] at org.eclipse.smarthome.io.rest.core.internal.discovery.DiscoveryResource.scan(DiscoveryResource.java:97) [116:org.eclipse.smarthome.io.rest.core:0.10.0.201809111909] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?] at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [167:org.glassfish.jersey.core.jersey-common:2.22.2] at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [168:org.glassfish.jersey.core.jersey-server:2.22.2] at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [165:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2] at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [84:org.eclipse.jetty.servlet:9.4.11.v20180605] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [84:org.eclipse.jetty.servlet:9.4.11.v20180605] at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [81:org.eclipse.jetty.security:9.4.11.v20180605] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [84:org.eclipse.jetty.servlet:9.4.11.v20180605] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.Server.handle(Server.java:531) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [83:org.eclipse.jetty.server:9.4.11.v20180605] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [74:org.eclipse.jetty.io:9.4.11.v20180605] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [74:org.eclipse.jetty.io:9.4.11.v20180605] at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [74:org.eclipse.jetty.io:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [86:org.eclipse.jetty.util:9.4.11.v20180605] at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [86:org.eclipse.jetty.util:9.4.11.v20180605] at java.lang.Thread.run(Thread.java:748) [?:?] 2018-09-19 12:01:10.737 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting 2018-09-19 12:01:10.742 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator 2018-09-19 12:01:10.745 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280 2018-09-19 12:01:10.748 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting 2018-09-19 12:01:10.750 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE 2018-09-19 12:01:10.753 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25 2018-09-19 12:01:10.755 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE 2018-09-19 12:01:10.759 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise 013136BCE4BE39BD5942D85160D74E63 2018-09-19 12:01:10.763 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize with protocol ASH2. 2018-09-19 12:01:10.766 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF. 2018-09-19 12:01:10.777 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized. 2018-09-19 12:01:10.780 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst [] 2018-09-19 12:01:10.784 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - AshFrameHandler thread started 2018-09-19 12:01:10.788 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:11.932 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameRstAck [version=2, resetCode=11, Reset: Software] 2018-09-19 12:01:11.934 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Connected 2018-09-19 12:01:11.946 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspVersionRequest [desiredProtocolVersion=4] 2018-09-19 12:01:11.949 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=CA 00 00 04] 2018-09-19 12:01:12.059 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=CA 80 00 04 02 00 58] 2018-09-19 12:01:12.062 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=CA 00 00 04] 2018-09-19 12:01:12.064 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-19 12:01:12.071 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-19 12:01:12.071 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:12.073 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800] 2018-09-19 12:01:12.076 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:12.078 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE] 2018-09-19 12:01:12.081 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=CB 00 52 1A] 2018-09-19 12:01:12.191 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=CB 80 52 00 00 00] 2018-09-19 12:01:12.194 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=CB 00 52 1A] 2018-09-19 12:01:12.197 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:12.200 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:12.200 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:12.202 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 0 2018-09-19 12:01:12.202 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL] 2018-09-19 12:01:12.205 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=CC 00 52 0D] 2018-09-19 12:01:12.315 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=CC 80 52 00 05 00] 2018-09-19 12:01:12.318 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=CC 00 52 0D] 2018-09-19 12:01:12.320 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 12:01:12.323 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:12.323 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 12:01:12.327 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:12.329 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE] 2018-09-19 12:01:12.332 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=CD 00 52 05] 2018-09-19 12:01:12.442 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=CD 80 52 00 08 00] 2018-09-19 12:01:12.445 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=CD 00 52 05] 2018-09-19 12:01:12.447 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 12:01:12.450 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 12:01:12.452 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:12.452 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:12.454 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE] 2018-09-19 12:01:12.457 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=CE 00 52 19] 2018-09-19 12:01:12.566 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=CE 80 52 00 00 00] 2018-09-19 12:01:12.569 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=CE 00 52 19] 2018-09-19 12:01:12.571 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:12.573 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:12.574 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:12.576 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:12.579 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE] 2018-09-19 12:01:12.581 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=CF 00 52 0C] 2018-09-19 12:01:12.691 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=CF 80 52 00 00 00] 2018-09-19 12:01:12.694 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=CF 00 52 0C] 2018-09-19 12:01:12.696 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:12.700 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:12.702 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:12.702 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:12.705 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT] 2018-09-19 12:01:12.707 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=D0 00 52 12] 2018-09-19 12:01:12.816 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=D0 80 52 00 B8 0B] 2018-09-19 12:01:12.819 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=D0 00 52 12] 2018-09-19 12:01:12.821 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000] 2018-09-19 12:01:12.824 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:12.824 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000] 2018-09-19 12:01:12.827 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS] 2018-09-19 12:01:12.827 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:12.829 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=D1 00 52 10] 2018-09-19 12:01:12.939 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=D1 80 52 00 1E 00] 2018-09-19 12:01:12.942 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=D1 00 52 10] 2018-09-19 12:01:12.944 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 12:01:12.946 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:12.946 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 12:01:12.949 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:12.949 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE] 2018-09-19 12:01:12.952 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=D2 00 52 17] 2018-09-19 12:01:13.062 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=D2 80 52 00 00 00] 2018-09-19 12:01:13.065 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=D2 00 52 17] 2018-09-19 12:01:13.067 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:13.069 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:13.069 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:13.076 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS] 2018-09-19 12:01:13.076 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.079 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=D3 00 52 2D] 2018-09-19 12:01:13.188 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=D3 80 52 00 01 00] 2018-09-19 12:01:13.191 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=D3 00 52 2D] 2018-09-19 12:01:13.193 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 12:01:13.195 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:13.195 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 12:01:13.198 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.198 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE] 2018-09-19 12:01:13.201 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=D4 00 52 1E] 2018-09-19 12:01:13.311 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=D4 80 52 00 06 00] 2018-09-19 12:01:13.313 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=D4 00 52 1E] 2018-09-19 12:01:13.316 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6] 2018-09-19 12:01:13.318 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:13.318 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6] 2018-09-19 12:01:13.320 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.320 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS] 2018-09-19 12:01:13.323 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=D5 00 52 2A] 2018-09-19 12:01:13.433 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=D5 80 52 00 00 00] 2018-09-19 12:01:13.436 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=D5 00 52 2A] 2018-09-19 12:01:13.438 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:13.440 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:13.440 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:13.443 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.443 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN] 2018-09-19 12:01:13.446 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=D6 00 52 11] 2018-09-19 12:01:13.556 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=D6 80 52 00 20 00] 2018-09-19 12:01:13.559 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=D6 00 52 11] 2018-09-19 12:01:13.562 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32] 2018-09-19 12:01:13.564 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32] 2018-09-19 12:01:13.564 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:13.566 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT] 2018-09-19 12:01:13.566 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.569 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=D7 00 52 03] 2018-09-19 12:01:13.679 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=D7 80 52 00 0A 00] 2018-09-19 12:01:13.682 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=D7 00 52 03] 2018-09-19 12:01:13.684 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 12:01:13.686 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:13.686 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 12:01:13.689 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.689 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE] 2018-09-19 12:01:13.692 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=D8 00 52 2B] 2018-09-19 12:01:13.802 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=D8 80 52 00 0F 00] 2018-09-19 12:01:13.805 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=D8 00 52 2B] 2018-09-19 12:01:13.808 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 12:01:13.810 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:13.810 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 12:01:13.812 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.815 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE] 2018-09-19 12:01:13.817 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=D9 00 52 02] 2018-09-19 12:01:13.927 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=D9 80 52 00 10 00] 2018-09-19 12:01:13.928 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=D9 00 52 02] 2018-09-19 12:01:13.930 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:13.931 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:13.931 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:13.933 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:13.934 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT] 2018-09-19 12:01:13.936 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=DA 00 52 01] 2018-09-19 12:01:14.047 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=DA 80 52 00 4B 00] 2018-09-19 12:01:14.050 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=DA 00 52 01] 2018-09-19 12:01:14.051 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75] 2018-09-19 12:01:14.053 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:14.053 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75] 2018-09-19 12:01:14.055 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 6 2018-09-19 12:01:14.056 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 0 2018-09-19 12:01:14.057 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 32 2018-09-19 12:01:14.059 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 0 2018-09-19 12:01:14.060 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15 2018-09-19 12:01:14.061 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 3000 2018-09-19 12:01:14.063 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 0 2018-09-19 12:01:14.064 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 0 2018-09-19 12:01:14.066 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1 2018-09-19 12:01:14.067 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16 2018-09-19 12:01:14.068 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10 2018-09-19 12:01:14.070 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30 2018-09-19 12:01:14.071 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 75 2018-09-19 12:01:14.073 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5 2018-09-19 12:01:14.074 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8 2018-09-19 12:01:14.075 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 0 2018-09-19 12:01:14.077 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.078 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY] 2018-09-19 12:01:14.080 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=DB 00 56 00] 2018-09-19 12:01:14.187 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=DB 80 56 00 01] 2018-09-19 12:01:14.190 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=DB 00 56 00] 2018-09-19 12:01:14.191 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 12:01:14.193 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:14.193 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 12:01:14.195 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.195 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY] 2018-09-19 12:01:14.197 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=DC 00 56 01] 2018-09-19 12:01:14.308 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=DC 80 56 00 10] 2018-09-19 12:01:14.310 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=DC 00 56 01] 2018-09-19 12:01:14.312 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION] 2018-09-19 12:01:14.314 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:14.314 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION] 2018-09-19 12:01:14.316 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.317 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY] 2018-09-19 12:01:14.319 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=DD 00 56 04] 2018-09-19 12:01:14.431 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=DD 80 56 00 40] 2018-09-19 12:01:14.432 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=DD 00 56 04] 2018-09-19 12:01:14.434 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 12:01:14.435 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 12:01:14.435 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:14.437 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.438 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY] 2018-09-19 12:01:14.440 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=DE 00 56 05] 2018-09-19 12:01:14.551 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=DE 80 56 00 50] 2018-09-19 12:01:14.553 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=DE 00 56 05] 2018-09-19 12:01:14.556 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS] 2018-09-19 12:01:14.558 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:14.559 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS] 2018-09-19 12:01:14.562 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS 2018-09-19 12:01:14.564 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK 2018-09-19 12:01:14.568 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS 2018-09-19 12:01:14.570 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_DISALLOW_BINDING_MODIFICATION 2018-09-19 12:01:14.574 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16] 2018-09-19 12:01:14.576 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.579 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16] 2018-09-19 12:01:14.582 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=DF 00 53 1A 10 00] 2018-09-19 12:01:14.698 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=DF 80 53 00] 2018-09-19 12:01:14.701 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=DF 00 53 1A 10 00] 2018-09-19 12:01:14.703 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:14.705 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:14.705 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:14.707 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5] 2018-09-19 12:01:14.710 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.712 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5] 2018-09-19 12:01:14.716 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=E0 00 53 0D 05 00] 2018-09-19 12:01:14.825 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=E0 80 53 00] 2018-09-19 12:01:14.830 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=E0 00 53 0D 05 00] 2018-09-19 12:01:14.833 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:14.835 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:14.836 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:14.838 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8] 2018-09-19 12:01:14.841 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.844 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=8] 2018-09-19 12:01:14.847 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=E1 00 53 05 08 00] 2018-09-19 12:01:14.962 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=E1 80 53 00] 2018-09-19 12:01:14.965 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=E1 00 53 05 08 00] 2018-09-19 12:01:14.967 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:14.970 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:14.970 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:14.972 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2] 2018-09-19 12:01:14.975 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:14.977 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2] 2018-09-19 12:01:14.980 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=E2 00 53 19 02 00] 2018-09-19 12:01:15.094 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=E2 80 53 00] 2018-09-19 12:01:15.098 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=E2 00 53 19 02 00] 2018-09-19 12:01:15.100 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.103 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:15.103 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.107 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2] 2018-09-19 12:01:15.111 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:15.114 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2] 2018-09-19 12:01:15.117 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=E3 00 53 0C 02 00] 2018-09-19 12:01:15.227 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=E3 80 53 00] 2018-09-19 12:01:15.231 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=E3 00 53 0C 02 00] 2018-09-19 12:01:15.233 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.236 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:15.236 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.239 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680] 2018-09-19 12:01:15.241 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:15.244 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680] 2018-09-19 12:01:15.248 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=E4 00 53 12 00 1E] 2018-09-19 12:01:15.358 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=E4 80 53 00] 2018-09-19 12:01:15.361 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=E4 00 53 12 00 1E] 2018-09-19 12:01:15.363 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.365 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:15.365 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.368 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30] 2018-09-19 12:01:15.370 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:15.373 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30] 2018-09-19 12:01:15.376 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=E5 00 53 10 1E 00] 2018-09-19 12:01:15.486 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=E5 80 53 00] 2018-09-19 12:01:15.491 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=E5 00 53 10 1E 00] 2018-09-19 12:01:15.494 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.499 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:15.499 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.502 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=0] 2018-09-19 12:01:15.505 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:15.507 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=0] 2018-09-19 12:01:15.510 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=E6 00 53 17 00 00] 2018-09-19 12:01:15.619 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=E6 80 53 00] 2018-09-19 12:01:15.630 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=E6 00 53 17 00 00] 2018-09-19 12:01:15.633 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.637 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:15.637 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.642 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1] 2018-09-19 12:01:15.651 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:15.654 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1] 2018-09-19 12:01:15.660 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=E7 00 53 2D 01 00] 2018-09-19 12:01:15.774 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=E7 80 53 00] 2018-09-19 12:01:15.776 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=E7 00 53 2D 01 00] 2018-09-19 12:01:15.777 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.779 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:15.779 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.780 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4] 2018-09-19 12:01:15.783 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:15.784 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4] 2018-09-19 12:01:15.786 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=E8 00 53 1E 04 00] 2018-09-19 12:01:15.899 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=E8 80 53 00] 2018-09-19 12:01:15.901 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=E8 00 53 1E 04 00] 2018-09-19 12:01:15.902 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.903 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:15.903 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:15.905 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1] 2018-09-19 12:01:15.907 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:15.908 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1] 2018-09-19 12:01:15.910 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=E9 00 53 2A 01 00] 2018-09-19 12:01:16.020 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=E9 80 53 00] 2018-09-19 12:01:16.022 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=E9 00 53 2A 01 00] 2018-09-19 12:01:16.023 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.025 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.025 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:16.027 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16] 2018-09-19 12:01:16.028 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.030 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16] 2018-09-19 12:01:16.032 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=EA 00 53 11 10 00] 2018-09-19 12:01:16.145 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=EA 80 53 00] 2018-09-19 12:01:16.146 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=EA 00 53 11 10 00] 2018-09-19 12:01:16.148 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.150 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:16.150 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.151 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10] 2018-09-19 12:01:16.153 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.154 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10] 2018-09-19 12:01:16.156 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=EB 00 53 03 0A 00] 2018-09-19 12:01:16.268 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=EB 80 53 00] 2018-09-19 12:01:16.270 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=EB 00 53 03 0A 00] 2018-09-19 12:01:16.271 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.273 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:16.273 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.275 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15] 2018-09-19 12:01:16.277 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.279 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15] 2018-09-19 12:01:16.281 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=EC 00 53 2B 0F 00] 2018-09-19 12:01:16.389 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=EC 80 53 36] 2018-09-19 12:01:16.391 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=EC 00 53 2B 0F 00] 2018-09-19 12:01:16.392 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE] 2018-09-19 12:01:16.394 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE] 2018-09-19 12:01:16.395 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:16.396 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16] 2018-09-19 12:01:16.398 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.400 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16] 2018-09-19 12:01:16.402 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=ED 00 53 02 10 00] 2018-09-19 12:01:16.515 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=ED 80 53 00] 2018-09-19 12:01:16.519 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=ED 00 53 02 10 00] 2018-09-19 12:01:16.522 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.525 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:16.525 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.532 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255] 2018-09-19 12:01:16.535 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.537 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255] 2018-09-19 12:01:16.539 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=EE 00 53 01 FF 00] 2018-09-19 12:01:16.653 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=EE 80 53 00] 2018-09-19 12:01:16.656 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=EE 00 53 01 FF 00] 2018-09-19 12:01:16.659 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.661 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:16.661 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:16.664 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.666 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE] 2018-09-19 12:01:16.670 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=EF 00 52 1A] 2018-09-19 12:01:16.779 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=EF 80 52 00 10 00] 2018-09-19 12:01:16.781 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=EF 00 52 1A] 2018-09-19 12:01:16.782 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:16.783 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:16.784 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:16.785 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.787 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL] 2018-09-19 12:01:16.789 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=F0 00 52 0D] 2018-09-19 12:01:16.900 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=F0 80 52 00 05 00] 2018-09-19 12:01:16.902 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=F0 00 52 0D] 2018-09-19 12:01:16.903 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 12:01:16.904 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5] 2018-09-19 12:01:16.904 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:16.906 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:16.907 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE] 2018-09-19 12:01:16.909 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=F1 00 52 05] 2018-09-19 12:01:17.021 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=F1 80 52 00 08 00] 2018-09-19 12:01:17.036 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=F1 00 52 05] 2018-09-19 12:01:17.038 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 12:01:17.039 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:17.041 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8] 2018-09-19 12:01:17.043 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:17.044 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE] 2018-09-19 12:01:17.046 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=F2 00 52 19] 2018-09-19 12:01:17.154 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=F2 80 52 00 02 00] 2018-09-19 12:01:17.156 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=F2 00 52 19] 2018-09-19 12:01:17.157 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 12:01:17.158 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:17.159 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 12:01:17.161 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:17.162 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE] 2018-09-19 12:01:17.163 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=F3 00 52 0C] 2018-09-19 12:01:17.275 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=F3 80 52 00 02 00] 2018-09-19 12:01:17.276 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=F3 00 52 0C] 2018-09-19 12:01:17.278 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 12:01:17.279 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:17.279 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2] 2018-09-19 12:01:17.280 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:17.280 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT] 2018-09-19 12:01:17.282 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=F4 00 52 12] 2018-09-19 12:01:17.396 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=F4 80 52 00 00 1E] 2018-09-19 12:01:17.398 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=F4 00 52 12] 2018-09-19 12:01:17.399 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680] 2018-09-19 12:01:17.401 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680] 2018-09-19 12:01:17.401 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:17.404 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:17.406 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS] 2018-09-19 12:01:17.408 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=F5 00 52 10] 2018-09-19 12:01:17.517 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=F5 80 52 00 1E 00] 2018-09-19 12:01:17.520 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=F5 00 52 10] 2018-09-19 12:01:17.522 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 12:01:17.524 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:17.524 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30] 2018-09-19 12:01:17.526 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:17.526 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE] 2018-09-19 12:01:17.529 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=F6 00 52 17] 2018-09-19 12:01:17.639 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=F6 80 52 00 00 00] 2018-09-19 12:01:17.641 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=F6 00 52 17] 2018-09-19 12:01:17.643 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:17.645 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:17.645 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0] 2018-09-19 12:01:17.648 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS] 2018-09-19 12:01:17.648 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:17.650 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=F7 00 52 2D] 2018-09-19 12:01:17.760 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=F7 80 52 00 01 00] 2018-09-19 12:01:17.762 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=F7 00 52 2D] 2018-09-19 12:01:17.765 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 12:01:17.767 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:17.767 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 12:01:17.769 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE] 2018-09-19 12:01:17.769 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:17.772 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=F8 00 52 1E] 2018-09-19 12:01:17.881 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=F8 80 52 00 04 00] 2018-09-19 12:01:17.884 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=F8 00 52 1E] 2018-09-19 12:01:17.886 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4] 2018-09-19 12:01:17.888 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:17.888 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4] 2018-09-19 12:01:17.890 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS] 2018-09-19 12:01:17.890 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 0 2018-09-19 12:01:17.893 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=F9 00 52 2A] 2018-09-19 12:01:18.002 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=F9 80 52 00 01 00] 2018-09-19 12:01:18.005 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=F9 00 52 2A] 2018-09-19 12:01:18.007 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 12:01:18.009 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:18.009 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1] 2018-09-19 12:01:18.011 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN] 2018-09-19 12:01:18.011 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.014 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=FA 00 52 11] 2018-09-19 12:01:18.124 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=FA 80 52 00 10 00] 2018-09-19 12:01:18.126 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=FA 00 52 11] 2018-09-19 12:01:18.128 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:18.130 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:18.131 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:18.134 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.136 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT] 2018-09-19 12:01:18.139 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=FB 00 52 03] 2018-09-19 12:01:18.248 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=FB 80 52 00 0A 00] 2018-09-19 12:01:18.250 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=FB 00 52 03] 2018-09-19 12:01:18.252 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 12:01:18.254 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:18.254 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10] 2018-09-19 12:01:18.257 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.259 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE] 2018-09-19 12:01:18.262 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=FC 00 52 2B] 2018-09-19 12:01:18.373 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=FC 80 52 00 0F 00] 2018-09-19 12:01:18.376 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=FC 00 52 2B] 2018-09-19 12:01:18.378 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 12:01:18.380 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:18.380 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15] 2018-09-19 12:01:18.382 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE] 2018-09-19 12:01:18.382 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.385 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=FD 00 52 02] 2018-09-19 12:01:18.494 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=FD 80 52 00 10 00] 2018-09-19 12:01:18.496 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=FD 00 52 02] 2018-09-19 12:01:18.499 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:18.500 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:18.500 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16] 2018-09-19 12:01:18.503 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.503 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT] 2018-09-19 12:01:18.506 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=FE 00 52 01] 2018-09-19 12:01:18.615 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=FE 80 52 00 FF 00] 2018-09-19 12:01:18.618 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=FE 00 52 01] 2018-09-19 12:01:18.620 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255] 2018-09-19 12:01:18.622 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:18.622 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255] 2018-09-19 12:01:18.624 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 4 2018-09-19 12:01:18.626 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 16 2018-09-19 12:01:18.629 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 16 2018-09-19 12:01:18.631 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 1 2018-09-19 12:01:18.633 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15 2018-09-19 12:01:18.635 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680 2018-09-19 12:01:18.637 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 0 2018-09-19 12:01:18.640 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2 2018-09-19 12:01:18.642 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1 2018-09-19 12:01:18.644 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16 2018-09-19 12:01:18.646 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10 2018-09-19 12:01:18.649 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30 2018-09-19 12:01:18.651 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 255 2018-09-19 12:01:18.653 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5 2018-09-19 12:01:18.655 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8 2018-09-19 12:01:18.657 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 2 2018-09-19 12:01:18.660 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.662 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 12:01:18.665 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=01 00 55 00 01] 2018-09-19 12:01:18.775 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=01 80 55 00] 2018-09-19 12:01:18.779 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=01 00 55 00 01] 2018-09-19 12:01:18.781 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:18.784 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:18.784 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:18.787 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.787 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-19 12:01:18.790 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=02 00 55 01 12] 2018-09-19 12:01:18.898 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=02 80 55 00] 2018-09-19 12:01:18.902 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=02 00 55 01 12] 2018-09-19 12:01:18.904 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:18.906 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:18.907 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:18.909 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:18.911 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 12:01:18.915 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=03 00 55 04 40] 2018-09-19 12:01:19.024 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=03 80 55 00] 2018-09-19 12:01:19.027 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=03 00 55 04 40] 2018-09-19 12:01:19.030 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:19.032 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:19.032 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:19.034 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.036 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-19 12:01:19.040 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=04 00 55 05 52] 2018-09-19 12:01:19.149 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=04 80 55 00] 2018-09-19 12:01:19.152 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=04 00 55 05 52] 2018-09-19 12:01:19.156 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:19.159 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:19.159 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:19.161 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TRUST_CENTER_POLICY] 2018-09-19 12:01:19.161 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.164 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=05 00 56 00] 2018-09-19 12:01:19.274 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=05 80 56 00 01] 2018-09-19 12:01:19.276 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=05 00 56 00] 2018-09-19 12:01:19.278 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 12:01:19.281 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:19.281 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS] 2018-09-19 12:01:19.283 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.285 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_BINDING_MODIFICATION_POLICY] 2018-09-19 12:01:19.289 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=06 00 56 01] 2018-09-19 12:01:19.397 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=06 80 56 00 12] 2018-09-19 12:01:19.400 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=06 00 56 01] 2018-09-19 12:01:19.403 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-19 12:01:19.406 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:19.406 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS] 2018-09-19 12:01:19.412 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.414 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY] 2018-09-19 12:01:19.418 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=07 00 56 04] 2018-09-19 12:01:19.527 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=07 80 56 00 40] 2018-09-19 12:01:19.529 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=3, reTx=false, data=07 00 56 04] 2018-09-19 12:01:19.530 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 12:01:19.532 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK] 2018-09-19 12:01:19.532 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false] 2018-09-19 12:01:19.533 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetPolicyRequest [policyId=EZSP_TC_KEY_REQUEST_POLICY] 2018-09-19 12:01:19.533 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.535 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=08 00 56 05] 2018-09-19 12:01:19.647 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=08 80 56 00 52] 2018-09-19 12:01:19.649 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=4, reTx=false, data=08 00 56 05] 2018-09-19 12:01:19.650 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-19 12:01:19.652 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_GENERATE_NEW_TC_LINK_KEY] 2018-09-19 12:01:19.652 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false] 2018-09-19 12:01:19.653 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_GENERATE_NEW_TC_LINK_KEY 2018-09-19 12:01:19.654 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK 2018-09-19 12:01:19.656 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS 2018-09-19 12:01:19.657 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS 2018-09-19 12:01:19.658 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.660 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetEui64Request [] 2018-09-19 12:01:19.662 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=09 00 26] 2018-09-19 12:01:19.770 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=09 80 26 C1 C4 D1 0D 00 6F 0D 00] 2018-09-19 12:01:19.772 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=5, reTx=false, data=09 00 26] 2018-09-19 12:01:19.774 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetEui64Response [eui64=000D6F000DD1C4C1] 2018-09-19 12:01:19.775 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false] 2018-09-19 12:01:19.775 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember local IEEE Address is 000D6F000DD1C4C1 2018-09-19 12:01:19.777 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.779 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-19 12:01:19.780 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=0A 00 28] 2018-09-19 12:01:19.893 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=0A 80 28 93 CD 00 00 00 00 00 00 00 00 FF FF FF 00 00 FF FF 00 00 00 00 00] 2018-09-19 12:01:19.894 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=0A 00 28] 2018-09-19 12:01:19.897 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_NOT_JOINED, nodeType=UNKNOWN, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=65535, radioTxPower=255, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=65535, nwkUpdateId=0, channels=00000000]] 2018-09-19 12:01:19.898 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false] 2018-09-19 12:01:19.898 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:19.900 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspAddEndpointRequest [endpoint=1, profileId=260, deviceId=0, appFlags=0, inputClusterList=00, outputClusterList=00] 2018-09-19 12:01:19.902 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=0B 00 02 01 04 01 00 00 00 01 01 00 00 00 00] 2018-09-19 12:01:20.013 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=0B 80 02 00] 2018-09-19 12:01:20.014 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=0B 00 02 01 04 01 00 00 00 01 01 00 00 00 00] 2018-09-19 12:01:20.016 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspAddEndpointResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:20.017 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false] 2018-09-19 12:01:20.017 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspAddEndpointResponse [status=EZSP_SUCCESS] 2018-09-19 12:01:20.019 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:20.019 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspNetworkInitRequest [] 2018-09-19 12:01:20.020 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=0C 00 17] 2018-09-19 12:01:20.133 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=0C 80 17 93] 2018-09-19 12:01:20.135 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=0, reTx=false, data=0C 00 17] 2018-09-19 12:01:20.136 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspNetworkInitResponse [status=EMBER_NOT_JOINED] 2018-09-19 12:01:20.138 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false] 2018-09-19 12:01:20.138 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspNetworkInitResponse [status=EMBER_NOT_JOINED] 2018-09-19 12:01:20.139 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:20.141 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetNetworkParametersRequest [] 2018-09-19 12:01:20.142 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=0D 00 28] 2018-09-19 12:01:20.257 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=0D 80 28 93 CD 00 00 00 00 00 00 00 00 FF FF FF 00 00 FF FF 00 00 00 00 00] 2018-09-19 12:01:20.259 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=0D 00 28] 2018-09-19 12:01:20.262 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_NOT_JOINED, nodeType=UNKNOWN, parameters=EmberNetworkParameters [extendedPanId=0000000000000000, panId=65535, radioTxPower=255, radioChannel=0, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=65535, nwkUpdateId=0, channels=00000000]] 2018-09-19 12:01:20.264 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1 2018-09-19 12:01:20.264 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false] 2018-09-19 12:01:20.267 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspGetCurrentSecurityStateRequest [] 2018-09-19 12:01:20.270 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=0E 00 69] 2018-09-19 12:01:20.381 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=0E 80 69 93 00 00 00 00 00 00 00 00 FF FF] 2018-09-19 12:01:20.384 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=0E 00 69] 2018-09-19 12:01:20.387 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-19 12:01:20.389 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false] 2018-09-19 12:01:20.390 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-19 12:01:20.392 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - Error during retrieval of security parameters: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=FFFF000000000000]] 2018-09-19 12:01:20.395 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize done: Initialised true 2018-09-19 12:01:20.397 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start. 2018-09-19 12:01:20.400 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: File does not exist 2018-09-19 12:01:20.402 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000DD1C4C1: Adding local coordinator node to network 2018-09-19 12:01:20.405 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000DD1C4C1: Node 0 added to the network 2018-09-19 12:01:20.408 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task starting