Zibee Yale YRD216 PBDB

najib_halimi · September 23, 2018, 4:27pm

I have a lock(YRD216) that is connected to openhab I want to be able to lock and unlock it from openhab.

currently I see the battery percentage and (on and off) status. I have not been able to unlock it or lock it

chris · September 23, 2018, 4:28pm

You should be able to send a command to the channel to unlock and lock it. If it’s not working, then please provide a full debug log of the initialisation of the binding. Please also ensure you use code fences when posting logs so they are readable.

najib_halimi · September 23, 2018, 4:31pm

What is the locking and unlocking command ?

chris · September 23, 2018, 4:32pm

You need to set the item to ON or OFF. ON will lock it, and OFF will unlock it.

najib_halimi · September 23, 2018, 4:37pm

When I toggle the switch from on to off and vice/versa the switch stays on the desired position but the lock dose not do anything.

the debug log registers two lines

2018-09-22 19:43:01.271 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-22 19:43:01.279 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate

chris · September 23, 2018, 4:38pm

Then please provide a full debug log of the initialisation of the binding. Please also ensure you use code fences when posting logs so they are readable!

najib_halimi · September 23, 2018, 5:06pm

I restarted the device and here is the log:

2018-09-23 09:59:06.078 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Stopped Paper UI
2018-09-23 09:59:06.101 [INFO ] [panel.internal.HABPanelDashboardTile] - Stopped HABPanel
2018-09-23 09:59:06.115 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Stopped Home Builder
2018-09-23 09:59:06.141 [INFO ] [.dashboard.internal.DashboardService] - Stopped Dashboard
2018-09-23 09:59:36.445 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=119, service.bundleid=196, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=8} - org.openhab.binding.zigbee.xbee
2018-09-23 09:59:36.473 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
2018-09-23 09:59:36.477 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
2018-09-23 09:59:36.519 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=120, service.bundleid=214, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=9} - org.openhab.binding.zigbee
2018-09-23 09:59:36.528 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTING - org.openhab.binding.zigbee
2018-09-23 09:59:36.533 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTED - org.openhab.binding.zigbee
2018-09-23 09:59:36.556 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService
2018-09-23 09:59:36.592 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=121, service.bundleid=214, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=10} - org.openhab.binding.zigbee
2018-09-23 09:59:36.597 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=122, service.bundleid=214, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=11} - org.openhab.binding.zigbee
2018-09-23 09:59:36.618 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee
2018-09-23 09:59:36.627 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee
2018-09-23 09:59:36.696 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=125, service.bundleid=199, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=17} - org.openhab.binding.zigbee.telegesis
2018-09-23 09:59:36.701 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=126, service.bundleid=199, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=18} - org.openhab.binding.zigbee.telegesis
2018-09-23 09:59:36.706 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis
2018-09-23 09:59:36.710 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis
2018-09-23 09:59:36.740 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
2018-09-23 09:59:36.750 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=127, service.bundleid=203, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=19} - org.openhab.binding.zigbee.cc2531
2018-09-23 09:59:36.755 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
2018-09-23 09:59:36.758 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
2018-09-23 09:59:36.765 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
2018-09-23 09:59:36.779 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=128, service.bundleid=205, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=20} - org.openhab.binding.zigbee.ember
2018-09-23 09:59:36.783 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=129, service.bundleid=205, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=21} - org.openhab.binding.zigbee.ember
2018-09-23 09:59:36.788 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
2018-09-23 09:59:36.792 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
2018-09-23 09:59:36.800 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
2018-09-23 09:59:39.073 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-09-23 09:59:39.380 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2018-09-23 09:59:39.883 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.15:8080
2018-09-23 09:59:39.891 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.15:8443
2018-09-23 10:00:00.206 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items'
2018-09-23 10:00:06.696 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.rules'
2018-09-23 10:00:07.090 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-09-23 10:00:07.716 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.sitemap'
2018-09-23 10:00:08.422 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=312, service.bundleid=196, service.scope=singleton} - org.openhab.binding.zigbee.xbee
2018-09-23 10:00:08.502 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - Initializing ZigBee XBee serial bridge handler.
2018-09-23 10:00:08.505 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_xbee:9abac4d2].
2018-09-23 10:00:08.508 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 17
2018-09-23 10:00:08.509 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 29449
2018-09-23 10:00:08.511 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 7560DD676CAEA780
2018-09-23 10:00:08.514 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-09-23 10:00:08.517 [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 10:00:08.523 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialised 3FBF4EE65E0C516642AA95CE4AF3BB22
2018-09-23 10:00:08.525 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key final array 3FBF4EE65E0C516642AA95CE4AF3BB22
2018-09-23 10:00:08.594 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - ZigBee XBee Coordinator opening Port:'/dev/ttyUSB0' PAN:7309, EPAN:7560DD676CAEA780, Channel:17
2018-09-23 10:00:08.605 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2018-09-23 10:00:08.637 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=313, service.bundleid=214, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 10:00:08.644 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=314, service.bundleid=214, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 10:00:08.706 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing ZigBee thing handler zigbee:device:9abac4d2:000d6f000f747372
2018-09-23 10:00:08.714 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator status changed to UNKNOWN.
2018-09-23 10:00:08.717 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator is unknown or not online.
2018-09-23 10:00:08.725 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel linked - polling started.
2018-09-23 10:00:08.725 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate linked - polling started.
2018-09-23 10:00:08.903 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2018-09-23 10:00:09.624 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-09-23 10:00:09.626 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-09-23 10:00:09.868 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise 3FBF4EE65E0C516642AA95CE4AF3BB22
2018-09-23 10:00:09.893 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 9600 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2018-09-23 10:00:09.992 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2018-09-23 10:00:10.606 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-09-23 10:00:10.711 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-09-23 10:00:14.713 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2018-09-23 10:00:15.084 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2018-09-23 10:00:15.238 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator status changed to ONLINE.
2018-09-23 10:00:15.241 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator is ONLINE. Starting device initialisation.
2018-09-23 10:00:15.290 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Start initialising ZigBee Thing handler
2018-09-23 10:00:15.301 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery start
2018-09-23 10:00:15.303 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery using 54597/1
2018-09-23 10:00:15.400 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise done. channel=CHANNEL_17, PanId=29449  EPanId=7560DD676CAEA780
2018-09-23 10:00:15.759 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:00:15.947 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:00:18.340 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: Stack version failed
2018-09-23 10:00:18.722 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:00:18.729 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:00:18.805 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:00:20.221 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: Date code failed
2018-09-23 10:00:20.231 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=MEDIUM, modelId=YRD216 PBDB, zigbee_networkaddress=54597, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, vendor=Yale, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, firmwareVersion=01020028, zigbee_neighbors=[], zigbee_devices=[]}
2018-09-23 10:00:20.291 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Checking endpoint 1 channels
2018-09-23 10:00:20.343 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Dynamically created 2 channels
2018-09-23 10:00:20.347 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryPercent@382d00
2018-09-23 10:00:20.348 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: Initialising device battery percent converter
2018-09-23 10:00:20.426 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:00:20.504 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:00:30.444 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:00:30.442 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:00:30.645 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:00:36.358 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterDoorLock@1766d6d
2018-09-23 10:00:36.363 [ERROR] [al.converter.ZigBeeConverterDoorLock] - 000D6F000F747372: Error opening device door lock controls
2018-09-23 10:00:36.366 [INFO ] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate failed to initialise
2018-09-23 10:00:36.369 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel initialisation complete
2018-09-23 10:00:44.383 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Error getting binding table
2018-09-23 10:00:44.402 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Polling initialised at 7820837ms
2018-09-23 10:00:44.406 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Done initialising ZigBee Thing handler
2018-09-23 10:00:44.424 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:00:44.538 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:00:47.518 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: ZigBee attribute reports ZclAttribute [cluster=POWER_CONFIGURATION, id=33, name=BatteryPercentageRemaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=146, lastReportTime=Sun Sep 23 10:00:47 PDT 2018]
2018-09-23 10:00:47.524 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel updated to 73
2018-09-23 10:00:47.527 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Updating ZigBee channel state zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel to 73
2018-09-23 10:01:26.808 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:01:26.818 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:01:26.939 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:02:28.460 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 10:02:28.468 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:02:40.426 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 10:02:40.434 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:02:53.115 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 10:02:53.121 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate

