Thanks for the reply!
I’ve just enabled debug logging via
log:set debug org.openhab.binding.zigbee
log:set debug com.zsmartsystems.zigbee
log:set info com.zsmartsystems.zigbee.dongle.ember.internal.ash
Although after pressing the buttons (both single taps, and tap and hold on each) the unique device name doesn’t show up in openhab.log at all.
I pressed the Sonoff switch on my desk to manually control my office light and checked the logs and found the entries for it, so I find that a bit strange.
Can you suggest anything else I can check or try to get it working?
I don’t care quite as much about the dimmer states (pressing and holding either button, although that action could be useful) I more thought the on/off button would be good to trigger certain lights on or off, or program some rules to jump through various dim levels (5/10/25/50/75/100%) using the on as a “jump up to next state” and off as "jump down to next state kind of option.
–Edit–
It does appear to be logging, here’s what the log outputs for that particular device
Line 981: 2022-03-07 20:02:00.635 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 8CF681FFFE2884D2: Node update. NWK Address=6574
Line 982: 2022-03-07 20:02:00.635 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 8CF681FFFE2884D2: Node 6574 is not updated
Line 986: 2022-03-07 20:02:00.638 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 8CF681FFFE2884D2: ZigBee attribute reports ZclAttribute [cluster=Power Configuration, id=32, name=Battery Voltage, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=30, lastReportTime=Mon Mar 07 20:02:00 MST 2022, implemented=true]
Line 987: 2022-03-07 20:02:00.638 [DEBUG] [verter.ZigBeeConverterBatteryVoltage] - 8CF681FFFE2884D2: ZigBee attribute reports ZclAttribute [cluster=Power Configuration, id=32, name=Battery Voltage, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=30, lastReportTime=Mon Mar 07 20:02:00 MST 2022, implemented=true]
Line 988: 2022-03-07 20:02:00.638 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=62, commandIdentifier=10, statusCode=SUCCESS]]
Line 988: 2022-03-07 20:02:00.638 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=62, commandIdentifier=10, statusCode=SUCCESS]]
Line 990: 2022-03-07 20:02:00.638 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=62, commandIdentifier=10, statusCode=SUCCESS]]
Line 991: 2022-03-07 20:02:00.638 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8CF681FFFE2884D2: Channel zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage updated to 3 V
Line 991: 2022-03-07 20:02:00.638 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8CF681FFFE2884D2: Channel zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage updated to 3 V
Line 991: 2022-03-07 20:02:00.638 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8CF681FFFE2884D2: Channel zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage updated to 3 V
Line 994: 2022-03-07 20:02:00.639 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Updating ZigBee channel state zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage to 3 V
Line 994: 2022-03-07 20:02:00.639 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Updating ZigBee channel state zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage to 3 V
Line 994: 2022-03-07 20:02:00.639 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Updating ZigBee channel state zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage to 3 V
Line 996: 2022-03-07 20:02:00.639 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:7e7c171ea7:8cf681fffe2884d2
Line 998: 2022-03-07 20:02:00.639 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:7e7c171ea7:8cf681fffe2884d2
Line 999: 2022-03-07 20:02:00.639 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:7e7c171ea7:8cf681fffe2884d2 in 14430 seconds
Line 1003: 2022-03-07 20:02:00.911 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 8CF681FFFE2884D2: Node update. NWK Address=6574
Line 1004: 2022-03-07 20:02:00.911 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 8CF681FFFE2884D2: Node 6574 is not updated
Line 1008: 2022-03-07 20:02:00.912 [DEBUG] [verter.ZigBeeConverterBatteryVoltage] - 8CF681FFFE2884D2: ZigBee attribute reports ZclAttribute [cluster=Power Configuration, id=33, name=Battery Percentage Remaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=87, lastReportTime=Mon Mar 07 20:02:00 MST 2022, implemented=true]
Line 1009: 2022-03-07 20:02:00.912 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 8CF681FFFE2884D2: ZigBee attribute reports ZclAttribute [cluster=Power Configuration, id=33, name=Battery Percentage Remaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=87, lastReportTime=Mon Mar 07 20:02:00 MST 2022, implemented=true]
Line 1010: 2022-03-07 20:02:00.912 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=63, commandIdentifier=10, statusCode=SUCCESS]]
Line 1010: 2022-03-07 20:02:00.912 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=63, commandIdentifier=10, statusCode=SUCCESS]]
Line 1011: 2022-03-07 20:02:00.912 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8CF681FFFE2884D2: Channel zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel updated to 43
Line 1011: 2022-03-07 20:02:00.912 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8CF681FFFE2884D2: Channel zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel updated to 43
Line 1011: 2022-03-07 20:02:00.912 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 8CF681FFFE2884D2: Channel zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel updated to 43
Line 1012: 2022-03-07 20:02:00.912 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Updating ZigBee channel state zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel to 43
Line 1012: 2022-03-07 20:02:00.912 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Updating ZigBee channel state zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel to 43
Line 1012: 2022-03-07 20:02:00.912 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Updating ZigBee channel state zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel to 43
Line 1013: 2022-03-07 20:02:00.912 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:7e7c171ea7:8cf681fffe2884d2
Line 1015: 2022-03-07 20:02:00.913 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:7e7c171ea7:8cf681fffe2884d2
Line 1016: 2022-03-07 20:02:00.913 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:7e7c171ea7:8cf681fffe2884d2 in 14430 seconds
Line 1020: 2022-03-07 20:02:01.680 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=1042, state=COMPLETE, sendCnt=1, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=62, commandIdentifier=10, statusCode=SUCCESS]]
Line 1022: 2022-03-07 20:02:01.680 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=COMPLETE, outstanding=0
Line 1024: 2022-03-07 20:02:01.681 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=770, state=WAITING, sendCnt=0, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=63, commandIdentifier=10, statusCode=SUCCESS]]
Line 1034: 2022-03-07 20:02:02.721 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=1810, state=COMPLETE, sendCnt=1, command=DefaultResponse [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=63, commandIdentifier=10, statusCode=SUCCESS]]
Line 1036: 2022-03-07 20:02:02.721 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=COMPLETE, outstanding=0
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1168: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling [zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_dimmer, zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage] channels...
Line 1169: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm
Line 1169: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm
Line 1169: 2022-03-07 20:10:17.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryalarm
Line 1171: 2022-03-07 20:10:17.047 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=--, identifiers=[62]]]
Line 1171: 2022-03-07 20:10:17.047 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=--, identifiers=[62]]]
Line 1173: 2022-03-07 20:10:17.047 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1180: 2022-03-07 20:10:27.047 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10000, state=FAILED, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1182: 2022-03-07 20:10:27.048 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=FAILED, outstanding=0
Line 1183: 2022-03-07 20:10:27.048 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10001, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1183: 2022-03-07 20:10:27.048 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10001, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1185: 2022-03-07 20:10:27.049 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1193: 2022-03-07 20:10:37.049 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20002, state=FAILED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1195: 2022-03-07 20:10:37.050 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=FAILED, outstanding=0
Line 1196: 2022-03-07 20:10:37.050 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete exceeded max retries 2
Line 1197: 2022-03-07 20:10:37.050 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20003, state=CANCELLED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1198: 2022-03-07 20:10:37.051 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20003, state=CANCELLED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=E9, identifiers=[62]]]
Line 1199: 2022-03-07 20:10:37.051 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel
Line 1199: 2022-03-07 20:10:37.051 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel
Line 1199: 2022-03-07 20:10:37.051 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batterylevel
Line 1201: 2022-03-07 20:10:37.051 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
Line 1205: 2022-03-07 20:10:37.051 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=--, identifiers=[33]]]
Line 1205: 2022-03-07 20:10:37.051 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=--, identifiers=[33]]]
Line 1207: 2022-03-07 20:10:37.052 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1220: 2022-03-07 20:10:47.053 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10001, state=FAILED, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1222: 2022-03-07 20:10:47.053 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=FAILED, outstanding=0
Line 1223: 2022-03-07 20:10:47.053 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1223: 2022-03-07 20:10:47.053 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1225: 2022-03-07 20:10:47.054 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10002, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1263: 2022-03-07 20:10:57.054 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20003, state=FAILED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1265: 2022-03-07 20:10:57.055 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=FAILED, outstanding=0
Line 1266: 2022-03-07 20:10:57.055 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete exceeded max retries 2
Line 1267: 2022-03-07 20:10:57.055 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20004, state=CANCELLED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1268: 2022-03-07 20:10:57.055 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20004, state=CANCELLED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EA, identifiers=[33]]]
Line 1269: 2022-03-07 20:10:57.055 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage
Line 1269: 2022-03-07 20:10:57.055 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage
Line 1269: 2022-03-07 20:10:57.055 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 8CF681FFFE2884D2: Polling zigbee:device:7e7c171ea7:8cf681fffe2884d2:8CF681FFFE2884D2_1_batteryvoltage
Line 1271: 2022-03-07 20:10:57.055 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
Line 1275: 2022-03-07 20:10:57.056 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=--, identifiers=[32]]]
Line 1275: 2022-03-07 20:10:57.056 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=--, identifiers=[32]]]
Line 1277: 2022-03-07 20:10:57.056 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1328: 2022-03-07 20:11:07.056 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10000, state=FAILED, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1330: 2022-03-07 20:11:07.057 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=FAILED, outstanding=0
Line 1331: 2022-03-07 20:11:07.057 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10001, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1331: 2022-03-07 20:11:07.057 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10001, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1333: 2022-03-07 20:11:07.057 [DEBUG] [transaction.ZigBeeTransactionManager] - 6574/1: Sending ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=10001, state=WAITING, sendCnt=1, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1384: 2022-03-07 20:11:17.058 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20002, state=FAILED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1386: 2022-03-07 20:11:17.059 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete, state=FAILED, outstanding=0
Line 1387: 2022-03-07 20:11:17.059 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete exceeded max retries 2
Line 1388: 2022-03-07 20:11:17.059 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20003, state=CANCELLED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1389: 2022-03-07 20:11:17.059 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=8CF681FFFE2884D2 queueTime=20003, state=CANCELLED, sendCnt=2, command=ReadAttributesCommand [Power Configuration: 0000/0 -> 6574/1, cluster=0001, TID=EC, identifiers=[32]]]
Line 1391: 2022-03-07 20:11:17.059 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 8CF681FFFE2884D2: transactionComplete but not outstanding, state=CANCELLED, outstanding=0