search

openhab / org.openhab.binding.zigbee

openHAB binding for ZigBee
Eclipse Public License 2.0
73 stars 111 forks source link

Polling aborted due to exception #813

Closed Mr-jreeves closed 7 months ago

Mr-jreeves commented 8 months ago

Outline

Was repeatably getting a Exception every 30 minutes as reported in this post:

https://community.openhab.org/t/zigbee-exception/139533/11

Configuration

openHAB 3.4.0 Build #3029 Using the Ember EM35x Coordinator on a Dual USB Nortek stick

Logs

2023-11-04 09:29:02.583 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:02.585 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:02.586 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 in 1830 seconds 2023-11-04 09:29:02.628 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000B2E7519: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=117, lastReportTime=Sat Nov 04 09:29:02 PDT 2023, implemented=true] 2023-11-04 09:29:02.628 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000B2E7519: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=117, lastReportTime=Sat Nov 04 09:29:02 PDT 2023, implemented=true] 2023-11-04 09:29:02.629 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000B2E7519: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=117, lastReportTime=Sat Nov 04 09:29:02 PDT 2023, implemented=true] 2023-11-04 09:29:02.630 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000B2E7519: Channel zigbee:device:ZigBee_Bridge:000d6f000b2e7519:000D6F000B2E7519_1_voltage updated to 11 V 2023-11-04 09:29:02.632 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B2E7519: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000b2e7519:000D6F000B2E7519_1_voltage to 11 V 2023-11-04 09:29:02.633 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2e7519 2023-11-04 09:29:02.635 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2e7519 2023-11-04 09:29:02.636 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2e7519 in 1830 seconds 2023-11-04 09:29:02.812 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=173, lastReportTime=Sat Nov 04 09:29:02 PDT 2023, implemented=true] 2023-11-04 09:29:02.812 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=173, lastReportTime=Sat Nov 04 09:29:02 PDT 2023, implemented=true] 2023-11-04 09:29:02.813 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=173, lastReportTime=Sat Nov 04 09:29:02 PDT 2023, implemented=true] 2023-11-04 09:29:02.815 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000B2D9BBD: Channel zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_current updated to 17 A 2023-11-04 09:29:02.816 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B2D9BBD: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_current to 17 A 2023-11-04 09:29:02.819 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:02.820 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:02.822 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd in 1830 seconds 2023-11-04 09:29:03.563 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=45, lastReportTime=Sat Nov 04 09:29:03 PDT 2023, implemented=true] 2023-11-04 09:29:03.563 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=45, lastReportTime=Sat Nov 04 09:29:03 PDT 2023, implemented=true] 2023-11-04 09:29:03.564 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=45, lastReportTime=Sat Nov 04 09:29:03 PDT 2023, implemented=true] 2023-11-04 09:29:03.565 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000A7BA835: Channel zigbee:device:ZigBee_Bridge:000d6f000a7ba835:000D6F000A7BA835_1_activepower updated to 4 W 2023-11-04 09:29:03.567 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000A7BA835: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000a7ba835:000D6F000A7BA835_1_activepower to 4 W 2023-11-04 09:29:03.569 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:03.570 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:03.572 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 in 1830 seconds 2023-11-04 09:29:03.816 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=123, lastReportTime=Sat Nov 04 09:29:03 PDT 2023, implemented=true] 2023-11-04 09:29:03.816 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=123, lastReportTime=Sat Nov 04 09:29:03 PDT 2023, implemented=true] 2023-11-04 09:29:03.816 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=123, lastReportTime=Sat Nov 04 09:29:03 PDT 2023, implemented=true] 2023-11-04 09:29:03.818 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000B2D9BBD: Channel zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_activepower updated to 12 W 2023-11-04 09:29:03.819 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B2D9BBD: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_activepower to 12 W 2023-11-04 09:29:03.821 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:03.824 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:03.826 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd in 1830 seconds 2023-11-04 09:29:05.444 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 24FD5B000108787A: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=1219, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.444 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 24FD5B000108787A: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=1219, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.444 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 24FD5B000108787A: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=1219, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.447 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B000108787A: Channel zigbee:device:ZigBee_Bridge:24fd5b000108787a:24FD5B000108787A_1_voltage updated to 121 V 2023-11-04 09:29:05.448 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B000108787A: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b000108787a:24FD5B000108787A_1_voltage to 121 V 2023-11-04 09:29:05.451 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b000108787a 2023-11-04 09:29:05.454 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b000108787a 2023-11-04 09:29:05.455 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b000108787a in 1830 seconds 2023-11-04 09:29:05.601 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=66, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.601 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=66, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.601 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=66, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.603 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000A7BA835: Channel zigbee:device:ZigBee_Bridge:000d6f000a7ba835:000D6F000A7BA835_1_current updated to 6 A 2023-11-04 09:29:05.604 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000A7BA835: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000a7ba835:000D6F000A7BA835_1_current to 6 A 2023-11-04 09:29:05.606 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:05.608 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:05.609 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 in 1830 seconds 2023-11-04 09:29:05.639 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000B2E7519: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=118, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.639 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000B2E7519: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=118, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.639 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000B2E7519: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=118, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.642 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000B2E7519: Channel zigbee:device:ZigBee_Bridge:000d6f000b2e7519:000D6F000B2E7519_1_voltage updated to 11 V 2023-11-04 09:29:05.643 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B2E7519: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000b2e7519:000D6F000B2E7519_1_voltage to 11 V 2023-11-04 09:29:05.646 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2e7519 2023-11-04 09:29:05.648 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2e7519 2023-11-04 09:29:05.649 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2e7519 in 1830 seconds 2023-11-04 09:29:05.812 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=172, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.812 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=172, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.812 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=172, lastReportTime=Sat Nov 04 09:29:05 PDT 2023, implemented=true] 2023-11-04 09:29:05.814 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000B2D9BBD: Channel zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_current updated to 17 A 2023-11-04 09:29:05.816 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B2D9BBD: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_current to 17 A 2023-11-04 09:29:05.817 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:05.819 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:05.820 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd in 1830 seconds 2023-11-04 09:29:06.560 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - null: Polling [zigbee:device:ZigBee_Bridge:0022a3000011cf86:0022A3000011CF86_1_batteryvoltage] channels... 2023-11-04 09:29:06.561 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=44, lastReportTime=Sat Nov 04 09:29:06 PDT 2023, implemented=true] 2023-11-04 09:29:06.561 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=44, lastReportTime=Sat Nov 04 09:29:06 PDT 2023, implemented=true] 2023-11-04 09:29:06.562 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - null: Polling zigbee:device:ZigBee_Bridge:0022a3000011cf86:0022A3000011CF86_1_batteryvoltage 2023-11-04 09:29:06.562 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=44, lastReportTime=Sat Nov 04 09:29:06 PDT 2023, implemented=true] 2023-11-04 09:29:06.563 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000A7BA835: Channel zigbee:device:ZigBee_Bridge:000d6f000a7ba835:000D6F000A7BA835_1_activepower updated to 4 W 2023-11-04 09:29:06.565 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000A7BA835: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000a7ba835:000D6F000A7BA835_1_activepower to 4 W 2023-11-04 09:29:06.564 [WARN ] [ng.zigbee.handler.ZigBeeThingHandler] - null: Polling aborted due to exception java.lang.NullPointerException: null at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1727) ~[bundleFile:?] at com.zsmartsystems.zigbee.zcl.clusters.ZclPowerConfigurationCluster.getBatteryVoltage(ZclPowerConfigurationCluster.java:762) ~[bundleFile:?] at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryVoltage.handleRefresh(ZigBeeConverterBatteryVoltage.java:111) ~[bundleFile:?] at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$2.run(ZigBeeThingHandler.java:607) [bundleFile:?] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?] 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) [?:?] 2023-11-04 09:29:06.567 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:06.568 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 2023-11-04 09:29:06.570 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000a7ba835 in 1830 seconds 2023-11-04 09:29:06.632 [DEBUG] [converter.ZigBeeConverterTemperature] - 0022A30000133E18: ZigBee attribute reports ZclAttribute [cluster=Temperature Measurement, id=0, name=Measured Value, dataType=SIGNED_16_BIT_INTEGER, lastValue=3412, lastReportTime=Sat Nov 04 09:29:06 PDT 2023, implemented=false] 2023-11-04 09:29:06.633 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 0022A30000133E18: Channel zigbee:device:ZigBee_Bridge:0022a30000133e18:0022A30000133E18_1_temperature updated to 34.12 °C 2023-11-04 09:29:06.634 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0022A30000133E18: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:0022a30000133e18:0022A30000133E18_1_temperature to 34.12 °C 2023-11-04 09:29:06.637 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:0022a30000133e18 2023-11-04 09:29:06.637 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:0022a30000133e18 2023-11-04 09:29:06.638 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:0022a30000133e18 in 1830 seconds 2023-11-04 09:29:07.823 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=126, lastReportTime=Sat Nov 04 09:29:07 PDT 2023, implemented=true] 2023-11-04 09:29:07.823 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=126, lastReportTime=Sat Nov 04 09:29:07 PDT 2023, implemented=true] 2023-11-04 09:29:07.823 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000B2D9BBD: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1291, name=Active Power, dataType=SIGNED_16_BIT_INTEGER, lastValue=126, lastReportTime=Sat Nov 04 09:29:07 PDT 2023, implemented=true] 2023-11-04 09:29:07.824 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000B2D9BBD: Channel zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_activepower updated to 12 W 2023-11-04 09:29:07.825 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B2D9BBD: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd:000D6F000B2D9BBD_1_activepower to 12 W 2023-11-04 09:29:07.826 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:07.827 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd 2023-11-04 09:29:07.828 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:000d6f000b2d9bbd in 1830 seconds 2023-11-04 09:29:08.462 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 24FD5B000108787A: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=1218, lastReportTime=Sat Nov 04 09:29:08 PDT 2023, implemented=true] 2023-11-04 09:29:08.462 [DEBUG] [ZigBeeConverterMeasurementRmsVoltage] - 24FD5B000108787A: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=1218, lastReportTime=Sat Nov 04 09:29:08 PDT 2023, implemented=true] 2023-11-04 09:29:08.462 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 24FD5B000108787A: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1285, name=RMS Voltage, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=1218, lastReportTime=Sat Nov 04 09:29:08 PDT 2023, implemented=true] 2023-11-04 09:29:08.464 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 24FD5B000108787A: Channel zigbee:device:ZigBee_Bridge:24fd5b000108787a:24FD5B000108787A_1_voltage updated to 121 V 2023-11-04 09:29:08.465 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B000108787A: Updating ZigBee channel state zigbee:device:ZigBee_Bridge:24fd5b000108787a:24FD5B000108787A_1_voltage to 121 V 2023-11-04 09:29:08.467 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:ZigBee_Bridge:24fd5b000108787a 2023-11-04 09:29:08.468 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b000108787a 2023-11-04 09:29:08.470 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:ZigBee_Bridge:24fd5b000108787a in 1830 seconds 2023-11-04 09:29:08.553 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=71, lastReportTime=Sat Nov 04 09:29:08 PDT 2023, implemented=true] 2023-11-04 09:29:08.553 [DEBUG] [ZigBeeConverterMeasurementRmsCurrent] - 000D6F000A7BA835: ZigBee attribute reports ZclAttribute [cluster=Electrical Measurement, id=1288, name=RMS Current, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=71, lastReportTime=Sat Nov 04 09:29:08 PDT 2023, implemented=true]

openhab-bot commented 8 months ago

This issue has been mentioned on openHAB Community. There might be relevant details there:

https://community.openhab.org/t/zigbee-exception/139533/13