2021-01-01 20:28:27.012 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received SUCCESS in response to 60
2021-01-01 20:29:20.470 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.thing.internal.CommunicationManager@1cddf1b' takes more than 5000ms.
2021-01-01 20:29:23.530 [DEBUG] [converter.ZigBeeConverterIlluminance] - 00158D0004516178: ZigBee attribute reports ZclAttribute [cluster=Illuminance Measurement, id=0, name=Measured Value, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=31, lastReportTime=Fri Jan 01 20:29:23 CET 2021, implemented=false]
2021-01-01 20:29:23.533 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0004516178: Channel zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_illuminance updated to 0.007163550132134766
2021-01-01 20:29:23.535 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Updating ZigBee channel state zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_illuminance to 0.007163550132134766
2021-01-01 20:29:23.539 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:29:23.541 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:29:23.544 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00158d0004516178 in 1830 seconds
2021-01-01 20:29:23.572 [DEBUG] [l.converter.ZigBeeConverterOccupancy] - 00158D0004516178: ZigBee attribute reports ZclAttribute [cluster=Occupancy Sensing, id=0, name=Occupancy, dataType=BITMAP_8_BIT, lastValue=1, lastReportTime=Fri Jan 01 20:29:23 CET 2021, implemented=false]
2021-01-01 20:29:23.576 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0004516178: Channel zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_occupancy updated to ON
2021-01-01 20:29:23.579 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Updating ZigBee channel state zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_occupancy to ON
2021-01-01 20:29:23.589 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:29:23.593 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:29:23.595 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00158d0004516178 in 1830 seconds
2021-01-01 20:30:06.640 [DEBUG] [converter.ZigBeeConverterIlluminance] - 00158D0004514C4B: ZigBee attribute reports ZclAttribute [cluster=Illuminance Measurement, id=0, name=Measured Value, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=71, lastReportTime=Fri Jan 01 20:30:06 CET 2021, implemented=false]
2021-01-01 20:30:06.643 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0004514C4B: Channel zigbee:device:01380F03:00158d0004514c4b:00158D0004514C4B_1_illuminance updated to 0.016482719722096473
2021-01-01 20:30:06.645 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Updating ZigBee channel state zigbee:device:01380F03:00158d0004514c4b:00158D0004514C4B_1_illuminance to 0.016482719722096473
2021-01-01 20:30:06.649 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00158d0004514c4b
2021-01-01 20:30:06.654 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00158d0004514c4b
2021-01-01 20:30:06.655 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00158d0004514c4b in 1830 seconds
2021-01-01 20:30:06.679 [DEBUG] [l.converter.ZigBeeConverterOccupancy] - 00158D0004514C4B: ZigBee attribute reports ZclAttribute [cluster=Occupancy Sensing, id=0, name=Occupancy, dataType=BITMAP_8_BIT, lastValue=1, lastReportTime=Fri Jan 01 20:30:06 CET 2021, implemented=false]
2021-01-01 20:30:06.681 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0004514C4B: Channel zigbee:device:01380F03:00158d0004514c4b:00158D0004514C4B_1_occupancy updated to ON
2021-01-01 20:30:06.684 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Updating ZigBee channel state zigbee:device:01380F03:00158d0004514c4b:00158D0004514C4B_1_occupancy to ON
2021-01-01 20:30:06.687 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00158d0004514c4b
2021-01-01 20:30:06.691 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00158d0004514c4b
2021-01-01 20:30:06.694 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00158d0004514c4b in 1830 seconds
2021-01-01 20:30:10.992 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer --> 60 [PercentType]
2021-01-01 20:30:11.018 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer waiting for response to 60
2021-01-01 20:30:11.038 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer --> 60 [PercentType]
2021-01-01 20:30:11.057 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature --> 50 [PercentType]
2021-01-01 20:30:11.074 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer waiting for response to 60
2021-01-01 20:30:11.101 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature --> 50 [PercentType]
2021-01-01 20:30:11.184 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received SUCCESS in response to 60
2021-01-01 20:30:12.013 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer --> 60 [PercentType]
2021-01-01 20:30:12.016 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer waiting for response to 60
2021-01-01 20:30:12.043 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature --> 50 [PercentType]
2021-01-01 20:30:12.043 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer --> 60 [PercentType]
2021-01-01 20:30:12.063 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer waiting for response to 60
2021-01-01 20:30:12.091 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature --> 50 [PercentType]
2021-01-01 20:30:12.159 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received SUCCESS in response to 60
2021-01-01 20:30:12.325 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer received SUCCESS in response to 60
2021-01-01 20:30:19.973 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer received SUCCESS in response to 60
2021-01-01 20:30:20.596 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer --> 30 [PercentType]
2021-01-01 20:30:20.610 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer waiting for response to 30
2021-01-01 20:30:20.641 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature --> 65 [PercentType]
2021-01-01 20:30:20.650 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer --> 15 [PercentType]
2021-01-01 20:30:20.657 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer waiting for response to 15
2021-01-01 20:30:20.677 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature --> 65 [PercentType]
2021-01-01 20:30:20.726 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer received SUCCESS in response to 30
2021-01-01 20:30:20.830 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received SUCCESS in response to 15
2021-01-01 20:30:20.883 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=144, lastReportTime=Fri Jan 01 20:30:20 CET 2021, implemented=true]
2021-01-01 20:30:20.910 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=149, lastReportTime=Fri Jan 01 20:30:20 CET 2021, implemented=true]
2021-01-01 20:30:20.913 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 59
2021-01-01 20:30:20.916 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 59
2021-01-01 20:30:20.927 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:20.930 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:20.943 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:30:21.076 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=219, lastReportTime=Fri Jan 01 20:30:21 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:30:21.079 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature updated to 51
2021-01-01 20:30:21.082 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature to 51
2021-01-01 20:30:21.094 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:30:21.098 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:30:21.100 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:30:21.380 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=71, lastReportTime=Fri Jan 01 20:30:21 CET 2021, implemented=true]
2021-01-01 20:30:21.402 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=229, lastReportTime=Fri Jan 01 20:30:21 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:30:21.405 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature updated to 57
2021-01-01 20:30:21.410 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature to 57
2021-01-01 20:30:21.417 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:30:21.431 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:30:21.435 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:30:21.631 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=237, lastReportTime=Fri Jan 01 20:30:21 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:30:21.634 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature updated to 60
2021-01-01 20:30:21.639 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature to 60
2021-01-01 20:30:21.645 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:21.655 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=78, lastReportTime=Fri Jan 01 20:30:21 CET 2021, implemented=true]
2021-01-01 20:30:21.658 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 31
2021-01-01 20:30:21.655 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:21.661 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 31
2021-01-01 20:30:21.664 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:30:21.677 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:21.681 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:21.683 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:30:25.671 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=247, lastReportTime=Fri Jan 01 20:30:25 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:30:25.675 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature updated to 65
2021-01-01 20:30:25.678 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature to 65
2021-01-01 20:30:25.682 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:25.685 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:25.694 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=76, lastReportTime=Fri Jan 01 20:30:25 CET 2021, implemented=true]
2021-01-01 20:30:25.696 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 30
2021-01-01 20:30:25.699 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 30
2021-01-01 20:30:25.703 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:30:25.707 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:25.711 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:30:25.714 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:30:28.126 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Polling [zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer, zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature] channels...
2021-01-01 20:30:28.365 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=247, lastReportTime=Fri Jan 01 20:30:28 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:30:28.368 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature updated to 65
2021-01-01 20:30:28.371 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature to 65
2021-01-01 20:30:28.375 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:30:28.378 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:30:28.381 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:30:28.387 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=38, lastReportTime=Fri Jan 01 20:30:28 CET 2021, implemented=true]
2021-01-01 20:30:42.560 [DEBUG] [converter.ZigBeeConverterIlluminance] - 00158D0004516178: ZigBee attribute reports ZclAttribute [cluster=Illuminance Measurement, id=0, name=Measured Value, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=11, lastReportTime=Fri Jan 01 20:30:42 CET 2021, implemented=false]
2021-01-01 20:30:42.562 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0004516178: Channel zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_illuminance updated to 0.0025360539605232546
2021-01-01 20:30:42.564 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Updating ZigBee channel state zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_illuminance to 0.0025360539605232546
2021-01-01 20:30:42.567 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:30:42.573 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:30:42.575 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00158d0004516178 in 1830 seconds
2021-01-01 20:30:42.584 [DEBUG] [l.converter.ZigBeeConverterOccupancy] - 00158D0004516178: ZigBee attribute reports ZclAttribute [cluster=Occupancy Sensing, id=0, name=Occupancy, dataType=BITMAP_8_BIT, lastValue=1, lastReportTime=Fri Jan 01 20:30:42 CET 2021, implemented=false]
2021-01-01 20:30:42.586 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00158D0004516178: Channel zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_occupancy updated to ON
2021-01-01 20:30:42.589 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Updating ZigBee channel state zigbee:device:01380F03:00158d0004516178:00158D0004516178_1_occupancy to ON
2021-01-01 20:30:42.592 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:30:42.601 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00158d0004516178
2021-01-01 20:30:42.604 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00158d0004516178 in 1830 seconds
2021-01-01 20:30:43.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer --> 30 [PercentType]
2021-01-01 20:30:43.586 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer waiting for response to 30
2021-01-01 20:30:43.619 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer --> 15 [PercentType]
2021-01-01 20:30:43.621 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature --> 65 [PercentType]
2021-01-01 20:30:43.624 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer waiting for response to 15
2021-01-01 20:30:43.666 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature --> 65 [PercentType]
2021-01-01 20:30:43.736 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received SUCCESS in response to 15
2021-01-01 20:30:43.764 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer received SUCCESS in response to 30
2021-01-01 20:31:11.260 [WARN ] [e.ember.internal.ash.AshFrameHandler] - ASH: ERROR received (code 81). Disconnecting.
2021-01-01 20:31:11.277 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2021-01-01 20:31:11.303 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to OFFLINE.
2021-01-01 20:31:11.315 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is unknown or not online.
2021-01-01 20:31:11.320 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Polling stopped
2021-01-01 20:31:11.322 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to OFFLINE.
2021-01-01 20:31:11.326 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to OFFLINE.
2021-01-01 20:31:11.328 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is unknown or not online.
2021-01-01 20:31:11.330 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is unknown or not online.
2021-01-01 20:31:11.332 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Polling stopped
2021-01-01 20:31:11.334 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Polling stopped
2021-01-01 20:31:11.337 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to OFFLINE.
2021-01-01 20:31:11.340 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to OFFLINE.
2021-01-01 20:31:11.340 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to OFFLINE.
2021-01-01 20:31:11.345 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is unknown or not online.
2021-01-01 20:31:11.343 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is unknown or not online.
2021-01-01 20:31:11.351 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Polling stopped
2021-01-01 20:31:11.350 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is unknown or not online.
2021-01-01 20:31:11.353 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Polling stopped
2021-01-01 20:31:11.355 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Polling stopped
2021-01-01 20:31:11.358 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to OFFLINE.
2021-01-01 20:31:11.366 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is unknown or not online.
2021-01-01 20:31:11.368 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Polling stopped
2021-01-01 20:31:12.358 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2021-01-01 20:31:12.361 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2021-01-01 20:31:12.714 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:31:12.747 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000DD02780: ZigBee saving network state complete.
2021-01-01 20:31:12.943 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:31:13.054 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004514C4B: ZigBee saving network state complete.
2021-01-01 20:31:13.152 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:31:13.260 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee saving network state complete.
2021-01-01 20:31:13.281 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:31:13.345 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:31:13.477 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2021-01-01 20:31:13.498 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2021-01-01 20:31:13.504 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:7a2c, EPAN:994686C23E8C0C13, Channel:11
2021-01-01 20:31:13.507 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2021-01-01 20:31:13.510 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to UNKNOWN.
2021-01-01 20:31:13.511 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2021-01-01 20:31:13.513 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is unknown or not online.
2021-01-01 20:31:13.515 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to UNKNOWN.
2021-01-01 20:31:13.518 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is unknown or not online.
2021-01-01 20:31:13.520 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to UNKNOWN.
2021-01-01 20:31:13.520 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to UNKNOWN.
2021-01-01 20:31:13.522 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is unknown or not online.
2021-01-01 20:31:13.522 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is unknown or not online.
2021-01-01 20:31:13.525 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to UNKNOWN.
2021-01-01 20:31:13.527 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is unknown or not online.
2021-01-01 20:31:13.523 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to UNKNOWN.
2021-01-01 20:31:13.523 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to UNKNOWN.
2021-01-01 20:31:13.531 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is unknown or not online.
2021-01-01 20:31:13.531 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is unknown or not online.
2021-01-01 20:31:14.514 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2021-01-01 20:31:14.517 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2021-01-01 20:31:14.521 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2021-01-01 20:31:14.872 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee reading network state complete.
2021-01-01 20:31:14.920 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000DD02780: ZigBee reading network state complete.
2021-01-01 20:31:15.105 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee reading network state complete.
2021-01-01 20:31:15.252 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004514C4B: ZigBee reading network state complete.
2021-01-01 20:31:15.386 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee reading network state complete.
2021-01-01 20:31:15.532 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee reading network state complete.
2021-01-01 20:31:15.567 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee reading network state complete.
2021-01-01 20:31:15.728 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee reading network state complete.
2021-01-01 20:31:15.735 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
2021-01-01 20:31:15.779 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2021-01-01 20:31:19.790 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400
2021-01-01 20:31:19.817 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_11, PanID=31276, EPanId=994686C23E8C0C13
2021-01-01 20:31:19.819 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_INSECURE
2021-01-01 20:31:20.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee initialise done. channel=CHANNEL_11, PanId=31276  EPanId=994686C23E8C0C13
2021-01-01 20:31:20.130 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Starting ZigBee device discovery
2021-01-01 20:31:20.135 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:20.142 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:31:20.120 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:31:20.147 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:31:20.153 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:31:20.158 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Starting ZigBee device discovery
2021-01-01 20:31:20.161 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:20.180 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D0004514C4B, NWK=D0F4, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:31:20.185 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:31:20.190 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004514C4B: Starting ZigBee device discovery
2021-01-01 20:31:20.193 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004514C4B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:20.196 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Start initialising ZigBee Thing handler
2021-01-01 20:31:20.198 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:31:20.200 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00124B001EFE4FEE, NWK=70EF, Type=ROUTER, endpoints=[11, 13]]
2021-01-01 20:31:20.202 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery start
2021-01-01 20:31:20.200 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery start
2021-01-01 20:31:20.207 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using basic cluster on endpoint D0F4/1
2021-01-01 20:31:20.211 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using OTA cluster on endpoint D0F4/1
2021-01-01 20:31:20.212 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Starting ZigBee device discovery
2021-01-01 20:31:20.212 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D0004516178, NWK=6B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:31:20.214 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Start initialising ZigBee Thing handler
2021-01-01 20:31:20.208 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using basic cluster on endpoint D0F4/1
2021-01-01 20:31:20.217 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Starting ZigBee device discovery
2021-01-01 20:31:20.217 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery start
2021-01-01 20:31:20.219 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:20.220 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:20.223 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery using basic cluster on endpoint 70EF/11
2021-01-01 20:31:20.226 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery start
2021-01-01 20:31:20.226 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Start initialising ZigBee Thing handler
2021-01-01 20:31:20.229 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery start
2021-01-01 20:31:20.229 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D0004232414, NWK=0984, endpoints=[]]
2021-01-01 20:31:20.231 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: Node doesn't support OTA cluster
2021-01-01 20:31:20.232 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery start
2021-01-01 20:31:20.260 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using basic cluster on endpoint 6B4D/1
2021-01-01 20:31:20.232 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using basic cluster on endpoint 6B4D/1
2021-01-01 20:31:20.260 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery using basic cluster on endpoint 70EF/11
2021-01-01 20:31:20.268 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using OTA cluster on endpoint 6B4D/1
2021-01-01 20:31:20.268 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x0000, modelId=GL-C-007, zigbee_networkaddress=28911, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20131206, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=GLEDOPTO, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=1, zigbee_neighbors=[], zigbee_applicationVersion=1, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:31:20.271 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=ONLINE
2021-01-01 20:31:20.272 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D00040221F8, NWK=7B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:31:20.273 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Checking endpoint 11 channels
2021-01-01 20:31:20.287 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Start initialising ZigBee Thing handler
2021-01-01 20:31:20.290 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:31:20.293 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:31:20.276 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-19
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeAdded(ZigBeeThingHandler.java:815) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$7.run(ZigBeeNetworkManager.java:1333) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:31:20.304 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:31:20.307 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], zigbee_applicationVersion=3, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:31:20.311 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Checking endpoint 1 channels
2021-01-01 20:31:20.340 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Dynamically created 1 channels
2021-01-01 20:31:20.345 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Device initialization will be skipped as the device is already initialized
2021-01-01 20:31:20.483 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:31:20.507 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to ONLINE.
2021-01-01 20:31:20.510 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:31:20.514 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to ONLINE.
2021-01-01 20:31:20.516 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:31:20.522 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to ONLINE.
2021-01-01 20:31:20.523 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000DD02780: ZigBee saving network state complete.
2021-01-01 20:31:20.527 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:31:20.583 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:31:20.585 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:31:20.588 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:31:20.606 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Starting ZigBee device discovery
2021-01-01 20:31:20.610 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:20.615 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:31:20.618 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:31:20.650 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:31:20.751 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004514C4B: ZigBee saving network state complete.
2021-01-01 20:31:20.840 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:31:20.939 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee saving network state complete.
2021-01-01 20:31:20.958 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:31:21.015 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:31:21.103 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: Node doesn't support OTA cluster
2021-01-01 20:31:21.105 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x0000, modelId=GL-C-007, zigbee_networkaddress=28911, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20131206, zigbee_zclversion=1, vendor=GLEDOPTO, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=1, zigbee_applicationVersion=1}
2021-01-01 20:31:21.108 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'GLEDOPTO GL-C-007'
2021-01-01 20:31:21.145 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:31:21.211 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:31:21.218 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:31:21.242 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Starting ZigBee device discovery
2021-01-01 20:31:21.245 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:21.250 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:31:21.252 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:31:21.440 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:31:21.553 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:31:22.080 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:31:22.087 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:31:22.092 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:31:22.452 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:31:24.674 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:31:24.694 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:31:24.697 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Starting ZigBee device discovery
2021-01-01 20:31:24.700 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:31:24.705 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:31:24.707 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:31:25.026 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:31:25.164 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:31:25.167 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:31:25.170 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:31:25.542 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:31:25.609 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 00124B001EFE4FEE: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:31:25.611 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 00124B001EFE4FEE: Removing channel zigbee:switch_level in favor of zigbee:color_color
2021-01-01 20:31:25.613 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Checking endpoint 13 channels
2021-01-01 20:31:25.624 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Dynamically created 2 channels
2021-01-01 20:31:25.648 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Device initialization will be skipped as the device is already initialized
2021-01-01 20:31:25.858 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:31:25.861 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:31:25.863 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:31:26.229 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:31:27.082 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=153, lastReportTime=Fri Jan 01 20:31:27 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:31:27.084 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature updated to 0
2021-01-01 20:31:27.088 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature to 0
2021-01-01 20:31:27.567 [WARN ] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: Cannot determine whether device supports RGB color. Assuming it supports HUE/SAT
2021-01-01 20:31:27.646 [DEBUG] [nverter.config.ZclLevelControlConfig] - 70EF/11: Unable to get supported attributes for Level Control.
2021-01-01 20:31:27.737 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:31:27 CET 2021, implemented=false]
2021-01-01 20:31:27.739 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0,0,100
2021-01-01 20:31:27.742 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0,0,100
2021-01-01 20:31:28.203 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=0, name=Current Hue, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=0, lastReportTime=Fri Jan 01 20:31:28 CET 2021, implemented=false]
2021-01-01 20:31:28.204 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=0, name=Current Hue, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=0, lastReportTime=Fri Jan 01 20:31:28 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:31:28.653 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=1, name=Current Saturation, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Fri Jan 01 20:31:28 CET 2021, implemented=false]
2021-01-01 20:31:28.653 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=1, name=Current Saturation, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Fri Jan 01 20:31:28 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:31:29.157 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=3, lastReportTime=Fri Jan 01 20:31:29 CET 2021, implemented=false]
2021-01-01 20:31:29.593 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=8, name=Color Mode, dataType=ENUMERATION_8_BIT, lastValue=0, lastReportTime=Fri Jan 01 20:31:29 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:31:29.593 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=8, name=Color Mode, dataType=ENUMERATION_8_BIT, lastValue=0, lastReportTime=Fri Jan 01 20:31:29 CET 2021, implemented=false]
2021-01-01 20:31:29.595 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Channel initialisation complete
2021-01-01 20:31:29.596 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature updated to UNDEF
2021-01-01 20:31:29.597 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Setting ONLINE/OFFLINE timeout interval to: 14430
2021-01-01 20:31:29.598 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature to UNDEF
2021-01-01 20:31:29.599 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:29.601 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:29.603 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:31:29.984 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Error getting binding table
2021-01-01 20:31:29.990 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Polling initialised at 7813115ms
2021-01-01 20:31:29.992 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Done initialising ZigBee Thing handler
2021-01-01 20:31:29.995 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:29.995 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Start initialising ZigBee Thing handler
2021-01-01 20:31:29.999 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:29.999 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to ONLINE.
2021-01-01 20:31:30.001 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:31:30.002 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:31:30.002 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:31:30.007 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:31:30.011 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:31:30.015 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.015 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:31:30.017 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.018 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:31:30.021 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.023 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.025 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:31:30.025 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Checking endpoint 3 channels
2021-01-01 20:31:30.027 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0,0,100
2021-01-01 20:31:30.029 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0,0,100
2021-01-01 20:31:30.032 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.040 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.042 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:31:30.044 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0,0,1
2021-01-01 20:31:30.047 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0,0,1
2021-01-01 20:31:30.051 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.053 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.053 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00AEC851: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:31:30.055 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:31:30.058 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Dynamically created 2 channels
2021-01-01 20:31:30.060 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0.0,100.0,1
2021-01-01 20:31:30.063 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0.0,100.0,1
2021-01-01 20:31:30.071 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Device initialization will be skipped as the device is already initialized
2021-01-01 20:31:30.073 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.075 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:31:30.077 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:31:30.226 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=247, lastReportTime=Fri Jan 01 20:31:30 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:31:30.229 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature updated to 65
2021-01-01 20:31:30.237 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: Level control initialized as server
2021-01-01 20:31:30.239 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature to 65
2021-01-01 20:31:30.327 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:31:30 CET 2021, implemented=false]
2021-01-01 20:31:30.332 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 100
2021-01-01 20:31:30.334 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 100
2021-01-01 20:31:30.344 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:31:31.172 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Channel initialisation complete
2021-01-01 20:31:31.175 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Setting ONLINE/OFFLINE timeout interval to: 1830
2021-01-01 20:31:31.177 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.178 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.180 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.182 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:31:31.324 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Polling initialised at 934074ms
2021-01-01 20:31:31.327 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Done initialising ZigBee Thing handler
2021-01-01 20:31:31.329 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Start initialising ZigBee Thing handler
2021-01-01 20:31:31.330 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.332 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:31:31.335 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.335 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:31:31.338 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:31:31.340 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.341 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=38, lastReportTime=Fri Jan 01 20:31:30 CET 2021, implemented=true]
2021-01-01 20:31:31.341 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:31:31.342 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.343 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 15
2021-01-01 20:31:31.344 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:31:31.345 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 15
2021-01-01 20:31:31.345 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:31:31.349 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.357 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Checking endpoint 3 channels
2021-01-01 20:31:31.373 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:31.379 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:31:31.386 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00ABA09B: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:31:31.389 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Dynamically created 2 channels
2021-01-01 20:31:31.392 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Device initialization will be skipped as the device is already initialized
2021-01-01 20:31:31.470 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=247, lastReportTime=Fri Jan 01 20:31:31 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:31:31.478 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: Level control initialized as server
2021-01-01 20:31:31.478 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature updated to 65
2021-01-01 20:31:31.481 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature to 65
2021-01-01 20:31:31.604 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:31:31 CET 2021, implemented=false]
2021-01-01 20:31:31.607 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 100
2021-01-01 20:31:31.609 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 100
2021-01-01 20:31:31.714 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:31:32.318 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Channel initialisation complete
2021-01-01 20:31:32.321 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Setting ONLINE/OFFLINE timeout interval to: 1830
2021-01-01 20:31:32.324 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.326 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.328 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.349 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:31:32.447 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Polling initialised at 947204ms
2021-01-01 20:31:32.449 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Done initialising ZigBee Thing handler
2021-01-01 20:31:32.452 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node has not finished discovery
2021-01-01 20:31:32.453 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.459 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.462 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:31:32.464 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.465 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=76, lastReportTime=Fri Jan 01 20:31:31 CET 2021, implemented=true]
2021-01-01 20:31:32.467 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.467 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 30
2021-01-01 20:31:32.469 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:31:32.471 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 30
2021-01-01 20:31:32.476 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.484 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:32.489 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:31:32.791 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:31:35.654 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:31:35 CET 2021, implemented=false]
2021-01-01 20:31:35.656 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 30
2021-01-01 20:31:35.658 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 30
2021-01-01 20:31:35.661 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:35.663 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:35.664 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:31:35.675 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=76, lastReportTime=Fri Jan 01 20:31:35 CET 2021, implemented=true]
2021-01-01 20:31:35.677 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 30
2021-01-01 20:31:35.680 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 30
2021-01-01 20:31:35.683 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:35.687 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:31:35.689 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:31:37.373 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:31:37 CET 2021, implemented=false]
2021-01-01 20:31:37.375 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 15
2021-01-01 20:31:37.379 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 15
2021-01-01 20:31:37.382 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:37.385 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:37.387 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:31:37.394 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=38, lastReportTime=Fri Jan 01 20:31:37 CET 2021, implemented=true]
2021-01-01 20:31:37.396 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 15
2021-01-01 20:31:37.398 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 15
2021-01-01 20:31:37.401 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:37.404 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:31:37.406 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:31:39.134 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using OTA cluster on endpoint D0F4/1
2021-01-01 20:31:39.293 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using OTA cluster on endpoint 6B4D/1
2021-01-01 20:31:41.222 [DEBUG] [onverter.config.ZclOnOffSwitchConfig] - 7B4D/1: Unable to get supported attributes for On/Off.
2021-01-01 20:31:48.569 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: Could not get OTA firmware version from device
2021-01-01 20:31:48.571 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=53492, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_neighbors=[], zigbee_applicationVersion=5, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:31:48.574 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Checking endpoint 1 channels
2021-01-01 20:31:48.707 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: Could not get OTA firmware version from device
2021-01-01 20:31:48.711 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=27469, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_neighbors=[], zigbee_applicationVersion=5, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:31:48.714 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Checking endpoint 1 channels
2021-01-01 20:31:58.307 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=C7E4, endpoints=[]]
2021-01-01 20:31:58.315 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=C7E4, endpoints=[]]
2021-01-01 20:31:58.319 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-22
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:31:58.318 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-16
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:31:58.573 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:32:00.317 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=C7E4, endpoints=[]]
2021-01-01 20:32:00.318 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=C7E4, endpoints=[]]
2021-01-01 20:32:00.321 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-25
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:00.321 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-20
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:00.593 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:32:01.224 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:32:01.229 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_applicationVersion=3}
2021-01-01 20:32:01.235 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'LUMI lumi.sensor_magnet.aq2'
2021-01-01 20:32:01.286 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:01.287 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:01.299 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Node updated - ZigBeeNode [state=ONLINE, IEEE=00124B001EFE4FEE, NWK=70EF, Type=ROUTER, endpoints=[11, 13]]
2021-01-01 20:32:01.300 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Node updated - ZigBeeNode [state=ONLINE, IEEE=00124B001EFE4FEE, NWK=70EF, Type=ROUTER, endpoints=[11, 13]]
2021-01-01 20:32:01.309 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Starting ZigBee device discovery
2021-01-01 20:32:01.314 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:01.333 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery start
2021-01-01 20:32:01.339 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery using basic cluster on endpoint 70EF/11
2021-01-01 20:32:01.596 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:32:01.734 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:01.735 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:01.853 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:02.025 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:32:02.217 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: Node doesn't support OTA cluster
2021-01-01 20:32:02.223 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x0000, modelId=GL-C-007, zigbee_networkaddress=28911, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20131206, zigbee_zclversion=1, vendor=GLEDOPTO, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=1, zigbee_applicationVersion=1}
2021-01-01 20:32:02.229 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'GLEDOPTO GL-C-007'
2021-01-01 20:32:02.232 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:32:02.655 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:32:12.880 [WARN ] [e.ember.internal.ash.AshFrameHandler] - ASH: ERROR received (code 81). Disconnecting.
2021-01-01 20:32:12.896 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2021-01-01 20:32:12.909 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to OFFLINE.
2021-01-01 20:32:12.912 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is unknown or not online.
2021-01-01 20:32:12.914 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Polling stopped
2021-01-01 20:32:13.437 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer --> 30 [PercentType]
2021-01-01 20:32:13.447 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer waiting for response to 30
2021-01-01 20:32:13.453 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer --> 15 [PercentType]
2021-01-01 20:32:13.472 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Command for channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature --> 65 [PercentType]
2021-01-01 20:32:13.484 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer waiting for response to 15
2021-01-01 20:32:13.503 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Command for channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature --> 65 [PercentType]
2021-01-01 20:32:13.907 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2021-01-01 20:32:13.910 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2021-01-01 20:32:13.998 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:14.021 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000DD02780: ZigBee saving network state complete.
2021-01-01 20:32:14.116 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:32:14.215 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004514C4B: ZigBee saving network state complete.
2021-01-01 20:32:14.295 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:32:14.378 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee saving network state complete.
2021-01-01 20:32:14.399 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:32:14.450 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:32:14.558 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2021-01-01 20:32:14.562 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: Could not get OTA firmware version from device
2021-01-01 20:32:14.564 [WARN ] [igbee.app.iasclient.ZclIasZoneClient] - 6B4D/1: IAS CIE address is NOT confirmed null
2021-01-01 20:32:14.564 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=53492, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_applicationVersion=5}
2021-01-01 20:32:14.576 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004514C4B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'LUMI lumi.sensor_motion.aq2'
2021-01-01 20:32:14.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to ONLINE.
2021-01-01 20:32:14.580 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:14.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to OFFLINE.
2021-01-01 20:32:14.583 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is unknown or not online.
2021-01-01 20:32:14.584 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to OFFLINE.
2021-01-01 20:32:14.587 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received TIMEOUT in response to 15
2021-01-01 20:32:14.587 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to OFFLINE.
2021-01-01 20:32:14.588 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer received TIMEOUT in response to 30
2021-01-01 20:32:14.588 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to ONLINE.
2021-01-01 20:32:14.590 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: Could not get OTA firmware version from device
2021-01-01 20:32:14.591 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received ERROR in response to 15
2021-01-01 20:32:14.593 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=27469, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_applicationVersion=5}
2021-01-01 20:32:14.591 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer received ERROR in response to 30
2021-01-01 20:32:14.594 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer received SUCCESS in response to 15
2021-01-01 20:32:14.596 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'LUMI lumi.sensor_motion.aq2'
2021-01-01 20:32:14.597 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Exception creating channels 
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.updateConfiguration(ZigBeeConverterSwitchOnoff.java:265) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:409) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:14.594 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:14.601 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is unknown or not online.
2021-01-01 20:32:14.604 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to OFFLINE.
2021-01-01 20:32:14.605 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to ONLINE.
2021-01-01 20:32:14.605 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to OFFLINE.
2021-01-01 20:32:14.606 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is unknown or not online.
2021-01-01 20:32:14.607 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:14.596 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer received SUCCESS in response to 30
2021-01-01 20:32:14.612 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to OFFLINE.
2021-01-01 20:32:14.614 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is unknown or not online.
2021-01-01 20:32:14.578 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-2
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.initialise(ZclIasZoneClient.java:230) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.appStartup(ZclIasZoneClient.java:192) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeEndpoint.addApplication(ZigBeeEndpoint.java:408) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZigBeeIasCieExtension.nodeAdded(ZigBeeIasCieExtension.java:55) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$7.run(ZigBeeNetworkManager.java:1333) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:14.616 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Polling stopped
2021-01-01 20:32:14.623 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is unknown or not online.
2021-01-01 20:32:14.586 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is unknown or not online.
2021-01-01 20:32:14.626 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Polling stopped
2021-01-01 20:32:14.653 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2021-01-01 20:32:14.575 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclIasZoneCluster.getZoneType(ZclIasZoneCluster.java:296) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIas.supportsIasChannel(ZigBeeConverterIas.java:143) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasCieSystem.getChannel(ZigBeeConverterIasCieSystem.java:38) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl.getChannels(ZigBeeChannelConverterFactoryImpl.java:83) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:288) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:14.658 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:7a2c, EPAN:994686C23E8C0C13, Channel:11
2021-01-01 20:32:14.662 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2021-01-01 20:32:14.664 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Start initialising ZigBee Thing handler
2021-01-01 20:32:14.574 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclIasZoneCluster.getZoneType(ZclIasZoneCluster.java:296) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIas.supportsIasChannel(ZigBeeConverterIas.java:143) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasCieSystem.getChannel(ZigBeeConverterIasCieSystem.java:38) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl.getChannels(ZigBeeChannelConverterFactoryImpl.java:83) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:288) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:14.667 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Start initialising ZigBee Thing handler
2021-01-01 20:32:14.667 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to UNKNOWN.
2021-01-01 20:32:14.667 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to UNKNOWN.
2021-01-01 20:32:14.669 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to UNKNOWN.
2021-01-01 20:32:14.670 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery start
2021-01-01 20:32:14.671 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is unknown or not online.
2021-01-01 20:32:14.671 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2021-01-01 20:32:14.674 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using basic cluster on endpoint D0F4/1
2021-01-01 20:32:14.674 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to UNKNOWN.
2021-01-01 20:32:14.668 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to UNKNOWN.
2021-01-01 20:32:14.668 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to UNKNOWN.
2021-01-01 20:32:14.677 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is unknown or not online.
2021-01-01 20:32:14.677 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is unknown or not online.
2021-01-01 20:32:14.680 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to UNKNOWN.
2021-01-01 20:32:14.680 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is unknown or not online.
2021-01-01 20:32:14.680 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:32:14.626 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-14
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.initialise(ZclIasZoneClient.java:220) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.appStartup(ZclIasZoneClient.java:192) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeEndpoint.addApplication(ZigBeeEndpoint.java:408) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZigBeeIasCieExtension.nodeAdded(ZigBeeIasCieExtension.java:55) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$7.run(ZigBeeNetworkManager.java:1333) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:14.682 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using OTA cluster on endpoint D0F4/1
2021-01-01 20:32:14.684 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is unknown or not online.
2021-01-01 20:32:14.677 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is unknown or not online.
2021-01-01 20:32:14.688 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:32:14.682 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is unknown or not online.
2021-01-01 20:32:14.692 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:32:14.694 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], zigbee_applicationVersion=3, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:32:14.699 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Checking endpoint 1 channels
2021-01-01 20:32:14.688 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.addPropertiesFromOtaCluster(ZigBeeNodePropertyDiscoverer.java:253) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.getProperties(ZigBeeNodePropertyDiscoverer.java:110) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:264) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:14.709 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Start initialising ZigBee Thing handler
2021-01-01 20:32:14.712 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Dynamically created 1 channels
2021-01-01 20:32:14.712 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery start
2021-01-01 20:32:14.714 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using basic cluster on endpoint 6B4D/1
2021-01-01 20:32:14.715 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Device initialization will be skipped as the device is already initialized
2021-01-01 20:32:14.718 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using OTA cluster on endpoint 6B4D/1
2021-01-01 20:32:14.721 [ERROR] [onverter.config.ZclOnOffSwitchConfig] - 7B4D/1: Error getting supported attributes for On/Off. 
java.util.concurrent.ExecutionException: java.lang.NullPointerException
	at java.util.concurrent.FutureTask.report(FutureTask.java:122) ~[?:?]
	at java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.config.ZclOnOffSwitchConfig.initialize(ZclOnOffSwitchConfig.java:59) [bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.initializeConverter(ZigBeeConverterSwitchOnoff.java:151) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:397) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
