2019-07-28 16:36:48.146 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - 0017880102139D00: ZigBee leave command
2019-07-28 16:36:48.148 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - 0017880102139D00: ZigBee leave command to 20336
2019-07-28 16:36:48.159 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:36:48.175 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:36:48.282 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:36:48.292 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Handler dispose.
2019-07-28 16:36:48.294 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Polling stopped
2019-07-28 16:36:48.296 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Canceling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:36:48.298 [DEBUG] [l.converter.ZigBeeConverterOccupancy] - 0017880102139D00: Closing device occupancy cluster
2019-07-28 16:36:48.299 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 0017880102139D00: Closing power configuration cluster
2019-07-28 16:36:48.313 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=423, service.bundleid=267, service.scope=singleton} - org.openhab.binding.zigbee
2019-07-28 16:36:52.266 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:36:52.275 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:36:52.376 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:38:03.271 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Discovery: Starting discovery for existing device
2019-07-28 16:38:03.276 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 001788011032A21B: Discovery: Starting discovery for existing device
2019-07-28 16:38:03.280 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Discovery: Starting discovery for existing device
2019-07-28 16:38:03.284 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Discovery: Starting discovery for existing device
2019-07-28 16:38:03.289 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:03.290 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:03.295 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 001788011032A21B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:03.301 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:03.301 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:03.305 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery start
2019-07-28 16:38:03.306 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery start
2019-07-28 16:38:03.307 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery start
2019-07-28 16:38:03.310 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery using 37002/3
2019-07-28 16:38:03.310 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery using 25747/1
2019-07-28 16:38:03.311 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery using 40918/1
2019-07-28 16:38:03.312 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery start
2019-07-28 16:38:03.320 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery using 17261/1
2019-07-28 16:38:03.326 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=RWL021, zigbee_networkaddress=17261, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=1, zigbee_datecode=20150918, zigbee_zclversion=1, vendor=Philips, zigbee_powermode=RECEIVER_ON_PERIODICALLY, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x42003F89, zigbee_applicationVersion=2}
2019-07-28 16:38:03.331 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:38:03.340 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:38:03.456 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:38:11.312 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: Application version failed
2019-07-28 16:38:11.314 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: Application version failed
2019-07-28 16:38:11.316 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=VMS_ADUROLIGHT, vendor=ADUROLIGHT, zigbee_networkaddress=25747, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_powersource=DISPOSABLE_BATTERY, hardwareVersion=1, zigbee_stkversion=2, zigbee_datecode=2017-08-04, zigbee_zclversion=1}
2019-07-28 16:38:11.316 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: Application version failed
2019-07-28 16:38:11.318 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'ADUROLIGHT VMS_ADUROLIGHT'
2019-07-28 16:38:11.319 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=lumi.sensor_magnet.aq2, vendor=LUMI, zigbee_networkaddress=40918, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_powersource=DISPOSABLE_BATTERY, hardwareVersion=2, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1}
2019-07-28 16:38:11.320 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:38:11.315 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, modelId=Plug 01, zigbee_networkaddress=37002, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331DEOS****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x01020412}
2019-07-28 16:38:11.322 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'LUMI lumi.sensor_magnet.aq2'
2019-07-28 16:38:11.324 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'OSRAM Plug 01'
2019-07-28 16:38:11.326 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Deferring ZigBee network state file save (another thread already writing)
2019-07-28 16:38:11.327 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Skipping ZigBee network state save (another thread already waiting)
2019-07-28 16:38:11.329 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:38:11.421 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:38:11.424 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Resuming ZigBee network state file save (previous thread finished)
2019-07-28 16:38:11.431 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:38:11.527 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:38:16.725 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Discovery: Starting discovery for existing device
2019-07-28 16:38:16.729 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 001788011032A21B: Discovery: Starting discovery for existing device
2019-07-28 16:38:16.732 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Discovery: Starting discovery for existing device
2019-07-28 16:38:16.736 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Discovery: Starting discovery for existing device
2019-07-28 16:38:16.741 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:16.739 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:16.744 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery start
2019-07-28 16:38:16.746 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery using 40918/1
2019-07-28 16:38:16.746 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 001788011032A21B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:16.752 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:16.755 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:38:16.757 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery start
2019-07-28 16:38:16.759 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery start
2019-07-28 16:38:16.759 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery using 17261/1
2019-07-28 16:38:16.760 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery using 25747/1
2019-07-28 16:38:16.761 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=RWL021, zigbee_networkaddress=17261, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=1, zigbee_datecode=20150918, zigbee_zclversion=1, vendor=Philips, zigbee_powermode=RECEIVER_ON_PERIODICALLY, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x42003F89, zigbee_applicationVersion=2}
2019-07-28 16:38:16.763 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery start
2019-07-28 16:38:16.766 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:38:16.766 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery using 37002/3
2019-07-28 16:38:16.774 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:38:16.864 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:38:24.748 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: Application version failed
2019-07-28 16:38:24.751 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=lumi.sensor_magnet.aq2, vendor=LUMI, zigbee_networkaddress=40918, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_powersource=DISPOSABLE_BATTERY, hardwareVersion=2, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1}
2019-07-28 16:38:24.754 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'LUMI lumi.sensor_magnet.aq2'
2019-07-28 16:38:24.757 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:38:24.763 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: Application version failed
2019-07-28 16:38:24.765 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:38:24.765 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=VMS_ADUROLIGHT, vendor=ADUROLIGHT, zigbee_networkaddress=25747, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_powersource=DISPOSABLE_BATTERY, hardwareVersion=1, zigbee_stkversion=2, zigbee_datecode=2017-08-04, zigbee_zclversion=1}
2019-07-28 16:38:24.767 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'ADUROLIGHT VMS_ADUROLIGHT'
2019-07-28 16:38:24.769 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Deferring ZigBee network state file save (another thread already writing)
2019-07-28 16:38:24.773 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: Application version failed
2019-07-28 16:38:24.775 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, modelId=Plug 01, zigbee_networkaddress=37002, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331DEOS****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x01020412}
2019-07-28 16:38:24.779 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'OSRAM Plug 01'
2019-07-28 16:38:24.782 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Skipping ZigBee network state save (another thread already waiting)
2019-07-28 16:38:24.879 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:38:24.880 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Resuming ZigBee network state file save (previous thread finished)
2019-07-28 16:38:24.896 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:38:25.011 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:38:25.345 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00A05370: Polling...
2019-07-28 16:38:25.348 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00A05370: Polling zigbee:device:f42905cb:7cb03eaa00a05370:7CB03EAA00A05370_3_activepower
2019-07-28 16:38:33.355 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00A05370: Polling zigbee:device:f42905cb:7cb03eaa00a05370:7CB03EAA00A05370_3_switch
2019-07-28 16:39:40.437 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Discovery: Starting discovery for existing device
2019-07-28 16:39:40.441 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 001788011032A21B: Discovery: Starting discovery for existing device
2019-07-28 16:39:40.444 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Discovery: Starting discovery for existing device
2019-07-28 16:39:40.447 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Discovery: Starting discovery for existing device
2019-07-28 16:39:40.450 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:40.452 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:40.455 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery start
2019-07-28 16:39:40.456 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 001788011032A21B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:40.460 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:40.463 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:40.464 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery start
2019-07-28 16:39:40.464 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery start
2019-07-28 16:39:40.466 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery using 17261/1
2019-07-28 16:39:40.466 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery start
2019-07-28 16:39:40.467 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery using 25747/1
2019-07-28 16:39:40.467 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery using 40918/1
2019-07-28 16:39:40.468 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery using 37002/3
2019-07-28 16:39:40.483 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011032A21B: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=RWL021, zigbee_networkaddress=17261, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=1, zigbee_datecode=20150918, zigbee_zclversion=1, vendor=Philips, zigbee_powermode=RECEIVER_ON_PERIODICALLY, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x42003F89, zigbee_applicationVersion=2}
2019-07-28 16:39:40.488 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:39:40.496 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:40.585 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:39:46.079 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:39:46.088 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:46.089 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 0017880102139D00: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:46.094 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 0017880102139D00: Node discovery not complete
2019-07-28 16:39:46.180 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:39:46.618 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:39:46.637 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 0017880102139D00: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:46.641 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 0017880102139D00: Node discovery not complete
2019-07-28 16:39:46.645 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:46.741 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:39:46.745 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:39:46.751 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:46.756 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 0017880102139D00: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:46.771 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 0017880102139D00: Node discovery not complete
2019-07-28 16:39:46.869 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:39:48.469 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: Application version failed
2019-07-28 16:39:48.473 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: Application version failed
2019-07-28 16:39:48.472 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: Application version failed
2019-07-28 16:39:48.476 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D000236EB75: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=lumi.sensor_magnet.aq2, vendor=LUMI, zigbee_networkaddress=40918, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_powersource=DISPOSABLE_BATTERY, hardwareVersion=2, zigbee_stkversion=2, zigbee_datecode=20161128, zigbee_zclversion=1}
2019-07-28 16:39:48.475 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00A05370: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, modelId=Plug 01, zigbee_networkaddress=37002, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331DEOS****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x01020412}
2019-07-28 16:39:48.479 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D000236EB75: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'LUMI lumi.sensor_magnet.aq2'
2019-07-28 16:39:48.479 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00A05370: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'OSRAM Plug 01'
2019-07-28 16:39:48.482 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:39:48.482 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 00158D00019533D3: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=VMS_ADUROLIGHT, vendor=ADUROLIGHT, zigbee_networkaddress=25747, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], zigbee_powersource=DISPOSABLE_BATTERY, hardwareVersion=1, zigbee_stkversion=2, zigbee_datecode=2017-08-04, zigbee_zclversion=1}
2019-07-28 16:39:48.483 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Deferring ZigBee network state file save (another thread already writing)
2019-07-28 16:39:48.484 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 00158D00019533D3: Update ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb, label 'ADUROLIGHT VMS_ADUROLIGHT'
2019-07-28 16:39:48.487 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Skipping ZigBee network state save (another thread already waiting)
2019-07-28 16:39:48.490 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:48.586 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:39:48.588 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Resuming ZigBee network state file save (previous thread finished)
2019-07-28 16:39:48.600 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:48.696 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:39:53.762 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:39:53.770 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:53.775 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 0017880102139D00: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:f42905cb
2019-07-28 16:39:53.780 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 0017880102139D00: ZigBee node property discovery start
2019-07-28 16:39:53.782 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 0017880102139D00: ZigBee node property discovery using 20336/1
2019-07-28 16:39:53.932 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:39:57.999 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 0017880102139D00: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=SML001, zigbee_networkaddress=20336, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=1, zigbee_datecode=20160630, zigbee_zclversion=1, vendor=Philips, zigbee_powermode=RECEIVER_ON_PERIODICALLY, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x420049E0, zigbee_applicationVersion=2}
2019-07-28 16:39:58.010 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:39:58.018 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:39:58.156 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:40:13.005 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:40:13.014 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:40:13.158 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:40:21.054 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=424, service.bundleid=267, service.scope=singleton} - org.openhab.binding.zigbee
2019-07-28 16:40:21.072 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.type.DynamicStateDescriptionProvider}={service.id=425, service.bundleid=267, service.scope=singleton} - org.openhab.binding.zigbee
2019-07-28 16:40:21.086 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=426, service.bundleid=267, service.scope=singleton} - org.openhab.binding.zigbee
2019-07-28 16:40:21.098 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Initializing ZigBee thing handler zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:21.106 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Coordinator status changed to ONLINE.
2019-07-28 16:40:21.110 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Coordinator is ONLINE. Starting device initialisation.
2019-07-28 16:40:21.124 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:motion linked - polling started.
2019-07-28 16:40:21.124 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:illuminance linked - polling started.
2019-07-28 16:40:21.132 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:battery_level linked - polling started.
2019-07-28 16:40:21.133 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Start initialising ZigBee Thing handler
2019-07-28 16:40:21.135 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:temperature linked - polling started.
2019-07-28 16:40:21.137 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 0017880102139D00: ZigBee node property discovery start
2019-07-28 16:40:21.140 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 0017880102139D00: ZigBee node property discovery using 20336/1
2019-07-28 16:40:21.143 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 0017880102139D00: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, modelId=SML001, zigbee_networkaddress=20336, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_stkversion=1, zigbee_datecode=20160630, zigbee_zclversion=1, vendor=Philips, zigbee_powermode=RECEIVER_ON_PERIODICALLY, zigbee_powersources=[DISPOSABLE_BATTERY, MAINS, RECHARGABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x420049E0, zigbee_applicationVersion=2}
2019-07-28 16:40:21.148 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Using static definition with existing 4 channels
2019-07-28 16:40:21.151 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Initializing device
2019-07-28 16:40:21.155 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Initializing channel zigbee:philips_sml001:f42905cb:0017880102139d00:motion with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterOccupancy@9c5173
2019-07-28 16:40:21.158 [DEBUG] [l.converter.ZigBeeConverterOccupancy] - 0017880102139D00: Initialising device occupancy cluster
2019-07-28 16:40:21.852 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:40:21.852 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000236EB75: Node updated - ZigBeeNode [IEEE=00158D000236EB75, NWK=9FD6, Type=END_DEVICE]
2019-07-28 16:40:21.861 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:40:22.000 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:40:29.161 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Initializing channel zigbee:philips_sml001:f42905cb:0017880102139d00:illuminance with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIlluminance@643a03
2019-07-28 16:40:37.165 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Initializing channel zigbee:philips_sml001:f42905cb:0017880102139d00:temperature with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterTemperature@189627d
2019-07-28 16:40:45.169 [DEBUG] [converter.ZigBeeConverterTemperature] - 0017880102139D00: Failed to bind temperature measurement cluster
2019-07-28 16:40:45.173 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Initializing channel zigbee:philips_sml001:f42905cb:0017880102139d00:battery_level with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryPercent@1fce80e
2019-07-28 16:40:45.176 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 0017880102139D00: Initialising device battery percent converter
2019-07-28 16:40:53.628 [DEBUG] [l.converter.ZigBeeConverterOccupancy] - 0017880102139D00: ZigBee attribute reports ZclAttribute [cluster=OCCUPANCY_SENSING, id=0, name=Occupancy, dataType=BITMAP_8_BIT, lastValue=0, lastReportTime=Sun Jul 28 16:40:53 CEST 2019]
2019-07-28 16:40:53.630 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:motion updated to OFF
2019-07-28 16:40:53.632 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Updating ZigBee channel state zigbee:philips_sml001:f42905cb:0017880102139d00:motion to OFF
2019-07-28 16:40:54.158 [DEBUG] [converter.ZigBeeConverterIlluminance] - 0017880102139D00: ZigBee attribute reports ZclAttribute [cluster=ILLUMINANCE_MEASUREMENT, id=0, name=MeasuredValue, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=26207, lastReportTime=Sun Jul 28 16:40:54 CEST 2019]
2019-07-28 16:40:54.162 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:illuminance updated to 262.07
2019-07-28 16:40:54.165 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Updating ZigBee channel state zigbee:philips_sml001:f42905cb:0017880102139d00:illuminance to 262.07
2019-07-28 16:40:54.686 [DEBUG] [converter.ZigBeeConverterTemperature] - 0017880102139D00: ZigBee attribute reports ZclAttribute [cluster=TEMPERATURE_MEASUREMENT, id=0, name=MeasuredValue, dataType=SIGNED_16_BIT_INTEGER, lastValue=2534, lastReportTime=Sun Jul 28 16:40:54 CEST 2019]
2019-07-28 16:40:54.688 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:temperature updated to 25.34 °C
2019-07-28 16:40:54.690 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Updating ZigBee channel state zigbee:philips_sml001:f42905cb:0017880102139d00:temperature to 25.34 °C
2019-07-28 16:40:55.295 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Channel initialisation complete
2019-07-28 16:40:55.293 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 0017880102139D00: ZigBee attribute reports ZclAttribute [cluster=POWER_CONFIGURATION, id=33, name=BatteryPercentageRemaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=115, lastReportTime=Sun Jul 28 16:40:55 CEST 2019]
2019-07-28 16:40:55.296 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - Setting ONLINE/OFFLINE timeout interval to: 14430
2019-07-28 16:40:55.297 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 0017880102139D00: Channel zigbee:philips_sml001:f42905cb:0017880102139d00:battery_level updated to 57
2019-07-28 16:40:55.298 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Updating ZigBee channel state zigbee:philips_sml001:f42905cb:0017880102139d00:battery_level to 57
2019-07-28 16:40:55.298 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:55.300 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00 in 14430 seconds
2019-07-28 16:40:56.296 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Polling initialised at 7555366ms
2019-07-28 16:40:56.300 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102139D00: Done initialising ZigBee Thing handler
2019-07-28 16:40:56.302 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state
2019-07-28 16:40:56.308 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-07-28 16:40:56.448 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-07-28 16:40:56.451 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:56.453 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Canceling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:56.455 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00 in 14430 seconds
2019-07-28 16:40:56.458 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:56.459 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00 in 14430 seconds
2019-07-28 16:40:56.463 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:56.464 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Canceling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:56.466 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00 in 14430 seconds
2019-07-28 16:40:56.455 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00
2019-07-28 16:40:56.468 [DEBUG] [.zigbee.handler.ZigbeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:philips_sml001:f42905cb:0017880102139d00 in 14430 seconds