Bitron video Zigbee offline

I’ve managed to get my Bitron Video USB working with Openhab 2.5 M5 and connect up to a couple of temperature sensors. Unfortunately, a couple of days later it’s all showing as offline.

I’m not sure how to troubleshoot this further.

I include recent logs below, which do show a couple of exceptions, but the logs continue to get written two for another couple of hours before ultimately going quiet.

Ultimately giving openhab a restart results in the device coming online again, but this is obviously less that ideal.

Any suggestions on what I could look into or how to get more information on debugging would be gratefully received.

Recent logs
16:06:20.279 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B0], lastHopLqi=255, lastHopRssi=-42, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 EF 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 66 08 65 21 D4 10 66 2B 7D 89 01 00 0A 21 00 00]
16:06:20.279 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=B0, payload=18 EF 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 66 08 65 21 D4 10 66 2B 7D 89 01 00 0A 21 00 00]
16:07:11.193 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B1], lastHopLqi=254, lastHopRssi=-43, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F0 0A 00 00 29 64 08]
16:07:11.193 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=B1, payload=18 F0 0A 00 00 29 64 08]
16:07:11.193 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B2], lastHopLqi=255, lastHopRssi=-42, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F1 0A 00 00 21 DF 10]
16:07:11.194 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=B2, payload=18 F1 0A 00 00 21 DF 10]
16:07:11.194 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B3], lastHopLqi=255, lastHopRssi=-42, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F2 0A 00 00 29 EF 03 14 00 28 FF 10 00 29 58 27]
16:07:11.194 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=B3, payload=18 F2 0A 00 00 29 EF 03 14 00 28 FF 10 00 29 58 27]
16:45:22.616 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=2C], lastHopLqi=255, lastHopRssi=-51, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 E3 0A 00 00 29 EC 07]
16:45:22.617 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=2C, payload=18 E3 0A 00 00 29 EC 07]
16:45:22.618 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=2D], lastHopLqi=255, lastHopRssi=-51, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 E4 0A 00 00 21 4B 11]
16:45:22.618 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=2D, payload=18 E4 0A 00 00 21 4B 11]
16:45:22.618 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=2E], lastHopLqi=254, lastHopRssi=-51, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 E5 0A 00 00 29 F1 03 14 00 28 FF 10 00 29 72 27]
16:45:22.619 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=2E, payload=18 E5 0A 00 00 29 F1 03 14 00 28 FF 10 00 29 72 27]
16:46:50.563 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B4], lastHopLqi=254, lastHopRssi=-46, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F3 0A 00 00 29 69 08]
16:46:50.563 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=B4, payload=18 F3 0A 00 00 29 69 08]
16:46:50.563 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B5], lastHopLqi=254, lastHopRssi=-42, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F4 0A 00 00 21 5D 10]
16:46:50.563 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=B5, payload=18 F4 0A 00 00 21 5D 10]
16:46:50.564 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B6], lastHopLqi=254, lastHopRssi=-42, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F5 0A 00 00 29 EF 03 14 00 28 FF 10 00 29 56 27]
16:46:50.564 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=B6, payload=18 F5 0A 00 00 29 EF 03 14 00 28 FF 10 00 29 56 27]
16:58:15.884 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=2F], lastHopLqi=255, lastHopRssi=-54, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 E6 0A 01 FF 42 25 01 21 A9 0B 04 21 A8 13 05 21 22 00 06 24 01 00 00 00 00 64 29 E6 07 65 21 5A 11 66 2B 72 8A 01 00 0A 21 00 00]
16:58:15.885 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=2F, payload=18 E6 0A 01 FF 42 25 01 21 A9 0B 04 21 A8 13 05 21 22 00 06 24 01 00 00 00 00 64 29 E6 07 65 21 5A 11 66 2B 72 8A 01 00 0A 21 00 00]
16:58:23.430 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B7], lastHopLqi=255, lastHopRssi=-42, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F6 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 64 08 65 21 4F 10 66 2B 61 89 01 00 0A 21 00 00]
16:58:23.431 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=B7, payload=18 F6 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 64 08 65 21 4F 10 66 2B 61 89 01 00 0A 21 00 00]
17:00:33.480 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D0004445250: Discovery: Starting discovery for existing device
17:00:33.481 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D0003F187DC: Discovery: Starting discovery for existing device
17:00:33.481 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_ember:stick
17:00:33.482 [DEBUG] [gbee.handler.ZigBeeCoordinatorHandler] - ZigBee coordinator is offline - aborted scan for zigbee:coordinator_ember:stick
17:00:33.492 [INFO ] [gbee.discovery.ZigBeeDiscoveryService] - 00158D0004445250: Starting ZigBee device discovery
17:00:33.492 [INFO ] [gbee.discovery.ZigBeeDiscoveryService] - 00158D0003F187DC: Starting ZigBee device discovery
17:00:33.492 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D0003F187DC: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:stick
17:00:33.492 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 00158D0004445250: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:stick
17:00:33.494 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D0003F187DC: ZigBee node property discovery start
17:00:33.494 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D0004445250: ZigBee node property discovery start
17:00:33.494 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D0003F187DC: ZigBee node property discovery using basic cluster on endpoint 49145/1
17:00:33.494 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00158D0004445250: ZigBee node property discovery using basic cluster on endpoint 42237/1
17:00:33.495 [DEBUG] [.transaction.ZigBeeTransactionManager] - 00158D0003F187DC: Creating new Transaction Queue
17:00:33.496 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
17:00:33.496 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: Updated sleepy state from false to true
17:00:33.496 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: Added transaction to queue, len=1, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=--, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:33.497 [DEBUG] [.transaction.ZigBeeTransactionManager] - 49145/1: Sending ZigBeeTransaction [queueTime=1, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:33.497 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
17:00:33.497 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]
17:00:33.498 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=0, commandId=0]
17:00:33.498 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49145/1, profile=0104, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=60, payload=00 00 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
17:00:33.499 [DEBUG] [.transaction.ZigBeeTransactionManager] - 00158D0004445250: Creating new Transaction Queue
17:00:33.499 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0004445250: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
17:00:33.499 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0004445250: Updated sleepy state from false to true
17:00:33.499 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0004445250: Added transaction to queue, len=1, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=--, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:33.500 [DEBUG] [.transaction.ZigBeeTransactionManager] - 42237/1: Sending ZigBeeTransaction [queueTime=1, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:33.500 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
17:00:33.500 [WARN ] [ommon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@62c489ab[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@208ddc45[Wrapped task = com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp$2@658f4630]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5f1be63f[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 35029]
        at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
        at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor.execute(ScheduledThreadPoolExecutor.java:705) ~[?:?]
        at com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp.sendCommand(ZigBeeDongleEzsp.java:621) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeNetworkManager.sendCommand(ZigBeeNetworkManager.java:777) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.send(ZigBeeTransactionManager.java:443) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.sendNextTransaction(ZigBeeTransactionManager.java:669) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.queueTransaction(ZigBeeTransactionManager.java:371) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.sendTransaction(ZigBeeTransactionManager.java:355) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeNetworkManager.sendTransaction(ZigBeeNetworkManager.java:1612) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeNode.sendTransaction(ZigBeeNode.java:857) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeEndpoint.sendTransaction(ZigBeeEndpoint.java:539) ~[?:?]
        at com.zsmartsystems.zigbee.zcl.ZclCluster.send(ZclCluster.java:273) ~[?:?]
        at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributes(ZclCluster.java:358) ~[?:?]
        at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.addPropertiesFromBasicCluster(ZigBeeNodePropertyDiscoverer.java:157) ~[?:?]
        at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.getProperties(ZigBeeNodePropertyDiscoverer.java:109) ~[?:?]
        at org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService$2.run(ZigBeeDiscoveryService.java:212) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
        at java.lang.Thread.run(Thread.java:834) [?:?]
17:00:33.500 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]
17:00:33.509 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=1, commandId=0]
17:00:33.509 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=42237/1, profile=0104, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=61, payload=00 01 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
17:00:33.509 [WARN ] [ommon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@21a7af5f[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@66c5a094[Wrapped task = com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp$2@23c72228]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5f1be63f[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 35029]
        at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
        at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor.execute(ScheduledThreadPoolExecutor.java:705) ~[?:?]
        at com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp.sendCommand(ZigBeeDongleEzsp.java:621) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeNetworkManager.sendCommand(ZigBeeNetworkManager.java:777) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.send(ZigBeeTransactionManager.java:443) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.sendNextTransaction(ZigBeeTransactionManager.java:669) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.queueTransaction(ZigBeeTransactionManager.java:371) ~[?:?]
        at com.zsmartsystems.zigbee.transaction.ZigBeeTransactionManager.sendTransaction(ZigBeeTransactionManager.java:355) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeNetworkManager.sendTransaction(ZigBeeNetworkManager.java:1612) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeNode.sendTransaction(ZigBeeNode.java:857) ~[?:?]
        at com.zsmartsystems.zigbee.ZigBeeEndpoint.sendTransaction(ZigBeeEndpoint.java:539) ~[?:?]
        at com.zsmartsystems.zigbee.zcl.ZclCluster.send(ZclCluster.java:273) ~[?:?]
        at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributes(ZclCluster.java:358) ~[?:?]
        at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.addPropertiesFromBasicCluster(ZigBeeNodePropertyDiscoverer.java:157) ~[?:?]
        at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.getProperties(ZigBeeNodePropertyDiscoverer.java:109) ~[?:?]
        at org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService$2.run(ZigBeeDiscoveryService.java:212) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
        at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
        at java.lang.Thread.run(Thread.java:834) [?:?]
17:00:43.497 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [queueTime=10001, state=FAILED, sendCnt=1, command=ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:43.498 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
17:00:43.498 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: transactionComplete FAILED 0
17:00:43.498 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: Added transaction to queue, len=1, transaction=ZigBeeTransaction [queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:43.498 [DEBUG] [.transaction.ZigBeeTransactionManager] - 49145/1: Sending ZigBeeTransaction [queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:43.498 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
17:00:43.498 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]
17:00:43.499 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=0, commandId=0]
17:00:43.499 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=49145/1, profile=0104, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=62, payload=00 00 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
17:00:43.500 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [queueTime=10001, state=FAILED, sendCnt=1, command=ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:43.501 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
17:00:43.501 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0004445250: transactionComplete FAILED 0
17:00:43.501 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0004445250: Added transaction to queue, len=1, transaction=ZigBeeTransaction [queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:43.501 [DEBUG] [.transaction.ZigBeeTransactionManager] - 42237/1: Sending ZigBeeTransaction [queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:43.501 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
17:00:43.501 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]
17:00:43.501 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=1, commandId=0]
17:00:43.501 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=42237/1, profile=0104, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=63, payload=00 01 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
17:00:53.498 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [queueTime=20002, state=FAILED, sendCnt=2, command=ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:53.499 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
17:00:53.499 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: transactionComplete FAILED 0
17:00:53.499 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: transactionComplete exceeded retries 2
17:00:53.499 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction terminated: ZigBeeTransaction [queueTime=20003, state=FAILED, sendCnt=2, command=ReadAttributesCommand [Basic: 0/0 -> 49145/1, cluster=0000, TID=00, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:53.501 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [queueTime=20002, state=FAILED, sendCnt=2, command=ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:00:53.501 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
17:00:53.501 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0004445250: transactionComplete FAILED 0
17:00:53.501 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0004445250: transactionComplete exceeded retries 2
17:00:53.502 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction terminated: ZigBeeTransaction [queueTime=20003, state=FAILED, sendCnt=2, command=ReadAttributesCommand [Basic: 0/0 -> 42237/1, cluster=0000, TID=01, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
17:50:56.131 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B8], lastHopLqi=255, lastHopRssi=-40, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F7 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 52 08 65 21 49 10 66 2B 54 89 01 00 0A 21 00 00]
17:50:56.138 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=B8, payload=18 F7 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 52 08 65 21 49 10 66 2B 54 89 01 00 0A 21 00 00]
17:54:40.170 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=30], lastHopLqi=255, lastHopRssi=-50, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 E7 0A 01 FF 42 25 01 21 A9 0B 04 21 A8 13 05 21 22 00 06 24 01 00 00 00 00 64 29 CA 07 65 21 56 11 66 2B 6A 8A 01 00 0A 21 00 00]
17:54:40.170 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=30, payload=18 E7 0A 01 FF 42 25 01 21 A9 0B 04 21 A8 13 05 21 22 00 06 24 01 00 00 00 00 64 29 CA 07 65 21 56 11 66 2B 6A 8A 01 00 0A 21 00 00]
18:13:05.325 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=31], lastHopLqi=255, lastHopRssi=-50, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 E8 0A 00 00 29 B5 07]
18:13:05.326 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=31, payload=18 E8 0A 00 00 29 B5 07]
18:13:05.327 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=32], lastHopLqi=254, lastHopRssi=-50, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 E9 0A 00 00 21 4D 11]
18:13:05.327 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=32, payload=18 E9 0A 00 00 21 4D 11]
18:13:05.327 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=33], lastHopLqi=254, lastHopRssi=-50, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 EA 0A 00 00 29 F1 03 14 00 28 FF 10 00 29 70 27]
18:13:05.328 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=33, payload=18 EA 0A 00 00 29 F1 03 14 00 28 FF 10 00 29 70 27]
18:23:04.185 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=B9], lastHopLqi=254, lastHopRssi=-50, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F8 0A 00 00 29 56 08]
18:23:04.186 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=B9, payload=18 F8 0A 00 00 29 56 08]
18:23:04.187 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BA], lastHopLqi=255, lastHopRssi=-50, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 F9 0A 00 00 21 9D 10]
18:23:04.187 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=BA, payload=18 F9 0A 00 00 21 9D 10]
18:23:04.187 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BB], lastHopLqi=254, lastHopRssi=-50, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 FA 0A 00 00 29 EE 03 14 00 28 FF 10 00 29 53 27]
18:23:04.187 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=BB, payload=18 FA 0A 00 00 29 EE 03 14 00 28 FF 10 00 29 53 27]
18:32:46.625 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BC], lastHopLqi=255, lastHopRssi=-50, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 FB 0A 00 00 29 54 08]
18:32:46.625 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=BC, payload=18 FB 0A 00 00 29 54 08]
18:32:46.626 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BD], lastHopLqi=254, lastHopRssi=-50, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 FC 0A 00 00 21 2C 13]
18:32:46.626 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=BD, payload=18 FC 0A 00 00 21 2C 13]
18:32:46.626 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BE], lastHopLqi=255, lastHopRssi=-50, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 FD 0A 00 00 29 EE 03 14 00 28 FF 10 00 29 53 27]
18:32:46.626 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=BE, payload=18 FD 0A 00 00 29 EE 03 14 00 28 FF 10 00 29 53 27]
18:37:31.507 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=34], lastHopLqi=255, lastHopRssi=-53, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 EB 0A 00 00 29 CC 07]
18:37:31.508 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=34, payload=18 EB 0A 00 00 29 CC 07]
18:37:31.508 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=35], lastHopLqi=254, lastHopRssi=-53, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 EC 0A 00 00 21 78 12]
18:37:31.508 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=35, payload=18 EC 0A 00 00 21 78 12]
18:37:31.508 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=36], lastHopLqi=255, lastHopRssi=-53, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 ED 0A 00 00 29 F1 03 14 00 28 FF 10 00 29 6E 27]
18:37:31.508 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=36, payload=18 ED 0A 00 00 29 F1 03 14 00 28 FF 10 00 29 6E 27]
18:44:09.485 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=BF], lastHopLqi=255, lastHopRssi=-50, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 FE 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 4C 08 65 21 67 11 66 2B 2E 89 01 00 0A 21 00 00]
18:44:09.486 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=BF, payload=18 FE 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 4C 08 65 21 67 11 66 2B 2E 89 01 00 0A 21 00 00]
18:50:44.960 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=37], lastHopLqi=255, lastHopRssi=-53, sender=49145, bindingIndex=255, addressIndex=255, messageContents=18 EE 0A 01 FF 42 25 01 21 A9 0B 04 21 A8 13 05 21 22 00 06 24 01 00 00 00 00 64 29 CD 07 65 21 E9 12 66 2B 4E 8A 01 00 0A 21 00 00]
18:50:44.961 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=49145/1, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=37, payload=18 EE 0A 01 FF 42 25 01 21 A9 0B 04 21 A8 13 05 21 22 00 06 24 01 00 00 00 00 64 29 CD 07 65 21 E9 12 66 2B 4E 8A 01 00 0A 21 00 00]
19:28:30.433 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=C0], lastHopLqi=255, lastHopRssi=-46, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 FF 0A 00 00 29 36 08]
19:28:30.434 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0402, addressMode=null, radius=0, apsSecurity=false, apsCounter=C0, payload=18 FF 0A 00 00 29 36 08]
19:28:30.434 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=C1], lastHopLqi=254, lastHopRssi=-46, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 00 0A 00 00 21 9F 11]
19:28:30.434 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0405, addressMode=null, radius=0, apsSecurity=false, apsCounter=C1, payload=18 00 0A 00 00 21 9F 11]
19:28:30.435 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=C2], lastHopLqi=255, lastHopRssi=-46, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 01 0A 00 00 29 EE 03 14 00 28 FF 10 00 29 50 27]
19:28:30.435 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=42237/1, destinationAddress=0/1, profile=0104, cluster=0403, addressMode=null, radius=0, apsSecurity=false, apsCounter=C2, payload=18 01 0A 00 00 29 EE 03 14 00 28 FF 10 00 29 50 27]