Caused by: java.lang.NullPointerException
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:908) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:882) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	... 1 more
2021-01-01 20:32:14.736 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.handleRefresh(ZigBeeConverterSwitchOnoff.java:199) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:14.721 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.addPropertiesFromOtaCluster(ZigBeeNodePropertyDiscoverer.java:253) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.getProperties(ZigBeeNodePropertyDiscoverer.java:110) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:264) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:15.685 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2021-01-01 20:32:15.686 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2021-01-01 20:32:15.691 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2021-01-01 20:32:15.836 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee reading network state complete.
2021-01-01 20:32:15.873 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000DD02780: ZigBee reading network state complete.
2021-01-01 20:32:16.019 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee reading network state complete.
2021-01-01 20:32:16.168 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004514C4B: ZigBee reading network state complete.
2021-01-01 20:32:16.290 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee reading network state complete.
2021-01-01 20:32:16.428 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee reading network state complete.
2021-01-01 20:32:16.456 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee reading network state complete.
2021-01-01 20:32:16.534 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee reading network state complete.
2021-01-01 20:32:16.540 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
2021-01-01 20:32:16.579 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2021-01-01 20:32:20.590 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400
2021-01-01 20:32:20.616 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_11, PanID=31276, EPanId=994686C23E8C0C13
2021-01-01 20:32:20.619 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_INSECURE
2021-01-01 20:32:20.860 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee initialise done. channel=CHANNEL_11, PanId=31276  EPanId=994686C23E8C0C13
2021-01-01 20:32:20.910 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:20.925 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Starting ZigBee device discovery
2021-01-01 20:32:20.925 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Start initialising ZigBee Thing handler
2021-01-01 20:32:20.927 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:20.929 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:32:20.932 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:32:20.933 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:32:20.937 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:32:20.937 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Starting ZigBee device discovery
2021-01-01 20:32:20.938 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:20.936 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:20.936 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:32:20.941 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D0004514C4B, NWK=D0F4, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:32:20.945 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:32:20.947 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004514C4B: Starting ZigBee device discovery
2021-01-01 20:32:20.947 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:32:20.950 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004514C4B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:20.951 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:32:20.955 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Start initialising ZigBee Thing handler
2021-01-01 20:32:20.955 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00124B001EFE4FEE, NWK=70EF, Type=ROUTER, endpoints=[11, 13]]
2021-01-01 20:32:20.960 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:32:20.961 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D0004516178, NWK=6B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:32:20.962 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Start initialising ZigBee Thing handler
2021-01-01 20:32:20.965 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery start
2021-01-01 20:32:20.965 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D0004232414, NWK=C7E4, endpoints=[]]
2021-01-01 20:32:20.967 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Checking endpoint 3 channels
2021-01-01 20:32:20.969 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Start initialising ZigBee Thing handler
2021-01-01 20:32:20.970 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:32:20.968 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using basic cluster on endpoint D0F4/1
2021-01-01 20:32:20.972 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery start
2021-01-01 20:32:20.970 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Starting ZigBee device discovery
2021-01-01 20:32:20.973 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:32:20.975 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:20.975 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery using basic cluster on endpoint 70EF/11
2021-01-01 20:32:20.976 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:32:20.978 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery start
2021-01-01 20:32:20.979 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004232414: Starting ZigBee device discovery
2021-01-01 20:32:20.981 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using basic cluster on endpoint D0F4/1
2021-01-01 20:32:20.982 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004232414: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:20.981 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery start
2021-01-01 20:32:20.979 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: Node doesn't support OTA cluster
2021-01-01 20:32:20.985 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery using basic cluster on endpoint 70EF/11
2021-01-01 20:32:20.986 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Checking endpoint 3 channels
2021-01-01 20:32:20.983 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using OTA cluster on endpoint D0F4/1
2021-01-01 20:32:20.990 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Starting ZigBee device discovery
2021-01-01 20:32:20.990 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=ONLINE
2021-01-01 20:32:20.992 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Node updated - ZigBeeNode [state=UNKNOWN, IEEE=00158D00040221F8, NWK=7B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:32:20.992 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:20.985 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x0000, modelId=GL-C-007, zigbee_networkaddress=28911, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20131206, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=GLEDOPTO, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=1, zigbee_neighbors=[], zigbee_applicationVersion=1, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:32:20.994 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to ONLINE.
2021-01-01 20:32:20.994 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to ONLINE.
2021-01-01 20:32:20.996 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:20.996 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to ONLINE.
2021-01-01 20:32:21.002 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Checking endpoint 11 channels
2021-01-01 20:32:21.002 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery start
2021-01-01 20:32:21.005 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:21.008 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using basic cluster on endpoint 6B4D/1
2021-01-01 20:32:21.008 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:21.036 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-16
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeAdded(ZigBeeThingHandler.java:815) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$7.run(ZigBeeNetworkManager.java:1333) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:21.073 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00AEC851: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:32:21.075 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Dynamically created 2 channels
2021-01-01 20:32:21.075 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00ABA09B: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:32:21.077 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Dynamically created 2 channels
2021-01-01 20:32:21.077 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Device initialization will be skipped as the device is already initialized
2021-01-01 20:32:21.082 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Device initialization will be skipped as the device is already initialized
2021-01-01 20:32:21.255 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:21.288 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000DD02780: ZigBee saving network state complete.
2021-01-01 20:32:21.386 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:32:21.476 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004514C4B: ZigBee saving network state complete.
2021-01-01 20:32:21.554 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:32:21.642 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee saving network state complete.
2021-01-01 20:32:21.660 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:32:21.710 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:32:21.896 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D00040221F8, NWK=7B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:32:21.897 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D00040221F8, NWK=7B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:32:22.184 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:32:24.769 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:32:24.772 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:32:24.775 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:32:24.779 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Starting ZigBee device discovery
2021-01-01 20:32:24.782 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:24.786 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:32:24.789 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:32:24.859 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=247, lastReportTime=Fri Jan 01 20:32:24 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:32:24.868 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: Level control initialized as server
2021-01-01 20:32:24.868 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature updated to 65
2021-01-01 20:32:24.871 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_colortemperature to 65
2021-01-01 20:32:25.109 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:32:25.883 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:32:25 CET 2021, implemented=false]
2021-01-01 20:32:25.886 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 100
2021-01-01 20:32:25.888 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 100
2021-01-01 20:32:26.418 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:32:26.421 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:32:26.424 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:32:26.431 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Starting ZigBee device discovery
2021-01-01 20:32:26.433 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:26.467 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:32:26.470 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:32:26.577 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=247, lastReportTime=Fri Jan 01 20:32:26 CET 2021, implemented=true]  on endpoint 3
2021-01-01 20:32:26.580 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature updated to 65
2021-01-01 20:32:26.582 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_colortemperature to 65
2021-01-01 20:32:26.593 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: Level control initialized as server
2021-01-01 20:32:26.749 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: Node doesn't support OTA cluster
2021-01-01 20:32:26.751 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x0000, modelId=GL-C-007, zigbee_networkaddress=28911, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20131206, zigbee_zclversion=1, vendor=GLEDOPTO, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=1, zigbee_applicationVersion=1}
2021-01-01 20:32:26.755 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'GLEDOPTO GL-C-007'
2021-01-01 20:32:26.782 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:27.083 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:32:27.252 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:32:27 CET 2021, implemented=false]
2021-01-01 20:32:27.255 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 100
2021-01-01 20:32:27.257 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 100
2021-01-01 20:32:27.393 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 00124B001EFE4FEE: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:32:27.395 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 00124B001EFE4FEE: Removing channel zigbee:switch_level in favor of zigbee:color_color
2021-01-01 20:32:27.397 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Checking endpoint 13 channels
2021-01-01 20:32:27.414 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Dynamically created 2 channels
2021-01-01 20:32:27.423 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Device initialization will be skipped as the device is already initialized
2021-01-01 20:32:29.141 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Channel initialisation complete
2021-01-01 20:32:29.143 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Setting ONLINE/OFFLINE timeout interval to: 1830
2021-01-01 20:32:29.146 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.148 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.151 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.153 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:32:29.579 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=153, lastReportTime=Fri Jan 01 20:32:29 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:32:29.582 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature updated to 0
2021-01-01 20:32:29.586 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature to 0
2021-01-01 20:32:29.674 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Polling initialised at 945405ms
2021-01-01 20:32:29.678 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Done initialising ZigBee Thing handler
2021-01-01 20:32:29.681 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Start initialising ZigBee Thing handler
2021-01-01 20:32:29.682 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.683 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery start
2021-01-01 20:32:29.684 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to ONLINE.
2021-01-01 20:32:29.685 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.687 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:32:29.687 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:29.691 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using basic cluster on endpoint 6B4D/1
2021-01-01 20:32:29.691 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.693 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=38, lastReportTime=Fri Jan 01 20:32:26 CET 2021, implemented=true]
2021-01-01 20:32:29.695 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using OTA cluster on endpoint 6B4D/1
2021-01-01 20:32:29.696 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.695 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 15
2021-01-01 20:32:29.701 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 15
2021-01-01 20:32:29.698 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:32:29.709 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.711 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:29.715 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:32:30.013 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:32:30.041 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Channel initialisation complete
2021-01-01 20:32:30.044 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Setting ONLINE/OFFLINE timeout interval to: 1830
2021-01-01 20:32:30.046 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.048 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.049 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.051 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:32:30.355 [WARN ] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: Cannot determine whether device supports RGB color. Assuming it supports HUE/SAT
2021-01-01 20:32:30.479 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Polling initialised at 949591ms
2021-01-01 20:32:30.481 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Done initialising ZigBee Thing handler
2021-01-01 20:32:30.484 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.483 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Start initialising ZigBee Thing handler
2021-01-01 20:32:30.486 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.490 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:32:30.490 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to ONLINE.
2021-01-01 20:32:30.494 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:30.495 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:32:30.500 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:32:30.500 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:32:30.502 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.502 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=76, lastReportTime=Fri Jan 01 20:32:27 CET 2021, implemented=true]
2021-01-01 20:32:30.504 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.504 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 30
2021-01-01 20:32:30.504 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], zigbee_applicationVersion=3, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:32:30.506 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:32:30.506 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 30
2021-01-01 20:32:30.508 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Checking endpoint 1 channels
2021-01-01 20:32:30.509 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.514 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:30.516 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:32:30.532 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Dynamically created 1 channels
2021-01-01 20:32:30.535 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Device initialization will be skipped as the device is already initialized
2021-01-01 20:32:30.814 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:30.842 [DEBUG] [nverter.config.ZclLevelControlConfig] - 70EF/11: Unable to get supported attributes for Level Control.
2021-01-01 20:32:31.377 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:32:31 CET 2021, implemented=false]
2021-01-01 20:32:31.380 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0,0,100
2021-01-01 20:32:31.384 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0,0,100
2021-01-01 20:32:31.672 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=90C4, endpoints=[]]
2021-01-01 20:32:31.672 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=90C4, endpoints=[]]
2021-01-01 20:32:31.676 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-12
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:31.676 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-7
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:31.834 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:32:31 CET 2021, implemented=false]
2021-01-01 20:32:31.836 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 30
2021-01-01 20:32:31.839 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 30
2021-01-01 20:32:31.842 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:31.844 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:31.846 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:32:31.943 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:32:32.058 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=0, name=Current Hue, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=0, lastReportTime=Fri Jan 01 20:32:32 CET 2021, implemented=false]
2021-01-01 20:32:32.059 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=0, name=Current Hue, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=0, lastReportTime=Fri Jan 01 20:32:32 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:32:32.655 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=90C4, endpoints=[]]
2021-01-01 20:32:32.656 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=90C4, endpoints=[]]
2021-01-01 20:32:32.659 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-21
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:32.661 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-22
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:32.915 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:32:33.033 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=1, name=Current Saturation, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Fri Jan 01 20:32:33 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:32:33.034 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=1, name=Current Saturation, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Fri Jan 01 20:32:33 CET 2021, implemented=false]
2021-01-01 20:32:33.407 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Fri Jan 01 20:32:33 CET 2021, implemented=false]
2021-01-01 20:32:33.409 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 15
2021-01-01 20:32:33.412 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 15
2021-01-01 20:32:33.420 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:33.420 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=3, lastReportTime=Fri Jan 01 20:32:33 CET 2021, implemented=false]
2021-01-01 20:32:33.423 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:33.425 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:32:33.662 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00ABA09B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=76, lastReportTime=Fri Jan 01 20:32:33 CET 2021, implemented=true]
2021-01-01 20:32:33.665 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00ABA09B: Channel zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer updated to 30
2021-01-01 20:32:33.667 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aba09b:7CB03EAA00ABA09B_3_dimmer to 30
2021-01-01 20:32:33.670 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:33.672 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b
2021-01-01 20:32:33.675 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aba09b in 1830 seconds
2021-01-01 20:32:33.852 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Channel initialisation complete
2021-01-01 20:32:33.853 [DEBUG] [rter.ZigBeeConverterColorTemperature] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=8, name=Color Mode, dataType=ENUMERATION_8_BIT, lastValue=0, lastReportTime=Fri Jan 01 20:32:33 CET 2021, implemented=false]  on endpoint 11
2021-01-01 20:32:33.853 [DEBUG] [.converter.ZigBeeConverterColorColor] - 00124B001EFE4FEE: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=8, name=Color Mode, dataType=ENUMERATION_8_BIT, lastValue=0, lastReportTime=Fri Jan 01 20:32:33 CET 2021, implemented=false]
2021-01-01 20:32:33.854 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Setting ONLINE/OFFLINE timeout interval to: 14430
2021-01-01 20:32:33.854 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature updated to UNDEF
2021-01-01 20:32:33.856 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:33.857 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_colortemperature to UNDEF
2021-01-01 20:32:33.858 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:33.860 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:33.862 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:32:34.413 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using OTA cluster on endpoint 6B4D/1
2021-01-01 20:32:34.650 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:34.650 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:34.660 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Starting ZigBee device discovery
2021-01-01 20:32:34.663 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:34.663 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:34.663 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:34.686 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:34.686 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00AEC851, NWK=9346, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:34.688 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:32:34.691 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:32:35.002 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:35.096 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:32:35.388 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 7CB03EAA00AEC851: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=38, lastReportTime=Fri Jan 01 20:32:35 CET 2021, implemented=true]
2021-01-01 20:32:35.398 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA00AEC851: Channel zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer updated to 15
2021-01-01 20:32:35.401 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Updating ZigBee channel state zigbee:device:01380F03:7cb03eaa00aec851:7CB03EAA00AEC851_3_dimmer to 15
2021-01-01 20:32:35.404 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:35.406 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851
2021-01-01 20:32:35.408 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:7cb03eaa00aec851 in 1830 seconds
2021-01-01 20:32:35.486 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:35.486 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Node updated - ZigBeeNode [state=ONLINE, IEEE=7CB03EAA00ABA09B, NWK=E1C8, Type=ROUTER, endpoints=[3]]
2021-01-01 20:32:35.601 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:32:35.604 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:32:35.607 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00AEC851: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:32:35.612 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Starting ZigBee device discovery
2021-01-01 20:32:35.614 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:35.619 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:32:35.622 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:32:35.815 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:35.943 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:32:38.516 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:32:38.519 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=0}
2021-01-01 20:32:38.522 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00ABA09B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'OSRAM PAR16 50 TW'
2021-01-01 20:32:38.857 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:32:40.403 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: Could not get OTA firmware version from device
2021-01-01 20:32:40.406 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=53492, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_neighbors=[], zigbee_applicationVersion=5, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:32:40.411 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Checking endpoint 1 channels
2021-01-01 20:32:43.966 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Error getting binding table
2021-01-01 20:32:43.969 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Polling initialised at 7424686ms
2021-01-01 20:32:43.971 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Done initialising ZigBee Thing handler
2021-01-01 20:32:43.975 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:43.978 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:43.979 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to ONLINE.
2021-01-01 20:32:43.980 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:32:43.982 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:32:43.986 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:43.988 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:43.990 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:32:43.993 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:43.995 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:43.997 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:32:43.999 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0,0,100
2021-01-01 20:32:44.001 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0,0,100
2021-01-01 20:32:44.004 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:44.006 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:44.009 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:32:44.011 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0,0,1
2021-01-01 20:32:44.013 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0,0,1
2021-01-01 20:32:44.016 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:44.020 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:44.023 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:32:44.031 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 00124B001EFE4FEE: Channel zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color updated to 0.0,100.0,1
2021-01-01 20:32:44.042 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Updating ZigBee channel state zigbee:device:01380F03:00124b001efe4fee:00124B001EFE4FEE_11_color to 0.0,100.0,1
2021-01-01 20:32:44.045 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:44.047 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:01380F03:00124b001efe4fee
2021-01-01 20:32:44.051 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:01380F03:00124b001efe4fee in 14430 seconds
2021-01-01 20:32:44.299 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:32:45.927 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:32:45.929 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_applicationVersion=3}
2021-01-01 20:32:45.932 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'LUMI lumi.sensor_magnet.aq2'
2021-01-01 20:32:46.231 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:32:49.824 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery using OTA cluster on endpoint D0F4/1
2021-01-01 20:32:53.277 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004516178, NWK=6B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:32:53.287 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004516178, NWK=6B4D, Type=END_DEVICE, endpoints=[1]]
2021-01-01 20:32:53.300 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Starting ZigBee device discovery
2021-01-01 20:32:53.302 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:53.305 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=C7E4, endpoints=[]]
2021-01-01 20:32:53.307 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery start
2021-01-01 20:32:53.309 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node updated - ZigBeeNode [state=ONLINE, IEEE=00158D0004232414, NWK=C7E4, endpoints=[]]
2021-01-01 20:32:53.312 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using basic cluster on endpoint 6B4D/1
2021-01-01 20:32:53.308 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-4
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:53.312 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-27
java.lang.NullPointerException: null
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.nodeUpdated(ZigBeeThingHandler.java:872) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$14.run(ZigBeeNetworkManager.java:1733) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:32:53.610 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee saving network state complete.
2021-01-01 20:32:53.631 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:32:55.311 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Node updated - ZigBeeNode [state=ONLINE, IEEE=00124B001EFE4FEE, NWK=70EF, Type=ROUTER, endpoints=[11, 13]]
2021-01-01 20:32:55.312 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Node updated - ZigBeeNode [state=ONLINE, IEEE=00124B001EFE4FEE, NWK=70EF, Type=ROUTER, endpoints=[11, 13]]
2021-01-01 20:32:55.335 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Starting ZigBee device discovery
2021-01-01 20:32:55.339 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03
2021-01-01 20:32:55.347 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery start
2021-01-01 20:32:55.351 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery using basic cluster on endpoint 70EF/11
2021-01-01 20:32:55.731 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:32:55.920 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: Node doesn't support OTA cluster
2021-01-01 20:32:55.924 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00124B001EFE4FEE: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x0000, modelId=GL-C-007, zigbee_networkaddress=28911, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20131206, zigbee_zclversion=1, vendor=GLEDOPTO, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=1, zigbee_applicationVersion=1}
2021-01-01 20:32:55.929 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00124B001EFE4FEE: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'GLEDOPTO GL-C-007'
2021-01-01 20:32:56.356 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:33:11.469 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:33:11.478 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_applicationVersion=3}
2021-01-01 20:33:11.481 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00040221F8: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'LUMI lumi.sensor_magnet.aq2'
2021-01-01 20:33:11.491 [WARN ] [e.ember.internal.ash.AshFrameHandler] - ASH: ERROR received (code 81). Disconnecting.
2021-01-01 20:33:11.501 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: Could not get OTA firmware version from device
2021-01-01 20:33:11.502 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2021-01-01 20:33:11.513 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=27469, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_neighbors=[], zigbee_applicationVersion=5, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:11.519 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to OFFLINE.
2021-01-01 20:33:11.519 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to OFFLINE.
2021-01-01 20:33:11.521 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Checking endpoint 1 channels
2021-01-01 20:33:11.521 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is unknown or not online.
2021-01-01 20:33:11.521 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is unknown or not online.
2021-01-01 20:33:11.524 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Polling stopped
2021-01-01 20:33:11.528 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to OFFLINE.
2021-01-01 20:33:11.532 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is unknown or not online.
2021-01-01 20:33:11.534 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Polling stopped
2021-01-01 20:33:11.538 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to OFFLINE.
2021-01-01 20:33:11.540 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is unknown or not online.
2021-01-01 20:33:11.544 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Polling stopped
2021-01-01 20:33:11.787 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:33:12.520 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2021-01-01 20:33:12.522 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2021-01-01 20:33:12.600 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00ABA09B: ZigBee saving network state complete.
2021-01-01 20:33:12.621 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F000DD02780: ZigBee saving network state complete.
2021-01-01 20:33:12.700 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00AEC851: ZigBee saving network state complete.
2021-01-01 20:33:12.778 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004514C4B: ZigBee saving network state complete.
2021-01-01 20:33:12.851 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001EFE4FEE: ZigBee saving network state complete.
2021-01-01 20:33:12.929 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004516178: ZigBee saving network state complete.
2021-01-01 20:33:12.947 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D0004232414: ZigBee saving network state complete.
2021-01-01 20:33:12.999 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00040221F8: ZigBee saving network state complete.
2021-01-01 20:33:13.044 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2021-01-01 20:33:13.048 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: Could not get OTA firmware version from device
2021-01-01 20:33:13.048 [WARN ] [igbee.app.iasclient.ZclIasZoneClient] - D0F4/1: IAS CIE address is NOT confirmed null
2021-01-01 20:33:13.051 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004514C4B: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=53492, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_applicationVersion=5}
2021-01-01 20:33:13.053 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using OTA cluster on endpoint 6B4D/1
2021-01-01 20:33:13.056 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004514C4B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'LUMI lumi.sensor_motion.aq2'
2021-01-01 20:33:13.080 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to OFFLINE.
2021-01-01 20:33:13.083 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is unknown or not online.
2021-01-01 20:33:13.087 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: Could not get OTA firmware version from device
2021-01-01 20:33:13.087 [DEBUG] [onverter.config.ZclOnOffSwitchConfig] - 7B4D/1: Unable to get supported attributes for On/Off.
2021-01-01 20:33:13.105 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_motion.aq2, zigbee_networkaddress=27469, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20170627, zigbee_zclversion=1, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=1, zigbee_applicationVersion=5}
2021-01-01 20:33:13.110 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to OFFLINE.
2021-01-01 20:33:13.110 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to ONLINE.
2021-01-01 20:33:13.112 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is ONLINE. Starting device initialisation.
2021-01-01 20:33:13.112 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is unknown or not online.
2021-01-01 20:33:13.113 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D0004516178: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:01380F03, label 'LUMI lumi.sensor_motion.aq2'
2021-01-01 20:33:13.107 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.handleRefresh(ZigBeeConverterSwitchOnoff.java:199) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.129 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to OFFLINE.
2021-01-01 20:33:13.129 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.056 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.addPropertiesFromOtaCluster(ZigBeeNodePropertyDiscoverer.java:253) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.getProperties(ZigBeeNodePropertyDiscoverer.java:110) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService$2.run(ZigBeeDiscoveryService.java:207) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.131 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is unknown or not online.
2021-01-01 20:33:13.132 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:33:13.056 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-6
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.initialise(ZclIasZoneClient.java:220) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.appStartup(ZclIasZoneClient.java:192) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeEndpoint.addApplication(ZigBeeEndpoint.java:408) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZigBeeIasCieExtension.nodeAdded(ZigBeeIasCieExtension.java:55) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$7.run(ZigBeeNetworkManager.java:1333) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.139 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2021-01-01 20:33:13.140 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:33:13.143 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Node has not finished discovery
2021-01-01 20:33:13.143 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:7a2c, EPAN:994686C23E8C0C13, Channel:11
2021-01-01 20:33:13.145 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2021-01-01 20:33:13.150 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator status changed to UNKNOWN.
2021-01-01 20:33:13.150 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator status changed to UNKNOWN.
2021-01-01 20:33:13.151 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator status changed to UNKNOWN.
2021-01-01 20:33:13.151 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator status changed to UNKNOWN.
2021-01-01 20:33:13.152 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Coordinator is unknown or not online.
2021-01-01 20:33:13.152 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator status changed to UNKNOWN.
2021-01-01 20:33:13.153 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Coordinator is unknown or not online.
2021-01-01 20:33:13.154 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator status changed to UNKNOWN.
2021-01-01 20:33:13.156 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator status changed to UNKNOWN.
2021-01-01 20:33:13.156 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Coordinator is unknown or not online.
2021-01-01 20:33:13.158 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Coordinator is unknown or not online.
2021-01-01 20:33:13.160 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00124B001EFE4FEE: Coordinator is unknown or not online.
2021-01-01 20:33:13.159 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:33:13.070 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclIasZoneCluster.getZoneType(ZclIasZoneCluster.java:296) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIas.supportsIasChannel(ZigBeeConverterIas.java:143) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasCieSystem.getChannel(ZigBeeConverterIasCieSystem.java:38) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl.getChannels(ZigBeeChannelConverterFactoryImpl.java:83) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:288) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.053 [WARN ] [zsmartsystems.zigbee.ZigBeeExecutors] - Uncaught exception in thread NotificationService-thread-11
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.initialise(ZclIasZoneClient.java:230) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZclIasZoneClient.appStartup(ZclIasZoneClient.java:192) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeEndpoint.addApplication(ZigBeeEndpoint.java:408) ~[?:?]
	at com.zsmartsystems.zigbee.app.iasclient.ZigBeeIasCieExtension.nodeAdded(ZigBeeIasCieExtension.java:55) ~[?:?]
	at com.zsmartsystems.zigbee.ZigBeeNetworkManager$7.run(ZigBeeNetworkManager.java:1333) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.164 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004516178: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.160 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2021-01-01 20:33:13.165 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004232414: Coordinator is unknown or not online.