chris · September 23, 2018, 5:09pm

There should be an XML in your {userdata}/zigbee folder - please can you provide this?

najib_halimi · September 23, 2018, 5:15pm

zigbee-network–zigbee_coordinator_xbee_9abac4d2.xml (116.0 KB)

chris · September 23, 2018, 5:38pm

Please try this version. If it still doesn’t work, then please provide a new log of the startup.

najib_halimi · September 23, 2018, 5:57pm

I dropped the jar and got error then restarted the device the log is from the first drop

2018-09-23 10:45:36.212 [ERROR] [org.openhab.binding.zigbee          ] - FrameworkEvent ERROR - org.openhab.binding.zigbee
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.zigbee [197]
  Another singleton bundle selected: osgi.identity; type="osgi.bundle"; version:Version="2.4.0.201809142249"; osgi.identity="org.openhab.binding.zigbee"; singleton:="true"

	at org.eclipse.osgi.container.Module.start(Module.java:444) ~[?:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1634) ~[?:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1613) ~[?:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1585) ~[?:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1528) ~[?:?]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1) ~[?:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [?:?]
	at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340) [?:?]
2018-09-23 10:45:36.246 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
2018-09-23 10:45:36.263 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=125, service.bundleid=203, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=14} - org.openhab.binding.zigbee.cc2531
2018-09-23 10:45:36.269 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
2018-09-23 10:45:36.273 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
2018-09-23 10:45:36.280 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
2018-09-23 10:45:36.297 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=126, service.bundleid=205, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=15} - org.openhab.binding.zigbee.ember
2018-09-23 10:45:36.301 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=127, service.bundleid=205, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=16} - org.openhab.binding.zigbee.ember
2018-09-23 10:45:36.305 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
2018-09-23 10:45:36.309 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
2018-09-23 10:45:36.317 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
2018-09-23 10:45:38.468 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-09-23 10:45:38.524 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2018-09-23 10:45:39.129 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.15:8080
2018-09-23 10:45:39.134 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.15:8443
2018-09-23 10:45:48.338 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items'
2018-09-23 10:45:58.029 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.rules'
2018-09-23 10:45:58.315 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-09-23 10:45:59.653 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.sitemap'
2018-09-23 10:46:00.373 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=308, service.bundleid=196, service.scope=singleton} - org.openhab.binding.zigbee.xbee
2018-09-23 10:46:00.446 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - Initializing ZigBee XBee serial bridge handler.
2018-09-23 10:46:00.448 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_xbee:9abac4d2].
2018-09-23 10:46:00.451 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 17
2018-09-23 10:46:00.453 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 29449
2018-09-23 10:46:00.454 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 7560DD676CAEA780
2018-09-23 10:46:00.458 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-09-23 10:46:00.460 [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 10:46:00.467 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialised E9D63BB1D8905A1714AD2FC08D6E4D12
2018-09-23 10:46:00.469 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key final array E9D63BB1D8905A1714AD2FC08D6E4D12
2018-09-23 10:46:00.536 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - ZigBee XBee Coordinator opening Port:'/dev/ttyUSB0' PAN:7309, EPAN:7560DD676CAEA780, Channel:17
2018-09-23 10:46:00.545 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2018-09-23 10:46:00.569 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=309, service.bundleid=214, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 10:46:00.576 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=310, service.bundleid=214, service.scope=singleton} - org.openhab.binding.zigbee
2018-09-23 10:46:00.630 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing ZigBee thing handler zigbee:device:9abac4d2:000d6f000f747372
2018-09-23 10:46:00.642 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator status changed to UNKNOWN.
2018-09-23 10:46:00.644 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator is unknown or not online.
2018-09-23 10:46:00.651 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel linked - polling started.
2018-09-23 10:46:00.669 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate linked - polling started.
2018-09-23 10:46:00.833 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2018-09-23 10:46:01.550 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-09-23 10:46:01.552 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-09-23 10:46:01.805 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise E9D63BB1D8905A1714AD2FC08D6E4D12
2018-09-23 10:46:01.821 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 9600 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2018-09-23 10:46:01.959 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2018-09-23 10:46:02.475 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-09-23 10:46:02.588 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-09-23 10:46:06.670 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2018-09-23 10:46:07.095 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2018-09-23 10:46:07.283 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator status changed to ONLINE.
2018-09-23 10:46:07.288 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Coordinator is ONLINE. Starting device initialisation.
2018-09-23 10:46:07.324 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Start initialising ZigBee Thing handler
2018-09-23 10:46:07.336 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery start
2018-09-23 10:46:07.339 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery using 54597/1
2018-09-23 10:46:07.436 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise done. channel=CHANNEL_17, PanId=29449  EPanId=7560DD676CAEA780
2018-09-23 10:46:08.820 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:46:09.040 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:46:10.550 [WARN ] [org.apache.felix.fileinstall        ] - Error while starting bundle: file:/usr/share/openhab2/addons/org.openhab.binding.zwave-2.4.0-SNAPSHOT.jar
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.zigbee [197]
  Another singleton bundle selected: osgi.identity; type="osgi.bundle"; version:Version="2.4.0.201809142249"; osgi.identity="org.openhab.binding.zigbee"; singleton:="true"

	at org.eclipse.osgi.container.Module.start(Module.java:444) ~[?:?]
	at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) ~[?:?]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [10:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [10:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startAllBundles(DirectoryWatcher.java:1221) [10:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:515) [10:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [10:org.apache.felix.fileinstall:3.6.4]
	at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [10:org.apache.felix.fileinstall:3.6.4]
2018-09-23 10:46:15.346 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: Stack version failed
2018-09-23 10:46:23.354 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: Date code failed
2018-09-23 10:46:23.360 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F000F747372: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=MEDIUM, modelId=YRD216 PBDB, zigbee_networkaddress=54597, zigbee_powersource=DISPOSABLE_BATTERY, zigbee_zclversion=1, zigbee_routes=[], zigbee_lastupdate=, vendor=Yale, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[DISPOSABLE_BATTERY], hardwareVersion=2, firmwareVersion=01020028, zigbee_neighbors=[], zigbee_devices=[]}
2018-09-23 10:46:23.418 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Checking endpoint 1 channels
2018-09-23 10:46:23.479 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Dynamically created 2 channels
2018-09-23 10:46:23.483 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryPercent@19756cd
2018-09-23 10:46:23.485 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: Initialising device battery percent converter
2018-09-23 10:46:39.495 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Initializing channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterDoorLock@17e2172
2018-09-23 10:46:39.499 [ERROR] [al.converter.ZigBeeConverterDoorLock] - 000D6F000F747372: Error opening device door lock controls
2018-09-23 10:46:39.503 [INFO ] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate failed to initialise
2018-09-23 10:46:39.506 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Channel initialisation complete
2018-09-23 10:46:43.813 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:46:43.815 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:46:43.900 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:46:47.514 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Error getting binding table
2018-09-23 10:46:47.521 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Polling initialised at 7840997ms
2018-09-23 10:46:47.526 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Done initialising ZigBee Thing handler
2018-09-23 10:46:47.536 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:46:47.612 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:46:56.793 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:46:56.795 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:46:56.911 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:47:00.294 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: ZigBee attribute reports ZclAttribute [cluster=POWER_CONFIGURATION, id=33, name=BatteryPercentageRemaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=146, lastReportTime=Sun Sep 23 10:47:00 PDT 2018]
2018-09-23 10:47:00.298 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel updated to 73
2018-09-23 10:47:00.302 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Updating ZigBee channel state zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel to 73
2018-09-23 10:47:05.400 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:47:05.497 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:47:10.493 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 10:47:10.497 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:47:23.430 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 10:47:23.433 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:47:31.939 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 10:47:31.963 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:47:36.044 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 10:47:36.053 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:49:15.441 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> OFF
2018-09-23 10:49:15.447 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:49:24.489 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Command for channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate --> ON
2018-09-23 10:49:24.500 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: No handler found for zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_doorlockstate
2018-09-23 10:50:34.602 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:50:34.613 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:50:34.710 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Polling...
2018-09-23 10:50:34.713 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Polling zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel
2018-09-23 10:50:34.752 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:50:35.259 [DEBUG] [verter.ZigBeeConverterBatteryPercent] - 000D6F000F747372: ZigBee attribute reports ZclAttribute [cluster=POWER_CONFIGURATION, id=33, name=BatteryPercentageRemaining, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=146, lastReportTime=Sun Sep 23 10:50:35 PDT 2018]
2018-09-23 10:50:35.261 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 000D6F000F747372: Channel zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel updated to 73
2018-09-23 10:50:35.263 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Updating ZigBee channel state zigbee:device:9abac4d2:000d6f000f747372:000D6F000F747372_1_batterylevel to 73
2018-09-23 10:51:01.790 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:51:01.799 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:51:01.932 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:51:10.178 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:51:10.278 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:51:58.169 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:51:58.178 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:51:58.296 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:52:00.312 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:52:00.451 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:53:33.517 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000F747372: Node updated - ZigBeeNode [IEEE=000D6F000F747372, NWK=D545, Type=END_DEVICE]
2018-09-23 10:53:33.555 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:53:33.625 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-09-23 10:53:35.490 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-09-23 10:53:35.626 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

chris · September 23, 2018, 5:59pm

You have two bundles running.

najib_halimi · September 23, 2018, 6:46pm

ok

after fixing the bundle. I am not able to lock it but it will unlock using the on/off switch on the controls

how can i fix the locking

chris · September 23, 2018, 6:47pm

Please provide a log - without this it’s impossible really to comment. I’m sorry for having to keep asking, but as I don’t have a device to test against, I really need you to provide this feedback - otherwise I can’t help you. Sorry.

najib_halimi · September 23, 2018, 6:54pm

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.

chris · September 23, 2018, 7:05pm

Have you enabled debug logging for com.zsmartsystems.zigbee? I don’t see any zigbee data being logged - only the top level data from the binding, but this is not useful unfortunately.

najib_halimi · September 23, 2018, 7:12pm

how will I do that?

chris · September 23, 2018, 7:18pm

Just enable debug logging for this namespace. See also the docs -:

najib_halimi · September 24, 2018, 2:55am

Chris,

I got it working. Both locking and unlocking works now.

I am not sure how to thank you for your time and efforts. All I could say thanks a-lot

Will you please make sure that the latest changes to the jar you provided me today is included in the final release.

chris · September 24, 2018, 6:38am

The changes are in this PR -: