Chris,
I
2018-09-23 11:43:49.206 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 11:44:00.855 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 11:44:03.386 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 11:44:08.439 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 11:44:23.289 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 11:44:38.494 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 11:45:06.527 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=52A8, Type=END_DEVICE]
2018-09-23 11:45:06.536 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:45:06.645 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:45:09.202 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:45:09.310 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:45:35.718 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: ZigBee attribute reports ZclAttribute [cluster=POWER_CONFIGURATION, id=33, name=BatteryPercentageRemaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=144, lastReportTime=Sun Sep 23 11:45:35 PDT 2018]
2018-09-23 11:45:35.723 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000F747372: Channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_batterylevel updated to 72
2018-09-23 11:45:35.726 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Updating ZigBee channel state zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_batterylevel to 72
2018-09-23 11:45:57.284 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=52A8, Type=END_DEVICE]
2018-09-23 11:45:57.294 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:45:57.439 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:45:59.248 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:45:59.389 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:46:45.467 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 11:46:47.506 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 11:47:08.787 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI
2018-09-23 11:47:15.045 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=371, service.bundleid=218, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=228} - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:15.058 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=372, service.bundleid=218, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=229} - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:15.088 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STOPPING - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:15.095 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STOPPED - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:15.106 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=365, service.bundleid=224, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=222} - org.openhab.binding.zigbee.ember
2018-09-23 11:47:15.119 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=366, service.bundleid=224, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=223} - org.openhab.binding.zigbee.ember
2018-09-23 11:47:15.150 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STOPPING - org.openhab.binding.zigbee.ember
2018-09-23 11:47:15.164 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STOPPED - org.openhab.binding.zigbee.ember
2018-09-23 11:47:15.221 [DEBUG] [org.openhab.binding.zigbee.xbee ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=373, service.bundleid=216, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=230} - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:15.229 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Handler dispose.
2018-09-23 11:47:15.231 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Polling stopped
2018-09-23 11:47:15.233 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: Closing power configuration cluster
2018-09-23 11:47:15.236 [DEBUG] [al.converter.ZigBeeConverterDoorLock] - 000D6F000F747372: Closing device door lock cluster
2018-09-23 11:47:15.253 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:47:15.328 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:47:15.340 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2018-09-23 11:47:15.342 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_xbee:098451d3] closed.
2018-09-23 11:47:15.347 [DEBUG] [org.openhab.binding.zigbee.xbee ] - ServiceEvent UNREGISTERING - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=374, service.bundleid=216, service.scope=singleton} - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:15.358 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STOPPING - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:15.361 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STOPPED - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:15.366 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=370, service.bundleid=222, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=227} - org.openhab.binding.zigbee.cc2531
2018-09-23 11:47:15.373 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STOPPING - org.openhab.binding.zigbee.cc2531
2018-09-23 11:47:15.376 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STOPPED - org.openhab.binding.zigbee.cc2531
2018-09-23 11:47:15.382 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=367, service.bundleid=219, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=224} - org.openhab.binding.zigbee
2018-09-23 11:47:15.388 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent UNREGISTERING - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=368, service.bundleid=219, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=225} - org.openhab.binding.zigbee
2018-09-23 11:47:15.396 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=369, service.bundleid=219, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=226} - org.openhab.binding.zigbee
2018-09-23 11:47:15.409 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=376, service.bundleid=219, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 11:47:15.414 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STOPPING - org.openhab.binding.zigbee
2018-09-23 11:47:15.417 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=375, service.bundleid=219, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 11:47:15.422 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STOPPED - org.openhab.binding.zigbee
2018-09-23 11:47:16.121 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Stopped Paper UI
2018-09-23 11:47:16.144 [INFO ] [panel.internal.HABPanelDashboardTile] - Stopped HABPanel
2018-09-23 11:47:16.160 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Stopped Home Builder
2018-09-23 11:47:16.187 [INFO ] [.dashboard.internal.DashboardService] - Stopped Dashboard
2018-09-23 11:47:48.604 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:48.634 [DEBUG] [org.openhab.binding.zigbee.xbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=123, service.bundleid=216, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=14} - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:48.644 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:48.649 [DEBUG] [org.openhab.binding.zigbee.xbee ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
2018-09-23 11:47:48.671 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee
2018-09-23 11:47:48.693 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=124, service.bundleid=219, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=15} - org.openhab.binding.zigbee
2018-09-23 11:47:48.700 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STARTING - org.openhab.binding.zigbee
2018-09-23 11:47:48.703 [DEBUG] [org.openhab.binding.zigbee ] - BundleEvent STARTED - org.openhab.binding.zigbee
2018-09-23 11:47:48.710 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService
2018-09-23 11:47:48.724 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=125, service.bundleid=219, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=16} - org.openhab.binding.zigbee
2018-09-23 11:47:48.749 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=126, service.bundleid=219, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=17} - org.openhab.binding.zigbee
2018-09-23 11:47:48.771 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:48.809 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=127, service.bundleid=218, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=18} - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:48.814 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=128, service.bundleid=218, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=19} - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:48.820 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:48.823 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis
2018-09-23 11:47:48.835 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
2018-09-23 11:47:48.846 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=129, service.bundleid=222, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=20} - org.openhab.binding.zigbee.cc2531
2018-09-23 11:47:48.851 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
2018-09-23 11:47:48.855 [DEBUG] [org.openhab.binding.zigbee.cc2531 ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
2018-09-23 11:47:48.864 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
2018-09-23 11:47:48.878 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=130, service.bundleid=224, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=21} - org.openhab.binding.zigbee.ember
2018-09-23 11:47:48.883 [DEBUG] [org.openhab.binding.zigbee.ember ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=131, service.bundleid=224, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=22} - org.openhab.binding.zigbee.ember
2018-09-23 11:47:48.888 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
2018-09-23 11:47:48.892 [DEBUG] [org.openhab.binding.zigbee.ember ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
2018-09-23 11:47:50.370 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2018-09-23 11:47:50.776 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-09-23 11:47:51.454 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.15:8080
2018-09-23 11:47:51.458 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.15:8443
2018-09-23 11:48:07.217 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items'
2018-09-23 11:48:13.582 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.rules'
2018-09-23 11:48:13.858 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-09-23 11:48:14.517 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.sitemap'
2018-09-23 11:48:15.962 [DEBUG] [org.openhab.binding.zigbee.xbee ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=305, service.bundleid=216, service.scope=singleton} - org.openhab.binding.zigbee.xbee
2018-09-23 11:48:16.023 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - Initializing ZigBee XBee serial bridge handler.
2018-09-23 11:48:16.025 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_xbee:098451d3].
2018-09-23 11:48:16.031 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 17
2018-09-23 11:48:16.033 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 31366
2018-09-23 11:48:16.035 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 1498E838ED8E5A5D
2018-09-23 11:48:16.040 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-09-23 11:48:16.042 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key String 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-09-23 11:48:16.049 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialised 19997FED77C707C6D35CCBF3C1ABE015
2018-09-23 11:48:16.052 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key final array 19997FED77C707C6D35CCBF3C1ABE015
2018-09-23 11:48:16.123 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - ZigBee XBee Coordinator opening Port:'/dev/ttyUSB0' PAN:7a86, EPAN:1498E838ED8E5A5D, Channel:17
2018-09-23 11:48:16.133 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2018-09-23 11:48:16.327 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2018-09-23 11:48:16.376 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=306, service.bundleid=219, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 11:48:16.387 [DEBUG] [org.openhab.binding.zigbee ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=307, service.bundleid=219, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 11:48:16.433 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing ZigBee thing handler zigbee:device:098451d3:000d6f000f747372
2018-09-23 11:48:16.442 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator status changed to UNKNOWN.
2018-09-23 11:48:16.444 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator is unknown or not online.
2018-09-23 11:48:17.139 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-09-23 11:48:17.142 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-09-23 11:48:17.461 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise 19997FED77C707C6D35CCBF3C1ABE015
2018-09-23 11:48:17.472 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 9600 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2018-09-23 11:48:17.559 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2018-09-23 11:48:17.988 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-09-23 11:48:18.102 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-09-23 11:48:18.581 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_batterylevel linked - polling started.
2018-09-23 11:48:18.583 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate linked - polling started.
2018-09-23 11:48:22.270 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2018-09-23 11:48:22.709 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2018-09-23 11:48:22.873 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator status changed to ONLINE.
2018-09-23 11:48:22.879 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator is ONLINE. Starting device initialisation.
2018-09-23 11:48:22.911 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Start initialising ZigBee Thing handler
2018-09-23 11:48:22.923 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery start
2018-09-23 11:48:22.926 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery using 21160/1
2018-09-23 11:48:23.018 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise done. channel=CHANNEL_17, PanId=29449 EPanId=7560DD676CAEA780
2018-09-23 11:48:30.938 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: Stack version failed
2018-09-23 11:48:32.784 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:48:33.025 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:48:35.522 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 11:48:35.525 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 11:48:36.989 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=52A8, Type=END_DEVICE]
2018-09-23 11:48:36.994 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:48:37.091 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:48:37.500 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=52A8, Type=END_DEVICE]
2018-09-23 11:48:37.508 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:48:37.636 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:48:37.993 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=52A8, Type=END_DEVICE]
2018-09-23 11:48:37.996 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:48:38.081 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:48:38.941 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: Date code failed
2018-09-23 11:48:38.948 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=MEDIUM, modelId=YRD216 PBDB, zigbee_networkaddress=21160, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, vendor=Yale, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, zigbee_neighbors=[], firmwareVersion=01020028, zigbee_devices=[]}
2018-09-23 11:48:39.030 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Checking endpoint 1 channels
2018-09-23 11:48:39.091 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Dynamically created 2 channels
2018-09-23 11:48:39.094 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_batterylevel with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryPercent@ab80fe
2018-09-23 11:48:39.097 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: Initialising device battery percent converter
2018-09-23 11:48:55.107 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterDoorLock@1533329
2018-09-23 11:49:00.761 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:49:00.898 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:49:02.243 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: ZigBee attribute reports ZclAttribute [cluster=POWER_CONFIGURATION, id=33, name=BatteryPercentageRemaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=144, lastReportTime=Sun Sep 23 11:49:02 PDT 2018]
2018-09-23 11:49:02.249 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000F747372: Channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_batterylevel updated to 72
2018-09-23 11:49:02.253 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Updating ZigBee channel state zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_batterylevel to 72
2018-09-23 11:49:04.540 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel initialisation complete
2018-09-23 11:49:05.163 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Polling initialised at 7319718ms
2018-09-23 11:49:05.168 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Done initialising ZigBee Thing handler
2018-09-23 11:49:05.179 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 11:49:05.276 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 11:49:16.913 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 11:49:31.177 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 11:50:09.118 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:098451d3:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
am realy sorry i got too excited when i saw the unlock feature work.