2021-01-01 20:33:13.167 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0004514C4B: Coordinator is unknown or not online.
2021-01-01 20:33:13.169 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], zigbee_applicationVersion=3, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.171 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery start
2021-01-01 20:33:13.173 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Checking endpoint 1 channels
2021-01-01 20:33:13.108 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclIasZoneCluster.getZoneType(ZclIasZoneCluster.java:296) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIas.supportsIasChannel(ZigBeeConverterIas.java:143) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasCieSystem.getChannel(ZigBeeConverterIasCieSystem.java:38) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactoryImpl.getChannels(ZigBeeChannelConverterFactoryImpl.java:83) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:288) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.178 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using basic cluster on endpoint 6B4D/1
2021-01-01 20:33:13.181 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D0004516178: ZigBee node property discovery using OTA cluster on endpoint 6B4D/1
2021-01-01 20:33:13.184 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.addPropertiesFromOtaCluster(ZigBeeNodePropertyDiscoverer.java:253) ~[?:?]
	at org.openhab.binding.zigbee.discovery.ZigBeeNodePropertyDiscoverer.getProperties(ZigBeeNodePropertyDiscoverer.java:110) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:264) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) ~[?:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.271 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Dynamically created 1 channels
2021-01-01 20:33:13.275 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.284 [ERROR] [onverter.config.ZclOnOffSwitchConfig] - 7B4D/1: Error getting supported attributes for On/Off. 
java.util.concurrent.ExecutionException: java.lang.NullPointerException
	at java.util.concurrent.FutureTask.report(FutureTask.java:122) ~[?:?]
	at java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.config.ZclOnOffSwitchConfig.initialize(ZclOnOffSwitchConfig.java:59) [bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.initializeConverter(ZigBeeConverterSwitchOnoff.java:151) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:397) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
Caused by: java.lang.NullPointerException
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:908) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:882) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	... 1 more
2021-01-01 20:33:13.300 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.handleRefresh(ZigBeeConverterSwitchOnoff.java:199) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.308 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:33:13.311 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:33:13.314 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:33:13.316 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], zigbee_applicationVersion=3, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.319 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Checking endpoint 1 channels
2021-01-01 20:33:13.331 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Dynamically created 1 channels
2021-01-01 20:33:13.339 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.344 [ERROR] [onverter.config.ZclOnOffSwitchConfig] - 7B4D/1: Error getting supported attributes for On/Off. 
java.util.concurrent.ExecutionException: java.lang.NullPointerException
	at java.util.concurrent.FutureTask.report(FutureTask.java:122) ~[?:?]
	at java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.config.ZclOnOffSwitchConfig.initialize(ZclOnOffSwitchConfig.java:59) [bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.initializeConverter(ZigBeeConverterSwitchOnoff.java:151) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:397) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
