console - zigbee nodes - error
SummerSeaSun opened this issue · 2 comments
SummerSeaSun commented
Guidelines
console was showing this output:
openhab-cli console
zigbee nodes
Network Addr IEEE Address Logical Type State EP Profile Device Type Manufacturer Model
0 0000 000D6F000DD02839 COORDINATOR ONLINE
16068 3EC4 847127FFFE0323E1 END_DEVICE ONLINE 1 ZIGBEE_HOME_AUTOMATION TEMPERATURE_SENSOR _TZ3000_rusu2vzb TS0201
Then I've added a Legrand Light switch with neutral K4003C from WebUI scan, and started getting errors.
Device isa Legrand Light switch with neutral K4003C.
Now the zigbee nodes output:
openhab> zigbee nodes
An unexpected error occurred during execution.
Logs:
2021-11-22 11:26:09.051 [ERROR] [b.core.io.console.ConsoleInterpreter] - An error occurred while executing the console command.
java.lang.NullPointerException: null
at com.zsmartsystems.zigbee.ZigBeeDeviceType.getByValue(ZigBeeDeviceType.java:648) ~[?:?]
at com.zsmartsystems.zigbee.console.ZigBeeConsoleNodeListCommand.printNode(ZigBeeConsoleNodeListCommand.java:96) ~[?:?]
at com.zsmartsystems.zigbee.console.ZigBeeConsoleNodeListCommand.process(ZigBeeConsoleNodeListCommand.java:71) ~[?:?]
at org.openhab.binding.zigbee.console.internal.ZigBeeConsoleCommandExtension.handleZigbeeCommand(ZigBeeConsoleCommandExtension.java:149) ~[?:?]
at org.openhab.binding.zigbee.console.internal.ZigBeeConsoleCommandExtension.handleCommand(ZigBeeConsoleCommandExtension.java:117) ~[?:?]
at org.openhab.binding.zigbee.console.internal.ZigBeeConsoleCommandExtension.execute(ZigBeeConsoleCommandExtension.java:89) ~[?:?]
at org.openhab.core.io.console.ConsoleInterpreter.execute(ConsoleInterpreter.java:55) [bundleFile:?]
at org.openhab.core.io.console.karaf.internal.CommandWrapper.execute(CommandWrapper.java:78) [bundleFile:?]
at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:68) [bundleFile:4.3.2]
at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:86) [bundleFile:4.3.2]
at org.apache.felix.gogo.runtime.Closure.executeCmd(Closure.java:599) [bundleFile:4.3.2]
at org.apache.felix.gogo.runtime.Closure.executeStatement(Closure.java:526) [bundleFile:4.3.2]
at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:415) [bundleFile:4.3.2]
at org.apache.felix.gogo.runtime.Pipe.doCall(Pipe.java:416) [bundleFile:4.3.2]
at org.apache.felix.gogo.runtime.Pipe.call(Pipe.java:229) [bundleFile:4.3.2]
at org.apache.felix.gogo.runtime.Pipe.call(Pipe.java:59) [bundleFile:4.3.2]
at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
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:829) [?:?]
log:set debug org.openhab.binding.zigbee
log:set debug com.zsmartsystems.zigbee
Outline
Describe your issue. Try and be clear and avoid jumping to conclusions about the cause - instead provide full information about the issue, what you did and what happened. Cover what happened, and what you think should have happened.
Configuration
| Configuration | Description |
|---|---|
| Coordinator used | Ember EM35x NCP - BV 2010/10 |
| openHAB version | OpenHabian v.3.1.0 Release build |
| Hardware | Raspberry Pi 4 Model B Rev 1.2 |
| Memory | 4GB |
| Java version | openjdk 11.0.13 2021-10-19 LTS |
| OpenJDK Runtime Environment Zulu11.52+13-CA (build 11.0.13+8-LTS) | |
| OpenJDK Client VM Zulu11.52+13-CA (build 11.0.13+8-LTS, mixed mode) |
|
| Devices | Provide all device types and their addresses |
Logs
2021-11-22 11:34:23.778 [INFO ] [ng.zigbee.handler.ZigBeeThingHandler] - 847127FFFE0323E1: Channel zigbee:device:01380FB1:847127fffe0323e1:847127FFFE0323E1_1_batteryalarm failed to initialise device
2021-11-22 11:34:23.779 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
2021-11-22 11:34:23.780 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 847127FFFE0323E1: Initializing channel zigbee:device:01380FB1:847127fffe0323e1:847127FFFE0323E1_1_humidity with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterRelativeHumidity@119ab8b
2021-11-22 11:34:23.780 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:23.781 [DEBUG] [transaction.ZigBeeTransactionManager] - 3EC4/0: Sending ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=40032, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:23.786 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2021-11-22 11:34:23.788 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]
2021-11-22 11:34:23.789 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=3EC4/0, profile=0000, cluster=0021, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=35, rssi=--, lqi=--, payload=2A E1 23 03 FE FF 27 71 84 01 01 00 03 39 28 D0 0D 00 6F 0D 00 01]
2021-11-22 11:34:23.790 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:23.793 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=--, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1029, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:23.794 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:23.803 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=95]
2021-11-22 11:34:23.831 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:29.426 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:30.364 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:30.924 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=01], lastHopLqi=123, lastHopRssi=-64, sender=A683, bindingIndex=255, addressIndex=255, messageContents=18 0E 0A 00 00 10 00]
2021-11-22 11:34:30.925 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=A683/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=01, rssi=-64, lqi=7B, payload=18 0E 0A 00 00 10 00]
2021-11-22 11:34:30.926 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 0004740000B5C716: Node update. NWK Address=A683
2021-11-22 11:34:30.927 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 0004740000B5C716: Node A683 is not updated
2021-11-22 11:34:30.928 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=14, commandId=10]
2021-11-22 11:34:30.930 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: A683/1 -> 0000/1, cluster=0006, TID=0E, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]]
2021-11-22 11:34:30.931 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: A683/1 -> 0000/1, cluster=0006, TID=0E, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]]
2021-11-22 11:34:30.933 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 0004740000B5C716: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=false, lastReportTime=Mon Nov 22 11:34:30 CET 2021, implemented=true]
2021-11-22 11:34:30.934 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 0004740000B5C716: Channel zigbee:device:01380FB1:0004740000b5c716:0004740000B5C716_1_switch updated to OFF
2021-11-22 11:34:30.935 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0004740000B5C716: Updating ZigBee channel state zigbee:device:01380FB1:0004740000b5c716:0004740000B5C716_1_switch to OFF
2021-11-22 11:34:30.937 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380FB1:0004740000b5c716
2021-11-22 11:34:30.938 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380FB1:0004740000b5c716
2021-11-22 11:34:30.939 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380FB1:0004740000b5c716 in 1830 seconds
2021-11-22 11:34:30.994 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=3EC4, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=93], messageTag=29, status=EMBER_DELIVERY_FAILED, messageContents=]
2021-11-22 11:34:30.995 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=29 state=RX_NAK
2021-11-22 11:34:30.996 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=29, state=RX_NAK, outstanding=1
2021-11-22 11:34:31.310 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:33.788 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=50039, state=FAILED, sendCnt=1, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:33.789 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-11-22 11:34:33.790 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete, state=FAILED, outstanding=0
2021-11-22 11:34:33.791 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: Added transaction to queue, len=3, transaction=ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=50042, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:33.792 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:33.793 [DEBUG] [transaction.ZigBeeTransactionManager] - 3EC4/0: Sending ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=50044, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:33.793 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2021-11-22 11:34:33.795 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]
2021-11-22 11:34:33.796 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=3EC4/0, profile=0000, cluster=0021, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=36, rssi=--, lqi=--, payload=2A E1 23 03 FE FF 27 71 84 01 01 00 03 39 28 D0 0D 00 6F 0D 00 01]
2021-11-22 11:34:33.809 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=96]
2021-11-22 11:34:33.842 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=1/3
2021-11-22 11:34:39.433 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:40.379 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:41.000 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=3EC4, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=94], messageTag=29, status=EMBER_DELIVERY_FAILED, messageContents=]
2021-11-22 11:34:41.001 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=29 state=RX_NAK
2021-11-22 11:34:41.002 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=29, state=RX_NAK, outstanding=1
2021-11-22 11:34:41.316 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:43.795 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=60046, state=FAILED, sendCnt=2, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:43.796 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-11-22 11:34:43.797 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete, state=FAILED, outstanding=0
2021-11-22 11:34:43.797 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete exceeded max retries 2
2021-11-22 11:34:43.798 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=60050, state=CANCELLED, sendCnt=2, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:43.799 [DEBUG] [onverter.ZigBeeConverterBatteryAlarm] - Could not bind to the power configuration cluster; polling battery alarm state every 1800 seconds
2021-11-22 11:34:43.800 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=60051, state=CANCELLED, sendCnt=2, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2A, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:43.800 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 847127FFFE0323E1: Channel zigbee:device:01380FB1:847127fffe0323e1:847127FFFE0323E1_1_batteryalarm failed to initialise device
2021-11-22 11:34:43.800 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-11-22 11:34:43.801 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
2021-11-22 11:34:43.802 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 847127FFFE0323E1: Initializing channel zigbee:device:01380FB1:847127fffe0323e1:847127FFFE0323E1_1_humidity with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterRelativeHumidity@5265b
2021-11-22 11:34:43.802 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:43.803 [DEBUG] [transaction.ZigBeeTransactionManager] - 3EC4/0: Sending ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=40034, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:43.804 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2021-11-22 11:34:43.805 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]
2021-11-22 11:34:43.807 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=3EC4/0, profile=0000, cluster=0021, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=37, rssi=--, lqi=--, payload=2B E1 23 03 FE FF 27 71 84 01 02 04 03 39 28 D0 0D 00 6F 0D 00 01]
2021-11-22 11:34:43.808 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:43.833 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=--, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1029, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:43.837 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:43.837 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=97]
2021-11-22 11:34:43.854 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:49.449 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:50.386 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:51.015 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=3EC4, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=95], messageTag=2A, status=EMBER_DELIVERY_FAILED, messageContents=]
2021-11-22 11:34:51.016 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=2A state=RX_NAK
2021-11-22 11:34:51.017 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=2A, state=RX_NAK, outstanding=1
2021-11-22 11:34:51.327 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:34:53.805 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=50036, state=FAILED, sendCnt=1, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:53.806 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-11-22 11:34:53.807 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete, state=FAILED, outstanding=0
2021-11-22 11:34:53.808 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: Added transaction to queue, len=3, transaction=ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=50039, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:53.809 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2021-11-22 11:34:53.810 [DEBUG] [transaction.ZigBeeTransactionManager] - 3EC4/0: Sending ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=50041, state=WAITING, sendCnt=1, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:34:53.811 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2021-11-22 11:34:53.812 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]
2021-11-22 11:34:53.814 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=3EC4/0, profile=0000, cluster=0021, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=38, rssi=--, lqi=--, payload=2B E1 23 03 FE FF 27 71 84 01 02 04 03 39 28 D0 0D 00 6F 0D 00 01]
2021-11-22 11:34:53.829 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=98]
2021-11-22 11:34:53.861 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=1/3
2021-11-22 11:34:59.456 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:35:00.397 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:35:01.022 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=3EC4, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=96], messageTag=2A, status=EMBER_DELIVERY_FAILED, messageContents=]
2021-11-22 11:35:01.024 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=2A state=RX_NAK
2021-11-22 11:35:01.024 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=2A, state=RX_NAK, outstanding=1
2021-11-22 11:35:01.335 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteErrorHandler [networkId=0, status=EMBER_MAC_INDIRECT_TIMEOUT, target=3EC4]
2021-11-22 11:35:03.812 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=60043, state=FAILED, sendCnt=2, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:35:03.813 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-11-22 11:35:03.814 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete, state=FAILED, outstanding=0
2021-11-22 11:35:03.815 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete exceeded max retries 2
2021-11-22 11:35:03.816 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=60046, state=CANCELLED, sendCnt=2, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:35:03.817 [DEBUG] [converter.ZigBeeConverterTemperature] - 847127FFFE0323E1: Failed to bind temperature measurement cluster
2021-11-22 11:35:03.817 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=60048, state=CANCELLED, sendCnt=2, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2B, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1026, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:35:03.818 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-11-22 11:35:03.818 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 847127FFFE0323E1: Initializing channel zigbee:device:01380FB1:847127fffe0323e1:847127FFFE0323E1_1_batteryalarm with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryAlarm@969fe4
2021-11-22 11:35:03.819 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
2021-11-22 11:35:03.819 [DEBUG] [onverter.ZigBeeConverterBatteryAlarm] - 847127FFFE0323E1: Initialising device battery alarm converter
2021-11-22 11:35:03.819 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2021-11-22 11:35:03.821 [DEBUG] [transaction.ZigBeeTransactionManager] - 3EC4/0: Sending ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=40028, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2C, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1029, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:35:03.821 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2021-11-22 11:35:03.822 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=2C, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1029, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]
2021-11-22 11:35:03.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=3EC4/0, profile=0000, cluster=0021, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=39, rssi=--, lqi=--, payload=2C E1 23 03 FE FF 27 71 84 01 05 04 03 39 28 D0 0D 00 6F 0D 00 01]
2021-11-22 11:35:03.824 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:35:03.826 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 847127FFFE0323E1: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=847127FFFE0323E1 queueTime=0, state=WAITING, sendCnt=0, command=BindRequest [0000/0 -> 3EC4/0, cluster=0021, TID=--, srcAddress=847127FFFE0323E1, srcEndpoint=1, bindCluster=1, dstAddrMode=3, dstAddress=000D6F000DD02839, dstEndpoint=1]]
2021-11-22 11:35:03.827 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-11-22 11:35:03.839 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=99]
2021-11-22 11:35:03.870 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
```
cdjackson commented
Please ensure you are using the latest binding as this is already resolved.
SummerSeaSun commented
I've upgraded to Milestone 3.2.0.M4 and it's working.
Thank you, BR