There is a ZigBee log viewer here.

https://www.cd-jackson.com/index.php/openhab/zigbee-log-viewer

Please provide a detailed debug log that shows the problem. This log is not really showing the route cause of whatever your issue is.

Update log here: http://tmp.aligrant.com/openhab2.log which goes all the way back to the last restart (the previous day). Rummaging through it a bit more with the help of the log viewer, I think things go screwy around the 21:57 mark in the log, extract below.

To my uneducated eyes, it looks like something causes the Zigbee components to shutdown, and then when it restarts it is unable to switch back into an online state.

Summary
2019-12-02 21:57:57.612 [WARN ] [e.ember.internal.ash.AshFrameHandler] - ASH: ERROR received (code 81). Disconnecting.
2019-12-02 21:57:57.666 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager transport state updated to OFFLINE
2019-12-02 21:57:57.674 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
2019-12-02 21:57:57.688 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2019-12-02 21:57:57.695 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003F187DC: Coordinator status changed to OFFLINE.
2019-12-02 21:57:57.697 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003F187DC: Coordinator is unknown or not online.
2019-12-02 21:57:57.695 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004445250: Coordinator status changed to OFFLINE.
2019-12-02 21:57:57.698 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004445250: Coordinator is unknown or not online.
2019-12-02 21:57:57.707 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004445250: Polling stopped
2019-12-02 21:57:57.707 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003F187DC: Polling stopped
2019-12-02 21:57:58.749 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager shutdown: networkState=OFFLINE
2019-12-02 21:57:58.750 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to SHUTDOWN
2019-12-02 21:57:58.750 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2019-12-02 21:57:58.751 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - Network discovery task: shutdown
2019-12-02 21:57:58.752 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000C86D853: Node SVC Discovery: stopped
2019-12-02 21:57:58.753 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00158D0003F187DC: Node SVC Discovery: stopped
2019-12-02 21:57:58.753 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00158D0004445250: Node SVC Discovery: stopped
2019-12-02 21:57:58.754 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Shutdown
2019-12-02 21:57:58.755 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - Data store: shutting down.
2019-12-02 21:57:58.760 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember NCP Shutdown
2019-12-02 21:57:58.802 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port '/dev/ttyUSBzig' closed.
2019-12-02 21:57:58.803 [DEBUG] [transaction.ZigBeeTransactionManager] - Shutting down transaction manager
2019-12-02 21:57:58.808 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Queue shutdown
2019-12-02 21:57:58.809 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Queue shutdown
2019-12-02 21:57:58.809 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 000D6F000C86D853: Queue shutdown
2019-12-02 21:57:58.809 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D0003F187DC: Queue shutdown
2019-12-02 21:57:58.809 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction terminated: ZigBeeTransaction [queueTime=6359520, state=FAILED, sendCnt=1, command=ReadAttributesCommand [Pressure Measurement: 0/0 -> 49145/1, cluster=0403, TID=3B, identifiers=[16]]]
2019-12-02 21:57:58.810 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00158D0004445250: Queue shutdown
2019-12-02 21:57:58.810 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction terminated: ZigBeeTransaction [queueTime=6592429, state=FAILED, sendCnt=1, command=ReadAttributesCommand [Pressure Measurement: 0/0 -> 42237/1, cluster=0403, TID=38, identifiers=[16]]]
2019-12-02 21:57:58.811 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2019-12-02 21:57:58.816 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Default: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=2, interTransactionDelay=50, maxRetries=2]
2019-12-02 21:57:58.817 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2019-12-02 21:57:58.818 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Multicast: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=3, interTransactionDelay=1200, maxRetries=0]
2019-12-02 21:57:58.822 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400
2019-12-02 21:57:58.823 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
2019-12-02 21:57:58.824 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
2019-12-02 21:57:58.824 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster 1280
2019-12-02 21:57:58.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
2019-12-02 21:57:58.824 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster 25
2019-12-02 21:57:58.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - ZigBeeNetworkManager initialize: networkState=UNINITIALISED
2019-12-02 21:57:58.825 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
2019-12-02 21:57:58.825 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2019-12-02 21:57:58.864 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000C86D853: ZigBee reading network state complete.
2019-12-02 21:57:58.866 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 000D6F000C86D853: Data store: Node was restored.
2019-12-02 21:57:58.867 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000C86D853: Updating node NWK=0
2019-12-02 21:57:58.898 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0003F187DC: ZigBee reading network state complete.
2019-12-02 21:57:58.899 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 49145/1: Unsupported cluster FFFF - using ZclCustomCluster
2019-12-02 21:57:58.901 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 49145/1: Unsupported cluster FFFF - using ZclCustomCluster
2019-12-02 21:57:58.901 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 00158D0003F187DC: Data store: Node was restored.
2019-12-02 21:57:58.902 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D0003F187DC: Updating node NWK=49145
2019-12-02 21:57:58.923 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004445250: ZigBee reading network state complete.
2019-12-02 21:57:58.924 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 42237/1: Unsupported cluster FFFF - using ZclCustomCluster
2019-12-02 21:57:58.925 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 42237/1: Unsupported cluster FFFF - using ZclCustomCluster
2019-12-02 21:57:58.925 [DEBUG] [atabase.ZigBeeNetworkDatabaseManager] - 00158D0004445250: Data store: Node was restored.
2019-12-02 21:57:58.926 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D0004445250: Updating node NWK=42237
2019-12-02 21:57:58.926 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize with protocol ASH2.
2019-12-02 21:57:58.926 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSBzig] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
2019-12-02 21:57:58.945 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSBzig] is initialized.
2019-12-02 21:58:00.012 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800]
2019-12-02 21:58:00.012 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5800]
2019-12-02 21:58:00.031 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.031 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.052 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2019-12-02 21:58:00.052 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2019-12-02 21:58:00.072 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8]
2019-12-02 21:58:00.072 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=8]
2019-12-02 21:58:00.094 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.094 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.114 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.115 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.135 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000]
2019-12-02 21:58:00.135 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=3000]
2019-12-02 21:58:00.155 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
2019-12-02 21:58:00.155 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
2019-12-02 21:58:00.176 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.177 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.196 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:00.197 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:00.222 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6]
2019-12-02 21:58:00.222 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6]
2019-12-02 21:58:00.242 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.242 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2019-12-02 21:58:00.262 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32]
2019-12-02 21:58:00.262 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=32]
2019-12-02 21:58:00.283 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10]
2019-12-02 21:58:00.283 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10]
2019-12-02 21:58:00.303 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
2019-12-02 21:58:00.303 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
2019-12-02 21:58:00.324 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:00.324 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:00.345 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75]
2019-12-02 21:58:00.345 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=75]
2019-12-02 21:58:00.365 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2019-12-02 21:58:00.365 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6]
2019-12-02 21:58:00.406 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=6]
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 6
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 0
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 0
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT_SHIFT = 6
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 0
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 0
2019-12-02 21:58:00.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 3000
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT = 5
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 75
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 32
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 8
2019-12-02 21:58:00.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 0
2019-12-02 21:58:00.408 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10
2019-12-02 21:58:00.408 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15
2019-12-02 21:58:00.426 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS]
2019-12-02 21:58:00.426 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS]
2019-12-02 21:58:00.446 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION]
2019-12-02 21:58:00.446 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DISALLOW_BINDING_MODIFICATION]
2019-12-02 21:58:00.467 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
2019-12-02 21:58:00.467 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
2019-12-02 21:58:00.487 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
2019-12-02 21:58:00.487 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
2019-12-02 21:58:00.508 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_APP_KEY_REQUESTS]
2019-12-02 21:58:00.508 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_APP_KEY_REQUESTS]
2019-12-02 21:58:00.508 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS
2019-12-02 21:58:00.508 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS
2019-12-02 21:58:00.508 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_DISALLOW_BINDING_MODIFICATION
2019-12-02 21:58:00.508 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_APP_KEY_REQUEST_POLICY = EZSP_ALLOW_APP_KEY_REQUESTS
2019-12-02 21:58:00.508 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK
2019-12-02 21:58:00.508 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE, value=16]
2019-12-02 21:58:00.528 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.528 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.528 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL, value=5]
2019-12-02 21:58:00.548 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.548 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.549 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_ADDRESS_TABLE_SIZE, value=25]
2019-12-02 21:58:00.569 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.569 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.569 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE, value=2]
2019-12-02 21:58:00.589 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.589 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.589 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE, value=2]
2019-12-02 21:58:00.610 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.610 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.610 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT, value=7680]
2019-12-02 21:58:00.630 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.630 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.630 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS, value=30]
2019-12-02 21:58:00.652 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.652 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.652 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_TX_POWER_MODE, value=1]
2019-12-02 21:58:00.672 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.672 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.672 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS, value=1]
2019-12-02 21:58:00.693 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.693 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.693 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE, value=4]
2019-12-02 21:58:00.713 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.713 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.713 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APPLICATION_ZDO_FLAGS, value=1]
2019-12-02 21:58:00.733 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.734 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.734 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_END_DEVICE_CHILDREN, value=16]
2019-12-02 21:58:00.754 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.754 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.754 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT, value=10]
2019-12-02 21:58:00.774 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.774 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.775 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_BROADCAST_TABLE_SIZE, value=15]
2019-12-02 21:58:00.795 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE]
2019-12-02 21:58:00.795 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_ERROR_INVALID_VALUE]
2019-12-02 21:58:00.795 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_NEIGHBOR_TABLE_SIZE, value=16]
2019-12-02 21:58:00.815 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.815 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.823 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_PACKET_BUFFER_COUNT, value=255]
2019-12-02 21:58:00.836 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.837 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.837 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT, value=211]
2019-12-02 21:58:00.856 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.856 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.856 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueRequest [configId=EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT_SHIFT, value=12]
2019-12-02 21:58:00.877 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.877 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetConfigurationValueResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:00.897 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:00.897 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:00.918 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2019-12-02 21:58:00.918 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2019-12-02 21:58:00.938 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=25]
2019-12-02 21:58:00.938 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=25]
2019-12-02 21:58:00.979 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
2019-12-02 21:58:00.979 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
2019-12-02 21:58:01.000 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
2019-12-02 21:58:01.000 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
2019-12-02 21:58:01.020 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680]
2019-12-02 21:58:01.020 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=7680]
2019-12-02 21:58:01.041 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
2019-12-02 21:58:01.041 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
2019-12-02 21:58:01.081 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:01.081 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:01.102 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:01.104 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:01.122 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4]
2019-12-02 21:58:01.122 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=4]
2019-12-02 21:58:01.163 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:01.163 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=1]
2019-12-02 21:58:01.184 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:01.184 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:01.204 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10]
2019-12-02 21:58:01.205 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=10]
2019-12-02 21:58:01.225 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
2019-12-02 21:58:01.225 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=15]
2019-12-02 21:58:01.245 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:01.245 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=16]
2019-12-02 21:58:01.265 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255]
2019-12-02 21:58:01.266 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=255]
2019-12-02 21:58:01.286 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=211]
2019-12-02 21:58:01.286 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=211]
2019-12-02 21:58:01.306 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=12]
2019-12-02 21:58:01.307 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=12]
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_KEY_TABLE_SIZE = 4
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TRUST_CENTER_ADDRESS_CACHE_SIZE = 2
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_STACK_PROFILE = 2
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT_SHIFT = 12
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APPLICATION_ZDO_FLAGS = 1
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SOURCE_ROUTE_TABLE_SIZE = 16
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_INDIRECT_TRANSMISSION_TIMEOUT = 7680
2019-12-02 21:58:01.307 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SUPPORTED_NETWORKS = 1
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_END_DEVICE_POLL_TIMEOUT = 211
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_HOPS = 30
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_NEIGHBOR_TABLE_SIZE = 16
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_SECURITY_LEVEL = 5
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_PACKET_BUFFER_COUNT = 255
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_MAX_END_DEVICE_CHILDREN = 16
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_ADDRESS_TABLE_SIZE = 25
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_TX_POWER_MODE = 1
2019-12-02 21:58:01.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_APS_UNICAST_MESSAGE_COUNT = 10
2019-12-02 21:58:01.309 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Configuration state EZSP_CONFIG_BROADCAST_TABLE_SIZE = 15
2019-12-02 21:58:01.327 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.327 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.347 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.348 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.388 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.389 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.429 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.430 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.470 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.470 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSetPolicyResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.511 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS]
2019-12-02 21:58:01.511 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_ALLOW_PRECONFIGURED_KEY_JOINS]
2019-12-02 21:58:01.531 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS]
2019-12-02 21:58:01.531 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS]
2019-12-02 21:58:01.552 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
2019-12-02 21:58:01.552 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK]
2019-12-02 21:58:01.572 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
2019-12-02 21:58:01.572 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_TC_KEY_REQUESTS]
2019-12-02 21:58:01.593 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_APP_KEY_REQUESTS]
2019-12-02 21:58:01.593 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetPolicyResponse [status=EZSP_SUCCESS, decisionId=EZSP_DENY_APP_KEY_REQUESTS]
2019-12-02 21:58:01.593 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TC_KEY_REQUEST_POLICY = EZSP_DENY_TC_KEY_REQUESTS
2019-12-02 21:58:01.593 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_TRUST_CENTER_POLICY = EZSP_ALLOW_PRECONFIGURED_KEY_JOINS
2019-12-02 21:58:01.593 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_BINDING_MODIFICATION_POLICY = EZSP_CHECK_BINDING_MODIFICATIONS_ARE_VALID_ENDPOINT_CLUSTERS
2019-12-02 21:58:01.593 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_APP_KEY_REQUEST_POLICY = EZSP_DENY_APP_KEY_REQUESTS
2019-12-02 21:58:01.593 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Policy state EZSP_MESSAGE_CONTENTS_IN_CALLBACK_POLICY = EZSP_MESSAGE_TAG_ONLY_IN_CALLBACK
2019-12-02 21:58:01.613 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetEui64Response [eui64=000D6F000C86D853]
2019-12-02 21:58:01.614 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Ember local IEEE Address is 000D6F000C86D853
2019-12-02 21:58:01.633 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=F91C012805CA974E, panId=60801, radioTxPower=255, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0, nwkUpdateId=0, channels=07FFF800]]
2019-12-02 21:58:01.674 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspAddEndpointResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.674 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspAddEndpointResponse [status=EZSP_SUCCESS]
2019-12-02 21:58:01.695 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspNetworkInitResponse [status=EMBER_SUCCESS]
2019-12-02 21:58:01.695 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspNetworkInitResponse [status=EMBER_SUCCESS]
2019-12-02 21:58:01.695 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspStackStatusHandler [status=EMBER_NETWORK_UP]
2019-12-02 21:58:01.716 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNetworkParametersResponse [status=EMBER_SUCCESS, nodeType=EMBER_COORDINATOR, parameters=EmberNetworkParameters [extendedPanId=F91C012805CA974E, panId=60801, radioTxPower=255, radioChannel=11, joinMethod=EMBER_USE_MAC_ASSOCIATION, nwkManagerId=0, nwkUpdateId=0, channels=07FFF800]]
2019-12-02 21:58:01.736 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetNodeIdResponse [nodeId=0]
2019-12-02 21:58:01.737 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Ignoring invalid transport state transition in UNINITIALISED to ONLINE
2019-12-02 21:58:01.756 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=[EMBER_HAVE_TRUST_CENTER_LINK_KEY, EMBER_GLOBAL_LINK_KEY, EMBER_TRUST_CENTER_USES_HASHED_LINK_KEY], trustCenterLongAddress=000D6F000C86D853]]
2019-12-02 21:58:01.757 [DEBUG] [systems.zigbee.dongle.ember.EmberNcp] - EzspGetCurrentSecurityStateResponse [status=EMBER_SUCCESS, state=EmberCurrentSecurityState [bitmask=[EMBER_HAVE_TRUST_CENTER_LINK_KEY, EMBER_GLOBAL_LINK_KEY, EMBER_TRUST_CENTER_USES_HASHED_LINK_KEY], trustCenterLongAddress=000D6F000C86D853]]
2019-12-02 21:58:21.220 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0, clusterId=31, sourceEndpoint=0, destinationEndpoint=0, options=[], groupId=0, sequence=00], lastHopLqi=255, lastHopRssi=0, sender=0, bindingIndex=255, addressIndex=255, messageContents=81 02 50 52 44 04 00 8D 15 00 DC 87 F1 03 00 8D 15 00]
2019-12-02 21:58:21.220 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Dropping APS: state=INITIALISING, frame=ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=001F, addressMode=null, radius=0, apsSecurity=false, apsCounter=00, payload=81 02 50 52 44 04 00 8D 15 00 DC 87 F1 03 00 8D 15 00]
2019-12-02 21:58:22.155 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_BROADCAST, indexOrDestination=65532, apsFrame=EmberApsFrame [profileId=0, clusterId=31, sourceEndpoint=0, destinationEndpoint=0, options=[], groupId=0, sequence=00], messageTag=00, status=EMBER_SUCCESS, messageContents=]
2019-12-02 21:58:22.156 [ERROR] [e.ember.internal.ash.AshFrameHandler] - AshFrameHandler Exception processing EZSP frame: 
java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@6407a47a[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@4f9278c2[Wrapped task = com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp$3@9da003f]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5f1be63f[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 35029]
	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor.execute(ScheduledThreadPoolExecutor.java:705) ~[?:?]
	at com.zsmartsystems.zigbee.dongle.ember.ZigBeeDongleEzsp.handlePacket(ZigBeeDongleEzsp.java:689) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.dongle.ember.internal.ash.AshFrameHandler.handleIncomingFrame(AshFrameHandler.java:330) [bundleFile:?]
	at com.zsmartsystems.zigbee.dongle.ember.internal.ash.AshFrameHandler.access$800(AshFrameHandler.java:56) [bundleFile:?]
	at com.zsmartsystems.zigbee.dongle.ember.internal.ash.AshFrameHandler$1.run(AshFrameHandler.java:206) [bundleFile:?]
2019-12-02 22:05:59.916 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=48], lastHopLqi=255, lastHopRssi=-42, sender=42237, bindingIndex=255, addressIndex=255, messageContents=18 87 0A 01 FF 42 25 01 21 9F 0B 04 21 A8 13 05 21 72 00 06 24 01 00 00 00 00 64 29 CF 08 65 21 D3 14 66 2B 15 8C 01 00 0A 21 00 00]

There’s an error in the log that if I remember correctly just means that the dongle has a problem. The binding will then restart, which it seems to be ok. The error about the invalid state looks a bit strange, and I’ve recently found some issues with this code, but since the version in the binding hasn’t changed for 6 months or so, Does this happen every time you start the binding - ie is this repeatable?

I can’t reproduce it on demand. I don’t think I was interacting with the system around this time, so I’m not sure what spooked it.

I’ll keep an eye on it and report if it happens again and try to ascertain any pattern (interval since restart, time of day etc).

1 Like