Caused by: java.lang.NullPointerException
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:908) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:882) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	... 1 more
2021-01-01 20:33:13.360 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.handleRefresh(ZigBeeConverterSwitchOnoff.java:199) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.366 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.366 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.368 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery start
2021-01-01 20:33:13.368 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:33:13.371 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery using basic cluster on endpoint 7B4D/1
2021-01-01 20:33:13.371 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:33:13.374 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: Node doesn't support OTA cluster
2021-01-01 20:33:13.374 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:33:13.376 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00040221F8: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x1037, modelId=lumi.sensor_magnet.aq2, zigbee_networkaddress=31565, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=LUMI, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], zigbee_applicationVersion=3, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.377 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.380 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Checking endpoint 1 channels
2021-01-01 20:33:13.380 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Checking endpoint 3 channels
2021-01-01 20:33:13.393 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Dynamically created 1 channels
2021-01-01 20:33:13.396 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.399 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00AEC851: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:33:13.401 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Dynamically created 2 channels
2021-01-01 20:33:13.411 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.401 [ERROR] [onverter.config.ZclOnOffSwitchConfig] - 7B4D/1: Error getting supported attributes for On/Off. 
java.util.concurrent.ExecutionException: java.lang.NullPointerException
	at java.util.concurrent.FutureTask.report(FutureTask.java:122) ~[?:?]
	at java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:?]
	at org.openhab.binding.zigbee.internal.converter.config.ZclOnOffSwitchConfig.initialize(ZclOnOffSwitchConfig.java:59) [bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.initializeConverter(ZigBeeConverterSwitchOnoff.java:151) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:397) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
