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]