Zigbee exception

Setup, Configuration and Use Beginners
1

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

FYI … I got this Zigbee exception … have not seen it before and only saw it once.

2022-10-01 09:40:58.708 [WARN ] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B2E7519: Polling aborted due to exception 
java.lang.ClassCastException: class com.zsmartsystems.zigbee.zcl.clusters.general.ReportAttributesCommand cannot be cast to class com.zsmartsystems.zigbee.zcl.clusters.general.ReadAttributesResponse (com.zsmartsystems.zigbee.zcl.clusters.general.ReportAttributesCommand and com.zsmartsystems.zigbee.zcl.clusters.general.ReadAttributesResponse are in unnamed module of loader org.eclipse.osgi.internal.loader.EquinoxClassLoader @1c0e690)
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1740) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclElectricalMeasurementCluster.getRmsVoltage(ZclElectricalMeasurementCluster.java:3214) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterMeasurementRmsVoltage.handleRefresh(ZigBeeConverterMeasurementRmsVoltage.java:121) ~[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) [?:?]
2

Please can you provide a debug log showing this issue so I can see the frames involved.

3

As I said, it only happened this one time.

4

Ok, if it happens again, then please provide the log and I’ll have a look. Without that I can’t really tell what caused this.

1 Like
5

Agreed. I was just noting it here in case someone else was seeing it as well.

6

Sure - the problem is that others may see this error for a completely different reason (or same reason, but different attributes on different devices) - the only way to know is the logs (sorry).

1 Like
7

Understood … do you think its better to not post this kind of occurrence and just wait until it’s repeatable and can get logs? Just trying to understand how I should handle it.

8

Good question - it’s a tough one and the answer is probably “it depends”. In many cases a debug log will be required - here for example the error is about a received frame, so without knowing what was received to cause the problem, I can’t really do a lot. In other instances it might be more self contained.

It’s not a problem (for me anyway) to post reports like this - so long as you’re prepared for the answer that logs are needed :slight_smile:

1 Like
9

Ok sounds good :slight_smile:

1 Like
10

You are not alone - I just saw this on openHAB 4.0.0 (build Build #3394), also with the Nortek.

Looks identical to me. And of course I didn’t have debug logs enabled. I’ll see if it recurs.

2023-04-13 03:17:10.692 [WARN ] [ng.zigbee.handler.ZigBeeThingHandler] - A4C138E1A1D0A026: Polling aborted due to exception
java.lang.ClassCastException: class com.zsmartsystems.zigbee.zcl.clusters.general.ReportAttributesCommand cannot be cast to class com.zsmartsystems.zigbee.zcl.clusters.general.ReadAttributesResponse (com.zsmartsystems.zigbee.zcl.clusters.general.ReportAttributesCommand and com.zsmartsystems.zigbee.zcl.clusters.general.ReadAttributesResponse are in unnamed module of loader org.eclipse.osgi.internal.loader.EquinoxClassLoader @38aa5eb0)
        at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1740) ~[?:?]
        at com.zsmartsystems.zigbee.zcl.clusters.ZclPowerConfigurationCluster.getBatteryPercentageRemaining(ZclPowerConfigurationCluster.java:804) ~[?:?]
        at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryPercent.handleRefresh(ZigBeeConverterBatteryPercent.java:114) ~[?:?]
        at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$2.run(ZigBeeThingHandler.java:636) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) ~[?:?]
        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:1136) ~[?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) ~[?:?]
        at java.lang.Thread.run(Thread.java:833) ~[?:?]
1 Like
11

Hi Chris - I saw this happening every ~30 minutes so I was able to capture the log. I can provide more log if needed but included some before and after. Not sure if it directly relates to the previous exception this thread was created for. This one is very repeatable (for now).

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]
12

Thanks.

Can you please raise this as an issue on GitHub and I’ll create a fix this evening.

13

ok posted.