Caused by: java.lang.NullPointerException
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:908) ~[?:?]
	at com.zsmartsystems.zigbee.zcl.ZclCluster$1.call(ZclCluster.java:882) ~[?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
	... 1 more
2021-01-01 20:33:13.415 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclColorControlCluster.getColorTemperature(ZclColorControlCluster.java:811) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterColorTemperature.handleRefresh(ZigBeeConverterColorTemperature.java:130) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.421 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.416 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00040221F8: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readAttributeValue(ZclCluster.java:480) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.ZclAttribute.readValue(ZclAttribute.java:193) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchOnoff.handleRefresh(ZigBeeConverterSwitchOnoff.java:199) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.424 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:33:13.426 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:33:13.431 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:33:13.434 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.441 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Checking endpoint 3 channels
2021-01-01 20:33:13.459 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00ABA09B: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:33:13.463 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Dynamically created 2 channels
2021-01-01 20:33:13.464 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.465 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.466 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:33:13.469 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:33:13.473 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:33:13.470 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclColorControlCluster.getColorTemperature(ZclColorControlCluster.java:811) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterColorTemperature.handleRefresh(ZigBeeConverterColorTemperature.java:130) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.475 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.485 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Checking endpoint 3 channels
2021-01-01 20:33:13.501 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00AEC851: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:33:13.504 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Dynamically created 2 channels
2021-01-01 20:33:13.506 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.510 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclColorControlCluster.getColorTemperature(ZclColorControlCluster.java:811) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterColorTemperature.handleRefresh(ZigBeeConverterColorTemperature.java:130) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.515 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.517 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.518 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:33:13.519 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:33:13.521 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:33:13.522 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:33:13.524 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:33:13.525 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:33:13.527 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.527 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.530 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Checking endpoint 3 channels
2021-01-01 20:33:13.530 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Checking endpoint 3 channels
2021-01-01 20:33:13.548 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00AEC851: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:33:13.550 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Dynamically created 2 channels
2021-01-01 20:33:13.551 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 7CB03EAA00ABA09B: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2021-01-01 20:33:13.553 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.554 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Dynamically created 2 channels
2021-01-01 20:33:13.558 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Device initialization will be skipped as the device is already initialized
2021-01-01 20:33:13.557 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclColorControlCluster.getColorTemperature(ZclColorControlCluster.java:811) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterColorTemperature.handleRefresh(ZigBeeConverterColorTemperature.java:130) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.563 [ERROR] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Exception creating channels 
java.lang.NullPointerException: null
	at com.zsmartsystems.zigbee.zcl.ZclCluster.readSync(ZclCluster.java:1627) ~[bundleFile:?]
	at com.zsmartsystems.zigbee.zcl.clusters.ZclColorControlCluster.getColorTemperature(ZclColorControlCluster.java:811) ~[bundleFile:?]
	at org.openhab.binding.zigbee.internal.converter.ZigBeeConverterColorTemperature.handleRefresh(ZigBeeConverterColorTemperature.java:130) ~[bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.doNodeInitialisation(ZigBeeThingHandler.java:406) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:233) [bundleFile:?]
	at org.openhab.binding.zigbee.handler.ZigBeeThingHandler$1.call(ZigBeeThingHandler.java:1) [bundleFile:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-01-01 20:33:13.574 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00ABA09B: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.577 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery start
2021-01-01 20:33:13.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AEC851: Start initialising ZigBee Thing handler
2021-01-01 20:33:13.580 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery start
2021-01-01 20:33:13.580 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using basic cluster on endpoint E1C8/3
2021-01-01 20:33:13.582 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using basic cluster on endpoint 9346/3
2021-01-01 20:33:13.583 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery using OTA cluster on endpoint E1C8/3
2021-01-01 20:33:13.585 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery using OTA cluster on endpoint 9346/3
2021-01-01 20:33:13.586 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00ABA09B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=57800, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}
2021-01-01 20:33:13.589 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00AEC851: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=PAR16 50 TW, zigbee_networkaddress=37702, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNEF****, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, zigbee_stkcompliance=0, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, DISPOSABLE_BATTERY, MAINS], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_neighbors=[], zigbee_applicationVersion=0, zigbee_device_initialised=true, zigbee_devices=[]}