Zigbee - XBee - Coordinator stuck on UNKNOWN

First of all thank you to all people helping out the community. I’ve been “working” with openHAB since a year and only now stumbled on a problem that I could not fix with the answers already in the forum.

That being said, I really am stuck…

Last year or so I bought the XBee Pro S2C on the sparkfun USB shield. After some testing and configuring I got it to work quite well and successfully managed to control my Tradfri and Innr Bulbs.

Only hickup, sometimes when I restarted the whole Pi, the lights would not come back online. Now I got myself some Raspberry Pi 3 B+ and transferred openHAB to it and updated to the latest version.

The update was itself was successful, but now my coordinator does not come back online… it stays on “UNKNOWN”. (Plugging the stick into my Windows PC and adding the same bulps works as I can see in XCTU)

  • So I deleted all Devices and Items and uninstalled the binding. That did not help.
  • Switching back to the Raspberry 3 B did not help either.
  • Changing Baudrate and Flow control did not help ( 9600 with Hardware and 57600 with Software)
  • Manually defining the Controller as thing did not help.
Bridge zigbee:coordinator_xbee:7e898e23 "Zigbee coordinator" [ zigbee_port="/dev/ttyUSB0", zigbee_baud=9600, zigbee_flowcontrol=1, zigbee_macAddress="XXXXXXXXXXXXXXXX", zigbee_channel=12, zigbee_panid=29411, zigbee_extendedpanid="C18C" ] {
}

or

Bridge zigbee:coordinator_xbee:7e898e23 "Zigbee coordinator" [ zigbee_port="/dev/ttyUSB0", zigbee_baud=57600, zigbee_flowcontrol=2, zigbee_macAddress="XXXXXXXXXXXXXXXX", zigbee_channel=12, zigbee_panid=29411, zigbee_extendedpanid="C18C" ] {
}
  • Platform information:
    • Hardware: Raspbery Pi 3 B and B+
    • OS: Raspbian Lite
    • openHAB version: 2.4.0-1
    • ZigBee Binding - 2.4.0

I Increased the log level.

openhab.log

2019-01-04 14:04:03.883 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=141, service.bundleid=273, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=35} - org.openhab.binding.zigbee
2019-01-04 14:04:03.904 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTING - org.openhab.binding.zigbee
2019-01-04 14:04:03.906 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTED - org.openhab.binding.zigbee
2019-01-04 14:04:03.913 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService
2019-01-04 14:04:03.936 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory}={service.id=142, service.bundleid=273, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory, component.id=36} - org.openhab.binding.zigbee
2019-01-04 14:04:04.002 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=143, service.bundleid=273, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=37} - org.openhab.binding.zigbee
2019-01-04 14:04:04.018 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=144, service.bundleid=273, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=38} - org.openhab.binding.zigbee
2019-01-04 14:04:04.096 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.converter.ZigBeeChannelConverterProvider}={service.id=145, service.bundleid=273, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeDefaultChannelConverterProvider, component.id=39} - org.openhab.binding.zigbee
2019-01-04 14:04:04.100 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee
2019-01-04 14:04:04.110 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=146, service.bundleid=274, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=40} - org.openhab.binding.zigbee.cc2531
2019-01-04 14:04:04.114 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
2019-01-04 14:04:04.116 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
2019-01-04 14:04:04.123 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
2019-01-04 14:04:04.137 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=147, service.bundleid=275, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=41} - org.openhab.binding.zigbee.ember
2019-01-04 14:04:04.141 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=148, service.bundleid=275, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=42} - org.openhab.binding.zigbee.ember
2019-01-04 14:04:04.145 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
2019-01-04 14:04:04.147 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
2019-01-04 14:04:04.155 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
2019-01-04 14:04:04.170 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=149, service.bundleid=276, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=43} - org.openhab.binding.zigbee.telegesis
2019-01-04 14:04:04.174 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis
2019-01-04 14:04:04.176 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis
2019-01-04 14:04:04.185 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=150, service.bundleid=276, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=44} - org.openhab.binding.zigbee.telegesis
2019-01-04 14:04:04.187 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
2019-01-04 14:04:04.197 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=151, service.bundleid=277, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=45} - org.openhab.binding.zigbee.xbee
2019-01-04 14:04:04.207 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
2019-01-04 14:04:04.209 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
2019-01-04 14:04:04.217 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee
2019-01-04 14:04:05.390 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
2019-01-04 14:04:27.766 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2019-01-04 14:04:45.237 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Europe/Berlin'.
2019-01-04 14:04:45.247 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to 'xxx,xxx'.
2019-01-04 14:05:28.996 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTING - com.zsmartsystems.zigbee
2019-01-04 14:05:29.002 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.rules'
2019-01-04 14:05:29.015 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTED - com.zsmartsystems.zigbee
2019-01-04 14:05:29.036 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.xbee
2019-01-04 14:05:29.042 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.xbee
2019-01-04 14:05:29.333 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2019-01-04 14:05:29.419 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.telegesis
2019-01-04 14:05:29.426 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.telegesis
2019-01-04 14:05:31.137 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'zigbee.things'
2019-01-04 14:05:31.194 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.cc2531
2019-01-04 14:05:31.211 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.cc2531
2019-01-04 14:05:31.330 [INFO ] [b.core.service.AbstractActiveService] - Expire Refresh Service has been started
2019-01-04 14:05:31.469 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP request packet listener online
2019-01-04 14:05:31.532 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://xxx:8080
2019-01-04 14:05:31.536 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://xxx:8443
2019-01-04 14:05:31.571 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.ember
2019-01-04 14:05:31.593 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.ember
2019-01-04 14:05:32.146 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
2019-01-04 14:05:33.783 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=426, service.bundleid=277, service.scope=singleton} - org.openhab.binding.zigbee.xbee
2019-01-04 14:05:33.944 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - Initializing ZigBee XBee serial bridge handler.
2019-01-04 14:05:33.950 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_xbee:9c22886c].
2019-01-04 14:05:33.952 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 0
2019-01-04 14:05:33.960 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 32133
2019-01-04 14:05:33.984 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID DCEE7E6267B649F3
2019-01-04 14:05:33.999 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 21AC91B8B2AF685EF5832A73511CB6CE
2019-01-04 14:05:34.001 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2019-01-04 14:05:34.003 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false
2019-01-04 14:05:34.004 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 21AC91B8B2AF685EF5832A73511CB6CE
2019-01-04 14:05:34.007 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 21AC91B8B2AF685EF5832A73511CB6CE
2019-01-04 14:05:34.011 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
2019-01-04 14:05:34.014 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
2019-01-04 14:05:34.044 [DEBUG] [ding.zigbee.xbee.handler.XBeeHandler] - ZigBee XBee Coordinator opening Port:'/dev/ttyUSB0' PAN:7d85, EPAN:DCEE7E6267B649F3, Channel:0
2019-01-04 14:05:34.063 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2019-01-04 14:05:35.069 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2019-01-04 14:05:35.071 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2019-01-04 14:05:35.137 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
2019-01-04 14:05:35.143 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
2019-01-04 14:05:35.266 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE
2019-01-04 14:05:35.269 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
2019-01-04 14:05:35.271 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE
2019-01-04 14:05:35.280 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2019-01-04 14:05:35.304 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2019-01-04 14:05:35.305 [DEBUG] [.zigbee.dongle.xbee.ZigBeeDongleXBee] - XBee dongle initialize.
2019-01-04 14:05:35.307 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.
2019-01-04 14:05:35.312 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to UNINITIALISED
2019-01-04 14:05:35.329 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=UNINITIALISED
2019-01-04 14:05:35.339 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2019-01-04 14:05:35.350 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - XBeeFrameHandler clearing receive buffer.
2019-01-04 14:05:35.452 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - XBeeFrameHandler cleared receive buffer.
2019-01-04 14:05:35.456 [DEBUG] [.zigbee.dongle.xbee.ZigBeeDongleXBee] - XBee dongle reset 1.
2019-01-04 14:05:35.456 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - XBeeFrameHandler thread started
2019-01-04 14:05:35.466 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetSoftwareResetCommand [frameId=1]
2019-01-04 14:05:35.468 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetSoftwareResetCommand [frameId=1]
2019-01-04 14:05:35.471 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 01 46 52 5E
2019-01-04 14:05:35.682 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 01 46 52 00 DE
2019-01-04 14:05:35.716 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeSoftwareResetResponse [frameId=1, commandStatus=OK]
2019-01-04 14:05:35.721 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 02 8A 00 75
2019-01-04 14:05:35.726 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeModemStatusEvent [status=HARDWARE_RESET]
2019-01-04 14:05:35.732 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 02 8A 06 6F
2019-01-04 14:05:35.734 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeModemStatusEvent [status=COORDINATOR_STARTED]
2019-01-04 14:05:35.734 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetApiEnableCommand [frameId=2, mode=2]
2019-01-04 14:05:35.736 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetApiEnableCommand [frameId=2, mode=2]
2019-01-04 14:05:35.739 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 05 08 02 41 50 02 62
2019-01-04 14:05:35.857 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 02 41 50 00 E4
2019-01-04 14:05:35.860 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeApiEnableResponse [frameId=2, commandStatus=OK, mode=null]
2019-01-04 14:05:35.863 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetApiModeCommand [frameId=3, mode=3]
2019-01-04 14:05:35.864 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetApiModeCommand [frameId=3, mode=3]
2019-01-04 14:05:35.868 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 05 08 03 41 4F 03 61
2019-01-04 14:05:35.986 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 03 41 4F 00 E4
2019-01-04 14:05:35.990 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeApiModeResponse [frameId=3, commandStatus=OK, mode=null]
2019-01-04 14:05:35.996 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetHardwareVersionCommand [frameId=4]
2019-01-04 14:05:35.999 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetHardwareVersionCommand [frameId=4]
2019-01-04 14:05:36.003 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 04 48 56 55
2019-01-04 14:05:36.112 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 07 88 04 48 56 00 2D 46 62
2019-01-04 14:05:36.115 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeHardwareVersionResponse [frameId=4, commandStatus=OK, hardwareVersion=2D 46]
2019-01-04 14:05:36.125 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetFirmwareVersionCommand [frameId=5]
2019-01-04 14:05:36.126 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetFirmwareVersionCommand [frameId=5]
2019-01-04 14:05:36.128 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 05 56 52 4A
2019-01-04 14:05:36.241 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 07 88 05 56 52 00 40 60 2A
2019-01-04 14:05:36.244 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeFirmwareVersionResponse [frameId=5, commandStatus=OK, firmwareVersion=40 60]
2019-01-04 14:05:36.248 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetDetailedVersionCommand [frameId=6]
2019-01-04 14:05:36.249 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetDetailedVersionCommand [frameId=6]
2019-01-04 14:05:36.252 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 06 56 4C 4F
2019-01-04 14:05:36.536 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 8B 88 06 56 4C 00 58 42 65 65 2D 50 52 4F 20 53 32 43 20 52 45 4C 45 3A 20 34 30 36 30 0D 42 75 69 6C 64 3A 20 53 65 70 20 32 32 20 32 30 31 37 20 30 38 3A 35 36 3A 34 35 0D 53 74 61 63 6B 3A 20 35 36 30 31 20 48 57 3A 20 32 44 34 36 20 4D 66 67 53 74 72 3A 20 30 30 31 32 46 46 34 30 46 46 46 46 46 46 46 46 0D 42 6F 6F 74 6C 6F 61 64 65 72 3A 20 34 36 43 36 20 43 6F 6D 70 69 6C 65 72 3A 20 37 30 33 30 30 30 31 0D 00 F8
2019-01-04 14:05:36.545 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeDetailedVersionResponse [frameId=6, commandStatus=OK, versionInfo=58 42 65 65 2D 50 52 4F 20 53 32 43 20 52 45 4C 45 3A 20 34 30 36 30 0D 42 75 69 6C 64 3A 20 53 65 70 20 32 32 20 32 30 31 37 20 30 38 3A 35 36 3A 34 35 0D 53 74 61 63 6B 3A 20 35 36 30 31 20 48 57 3A 20 32 44 34 36 20 4D 66 67 53 74 72 3A 20 30 30 31 32 46 46 34 30 46 46 46 46 46 46 46 46 0D 42 6F 6F 74 6C 6F 61 64 65 72 3A 20 34 36 43 36 20 43 6F 6D 70 69 6C 65 72 3A 20 37 30 33 30 30 30 31 0D 00]
2019-01-04 14:05:36.549 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetIeeeAddressHighCommand [frameId=7]
2019-01-04 14:05:36.550 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetIeeeAddressHighCommand [frameId=7]
2019-01-04 14:05:36.552 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 07 53 48 55
2019-01-04 14:05:36.668 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 09 88 07 53 48 00 00 13 A2 00 20
2019-01-04 14:05:36.671 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeIeeeAddressHighResponse [frameId=7, commandStatus=OK, ieeeAddress=00 13 A2 00]
2019-01-04 14:05:36.675 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetIeeeAddressLowCommand [frameId=8]
2019-01-04 14:05:36.676 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetIeeeAddressLowCommand [frameId=8]
2019-01-04 14:05:36.679 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 08 53 4C 50
2019-01-04 14:05:36.797 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 09 88 08 53 4C 00 41 64 1E 55 B8
2019-01-04 14:05:36.800 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeIeeeAddressLowResponse [frameId=8, commandStatus=OK, ieeeAddress=41 64 1E 55]
2019-01-04 14:05:36.802 [DEBUG] [.zigbee.dongle.xbee.ZigBeeDongleXBee] - XBee IeeeAddress=0013A20041641E55
2019-01-04 14:05:36.806 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetZigbeeStackProfileCommand [frameId=9, stackProfile=2]
2019-01-04 14:05:36.807 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetZigbeeStackProfileCommand [frameId=9, stackProfile=2]
2019-01-04 14:05:36.810 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 05 08 09 5A 53 02 3F
2019-01-04 14:05:36.923 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 09 5A 53 00 C1
2019-01-04 14:05:36.926 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeZigbeeStackProfileResponse [frameId=9, commandStatus=OK, stackProfile=null]
2019-01-04 14:05:36.930 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetEncryptionEnableCommand [frameId=10, enableEncryption=true]
2019-01-04 14:05:36.931 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetEncryptionEnableCommand [frameId=10, enableEncryption=true]
2019-01-04 14:05:36.933 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 05 08 0A 45 45 01 62
2019-01-04 14:05:37.052 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 0A 45 45 00 E3
2019-01-04 14:05:37.054 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeEncryptionEnableResponse [frameId=10, commandStatus=OK, enableEncryption=null]
2019-01-04 14:05:37.060 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetEncryptionOptionsCommand [frameId=11, encryptionOptions=[ENABLE_TRUST_CENTRE]]
2019-01-04 14:05:37.061 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetEncryptionOptionsCommand [frameId=11, encryptionOptions=[ENABLE_TRUST_CENTRE]]
2019-01-04 14:05:37.064 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 05 08 0B 45 4F 02 56
2019-01-04 14:05:37.189 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 0B 45 4F 00 D8
2019-01-04 14:05:37.212 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeEncryptionOptionsResponse [frameId=11, commandStatus=OK]
2019-01-04 14:05:37.216 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetCoordinatorEnableCommand [frameId=12, enable=true]
2019-01-04 14:05:37.224 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetCoordinatorEnableCommand [frameId=12, enable=true]
2019-01-04 14:05:37.226 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 05 08 0C 43 45 01 62
2019-01-04 14:05:37.341 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 0C 43 45 00 E3
2019-01-04 14:05:37.343 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeCoordinatorEnableResponse [frameId=12, commandStatus=OK, enable=null]
2019-01-04 14:05:37.349 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetNetworkKeyCommand [frameId=13, networkKey=00000000000000000000000000000000]
2019-01-04 14:05:37.351 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetNetworkKeyCommand [frameId=13, networkKey=00000000000000000000000000000000]
2019-01-04 14:05:37.354 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 14 08 0D 4E 4B 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 51
2019-01-04 14:05:37.468 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 0D 4E 4B 00 D1
2019-01-04 14:05:37.470 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeNetworkKeyResponse [frameId=13, commandStatus=OK]
2019-01-04 14:05:37.474 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetLinkKeyCommand [frameId=14, linkKey=5A6967426565416C6C69616E63653039]
2019-01-04 14:05:37.477 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetLinkKeyCommand [frameId=14, linkKey=5A6967426565416C6C69616E63653039]
2019-01-04 14:05:37.483 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 14 08 0E 4B 59 5A 69 67 42 65 65 41 6C 6C 69 61 6E 63 65 30 39 8D
2019-01-04 14:05:37.596 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 0E 4B 59 00 C5
2019-01-04 14:05:37.598 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeLinkKeyResponse [frameId=14, commandStatus=OK]
2019-01-04 14:05:37.601 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeSetSaveDataCommand [frameId=15]
2019-01-04 14:05:37.603 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeSetSaveDataCommand [frameId=15]
2019-01-04 14:05:37.605 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 0F 57 52 3F
2019-01-04 14:05:37.724 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 05 88 0F 57 52 00 BF
2019-01-04 14:05:37.727 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeSaveDataResponse [frameId=15, commandStatus=OK]
2019-01-04 14:05:37.729 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetPanIdCommand [frameId=16]
2019-01-04 14:05:37.731 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetPanIdCommand [frameId=16]
2019-01-04 14:05:37.733 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 10 4F 49 4F
2019-01-04 14:05:37.853 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 07 88 10 4F 49 00 EA 20 C5
2019-01-04 14:05:37.855 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeePanIdResponse [frameId=16, commandStatus=OK, panId=59936]
2019-01-04 14:05:37.858 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetExtendedPanIdCommand [frameId=17]
2019-01-04 14:05:37.860 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetExtendedPanIdCommand [frameId=17]
2019-01-04 14:05:37.862 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 11 4F 50 47
2019-01-04 14:05:37.982 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 0D 88 11 4F 50 00 00 00 00 00 00 02 94 11 20
2019-01-04 14:05:37.985 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeExtendedPanIdResponse [frameId=17, commandStatus=OK, extendedPanId=0000000000029411]
2019-01-04 14:05:37.987 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
2019-01-04 14:05:37.989 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetOperatingChannelCommand [frameId=18]
2019-01-04 14:05:37.989 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2019-01-04 14:05:37.990 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetOperatingChannelCommand [frameId=18]
2019-01-04 14:05:37.993 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 12 43 48 5A
2019-01-04 14:05:38.110 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 06 88 12 43 48 00 0C CE
2019-01-04 14:05:38.112 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeOperatingChannelResponse [frameId=18, commandStatus=OK, channel=12]
2019-01-04 14:05:38.117 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_12, PanID=59936, EPanId=0000000000029411
2019-01-04 14:05:38.120 [DEBUG] [.zigbee.dongle.xbee.ZigBeeDongleXBee] - XBee dongle startup.
2019-01-04 14:05:38.121 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeGetOperatingChannelCommand [frameId=19]
2019-01-04 14:05:38.121 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to ONLINE
2019-01-04 14:05:38.123 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeGetOperatingChannelCommand [frameId=19]
2019-01-04 14:05:38.126 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 04 08 13 43 48 59
2019-01-04 14:05:38.240 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 06 88 13 43 48 00 0C CD
2019-01-04 14:05:38.242 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeOperatingChannelResponse [frameId=19, commandStatus=OK, channel=12]
2019-01-04 14:05:38.246 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise done. channel=CHANNEL_12, PanId=59936  EPanId=0000000000029411
2019-01-04 14:05:38.275 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
2019-01-04 14:05:38.278 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE

event.log

2019-01-04 14:05:33.921 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_xbee:9c22886c' changed from UNINITIALIZED to INITIALIZING
2019-01-04 14:05:34.113 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_xbee:9c22886c' changed from INITIALIZING to UNKNOWN
2019-01-04 14:05:38.275 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_xbee:9c22886c' has been updated.

And then nothing happens…
Any suggestions?

Update this is what happens if I start the discovery

2019-01-04 15:28:46.583 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_xbee:9c22886c
2019-01-04 15:28:46.596 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee coordinator is offline - aborted scan for zigbee:coordinator_xbee:9c22886c

An this is what happens if I then reset a bulb to reconnect:

2019-01-04 15:29:36.695 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 1E 91 00 0B 57 FF FE C0 C0 1B F9 87 00 00 00 13 00 00 02 81 87 F9 1B C0 C0 FE FF 57 0B 00 8E 56
2019-01-04 15:29:36.718 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeReceivePacketExplicitEvent [ieeeAddress=000B57FFFEC0C01B, networkAddress=63879, sourceEndpoint=0, destinationEndpoint=0, clusterId=19, profileId=0, receiveOptions=PACKET_BROADCAST, data=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:36.732 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=63879/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:36.885 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [63879/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=63879, ieeeAddr=000B57FFFEC0C01B, capability=142]
2019-01-04 15:29:37.344 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 1E 91 00 0B 57 FF FE C0 C0 1B F9 87 00 00 00 13 00 00 02 81 87 F9 1B C0 C0 FE FF 57 0B 00 8E 56
2019-01-04 15:29:37.348 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeReceivePacketExplicitEvent [ieeeAddress=000B57FFFEC0C01B, networkAddress=63879, sourceEndpoint=0, destinationEndpoint=0, clusterId=19, profileId=0, receiveOptions=PACKET_BROADCAST, data=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:37.351 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=63879/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:37.354 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [63879/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=63879, ieeeAddr=000B57FFFEC0C01B, capability=142]
2019-01-04 15:29:38.035 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 1E 91 00 0B 57 FF FE C0 C0 1B F9 87 00 00 00 13 00 00 02 81 87 F9 1B C0 C0 FE FF 57 0B 00 8E 56
2019-01-04 15:29:38.041 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeReceivePacketExplicitEvent [ieeeAddress=000B57FFFEC0C01B, networkAddress=63879, sourceEndpoint=0, destinationEndpoint=0, clusterId=19, profileId=0, receiveOptions=PACKET_BROADCAST, data=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:38.046 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=63879/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:38.051 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [63879/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=63879, ieeeAddr=000B57FFFEC0C01B, capability=142]
2019-01-04 15:29:38.722 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 1E 91 00 0B 57 FF FE C0 C0 1B F9 87 00 00 00 13 00 00 02 81 87 F9 1B C0 C0 FE FF 57 0B 00 8E 56
2019-01-04 15:29:38.728 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeReceivePacketExplicitEvent [ieeeAddress=000B57FFFEC0C01B, networkAddress=63879, sourceEndpoint=0, destinationEndpoint=0, clusterId=19, profileId=0, receiveOptions=PACKET_BROADCAST, data=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:38.732 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=63879/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:38.737 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [63879/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=63879, ieeeAddr=000B57FFFEC0C01B, capability=142]
2019-01-04 15:29:40.656 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 1E 91 00 0B 57 FF FE C0 C0 1B F9 87 00 00 00 13 00 00 02 81 87 F9 1B C0 C0 FE FF 57 0B 00 8E 56
2019-01-04 15:29:40.659 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeReceivePacketExplicitEvent [ieeeAddress=000B57FFFEC0C01B, networkAddress=63879, sourceEndpoint=0, destinationEndpoint=0, clusterId=19, profileId=0, receiveOptions=PACKET_BROADCAST, data=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:40.664 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=63879/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=81 87 F9 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:40.667 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [63879/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=63879, ieeeAddr=000B57FFFEC0C01B, capability=142]
2019-01-04 15:29:49.977 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 1E 91 00 0B 57 FF FE C0 C0 1B FD 31 00 00 00 13 00 00 02 81 31 FD 1B C0 C0 FE FF 57 0B 00 8E FA
2019-01-04 15:29:49.982 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeReceivePacketExplicitEvent [ieeeAddress=000B57FFFEC0C01B, networkAddress=64817, sourceEndpoint=0, destinationEndpoint=0, clusterId=19, profileId=0, receiveOptions=PACKET_BROADCAST, data=81 31 FD 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:49.986 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=64817/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=81 31 FD 1B C0 C0 FE FF 57 0B 00 8E]
2019-01-04 15:29:49.990 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [64817/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=64817, ieeeAddr=000B57FFFEC0C01B, capability=142]
2019-01-04 15:31:37.807 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 1B 91 00 0B 57 FF FE C0 C0 1B FD 31 00 00 00 06 00 00 02 01 FD FF 04 01 01 19 00 00 22
2019-01-04 15:31:37.818 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeReceivePacketExplicitEvent [ieeeAddress=000B57FFFEC0C01B, networkAddress=64817, sourceEndpoint=0, destinationEndpoint=0, clusterId=6, profileId=0, receiveOptions=PACKET_BROADCAST, data=01 FD FF 04 01 01 19 00 00]
2019-01-04 15:31:37.822 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=64817/0, destinationAddress=0/0, profile=0000, cluster=6, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=01 FD FF 04 01 01 19 00 00]
2019-01-04 15:31:37.828 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: MatchDescriptorRequest [64817/0 -> 0/0, cluster=0006, TID=NULL, nwkAddrOfInterest=65533, profileId=260, inClusterList=[25], outClusterList=[]]
2019-01-04 15:31:37.833 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 0000000000029411: ClusterMatcher received request MatchDescriptorRequest [64817/0 -> 0/0, cluster=0006, TID=NULL, nwkAddrOfInterest=65533, profileId=260, inClusterList=[25], outClusterList=[]]
2019-01-04 15:31:37.842 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 0000000000029411: ClusterMatcher sending match MatchDescriptorResponse [null -> 64817/0, cluster=8006, TID=NULL, status=SUCCESS, nwkAddrOfInterest=65533, matchList=[1]]
2019-01-04 15:31:37.844 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MatchDescriptorResponse [0/0 -> 64817/0, cluster=8006, TID=00, status=SUCCESS, nwkAddrOfInterest=65533, matchList=[1]]
2019-01-04 15:31:37.864 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=64817/0, profile=0000, cluster=32774, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=0, payload=00 00 FD FF 01 01]
2019-01-04 15:31:37.873 [DEBUG] [.zigbee.dongle.xbee.ZigBeeDongleXBee] - XBee send: XBeeTransmitRequestExplicitCommand [frameId=null, ieeeAddress=FFFFFFFFFFFFFFFF, networkAddress=64817, sourceEndpoint=0, destinationEndpoint=0, cluster=32774, profileId=0, broadcastRadius=0, options=[], data=00 00 FD FF 01 01]
2019-01-04 15:31:37.878 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE queue: 1: XBeeTransmitRequestExplicitCommand [frameId=20, ieeeAddress=FFFFFFFFFFFFFFFF, networkAddress=64817, sourceEndpoint=0, destinationEndpoint=0, cluster=32774, profileId=0, broadcastRadius=0, options=[], data=00 00 FD FF 01 01]
2019-01-04 15:31:37.882 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE: XBeeTransmitRequestExplicitCommand [frameId=20, ieeeAddress=FFFFFFFFFFFFFFFF, networkAddress=64817, sourceEndpoint=0, destinationEndpoint=0, cluster=32774, profileId=0, broadcastRadius=0, options=[], data=00 00 FD FF 01 01]
2019-01-04 15:31:37.890 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - TX XBEE Data: 00 1A 11 14 FF FF FF FF FF FF FF FF FD 31 00 00 80 06 00 00 00 00 00 00 FD FF 01 01 30
2019-01-04 15:31:38.185 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE Data: 00 07 8B 14 FD 31 00 00 00 32
2019-01-04 15:31:38.198 [DEBUG] [ongle.xbee.internal.XBeeFrameHandler] - RX XBEE: XBeeTransmitStatusResponse [frameId=20, networkAddress=64817, transmitRetryCount=0, deliveryStatus=SUCCESS, discoveryStatus=NO_DISCOVERY_OVERHEAD]

Have you set everything up and fixed permissions with the openhabian-config tool?

I just re did it (Because I did not do it after the update) but it had no effect.

Have you checked the EXTRA_JAVA_OPTS in /etc/default/openhab2 to add your ttyUSB0?

I did not change it, but is was already set:

EXTRA_JAVA_OPTS="-Dgnu.io.rxtx.SerialPorts=/dev/ttyUSB0:/dev/ttyS0:/dev/ttyS2:/dev/ttyACM0:/dev/ttyAMA0"

Try changing the coordinator to use /dev/ttyACM0 and see what happens.

I tried re-plugging the dongle but only ttyusb0 was shown in Paper UI. Changing it in the things file did result in: OFFLINE - COMMUNICATION_ERROR

I wouldn’t mess with the TTY configuration. The port is opening fine, and from the log the serial port is clearly working as there is communication to and from the device. The network state comes up to ONLINE, and it looks like devices have joined the network.

Let’s go right back to the beginning -:

Please provide a screenshot showing where/what exactly says UNKNOWN?

The Coordinator Thing in Paper UI said that:

What binding version are you using?

The latest that gets installed when you install the binding today via Paper UI.

Ok, so you will need to upgrade to the latest version.

Please see the following thread which has a script to update. This can load the latest libraries - the latest version is 1.1.8 (I think the script will ask for this). This should solve your problem as the 2.4 release has a bug with the XBee coordinator.

Great. Thank you.
Would you have a link to that threat? :slight_smile:

Oops - apologies. I meant to paste it in earlier…

1 Like

That fixed it. Thanks a lot.

I had to go the manual way and download
2.5.0 Zigbee Binding

2.5.0 XBee Binding

Smartsystems Zigbee Jar 1.1.8

Smartsystems XBee Jar 1.1.8

manually and copy them to the addons folder, since I could not figure out how to run the bash with openhab.

But now it works again !!! :+1:

1 Like

Hello, could you help me I have an equivalent error:
2019-02-14 03:23:11.514 [hingStatusInfoChangedEvent] - ‘zigbee:coordinator_xbee:bf3b443d’ changed from UNINITIALIZED to INITIALIZING

2019-02-14 03:23:11.549 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_xbee:bf3b443d' has been updated.

2019-02-14 03:23:11.578 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_xbee:bf3b443d' has been updated.

2019-02-14 03:23:11.605 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_xbee:bf3b443d' has been updated.

2019-02-14 03:23:11.633 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_xbee:bf3b443d' has been updated.

2019-02-14 03:23:11.637 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_xbee:bf3b443d' changed from INITIALIZING to UNKNOWN

==> /var/log/openhab2/openhab.log <==

2019-02-14 03:23:12.743 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB2 in use.

2019-02-14 03:23:12.746 [ERROR] [.zigbee.dongle.xbee.ZigBeeDongleXBee] - Unable to open XBee serial port

==> /var/log/openhab2/events.log <==

2019-02-14 03:23:12.756 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_xbee:bf3b443d' changed from UNKNOWN to OFFLINE: Failed to open communications port

2019-02-14 03:23:12.769 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_xbee:bf3b443d' changed from OFFLINE: Failed to open communications port to OFFLINE (COMMUNICATION_ERROR)

thank you !

Take a look at your log - it shows that the serial port can’t be opened.

Yes but I don’t know why. Here is another problem I have, the xbee USB key is detected as an enocean binding (USB 300)? But I do have the enocean USB 300 on another port: the ttyUSB1 port.


In fact here is my mapping for the serial ports:

cat /etc/udev/rules.d/*
SUBSYSTEM=="input", GROUP="input", MODE="0660"
SUBSYSTEM=="i2c-dev", GROUP="i2c", MODE="0660"
SUBSYSTEM=="spidev", GROUP="spi", MODE="0660"
SUBSYSTEM=="bcm2835-gpiomem", GROUP="gpio", MODE="0660"

SUBSYSTEM=="gpio", GROUP="gpio", MODE="0660"
SUBSYSTEM=="gpio*", PROGRAM="/bin/sh -c '\
        chown -R root:gpio /sys/class/gpio && chmod -R 770 /sys/class/gpio;\
        chown -R root:gpio /sys/devices/virtual/gpio && chmod -R 770 /sys/devices/virtual/gpio;\
        chown -R root:gpio /sys$devpath && chmod -R 770 /sys$devpath\
'"

KERNEL=="ttyAMA[01]", PROGRAM="/bin/sh -c '\
        ALIASES=/proc/device-tree/aliases; \
        if cmp -s $ALIASES/uart0 $ALIASES/serial0; then \
                echo 0;\
        elif cmp -s $ALIASES/uart0 $ALIASES/serial1; then \
                echo 1; \
        else \
                exit 1; \
        fi\
'", SYMLINK+="serial%c"

KERNEL=="ttyS0", PROGRAM="/bin/sh -c '\
        ALIASES=/proc/device-tree/aliases; \
        if cmp -s $ALIASES/uart1 $ALIASES/serial0; then \
                echo 0; \
        elif cmp -s $ALIASES/uart1 $ALIASES/serial1; then \
                echo 1; \
        else \
                exit 1; \
        fi \
'", SYMLINK+="serial%c"
SUBSYSTEM=="tty", ATTRS{serial}=="FT2PT0KS", SYMLINK+="enocean0"
SUBSYSTEM=="tty", ATTRS{product}=="RFXtrx433XL", ATTRS{serial}=="DO2ZWA2T", SYMLINK+="rfxtrx0"
SUBSYSTEM=="tty", ATTRS{product}=="XStick", SYMLINK+="xbee0"

and here is the result:

ls -l /dev/enocean0
lrwxrwxrwx 1 root root 7 Feb 16 16:04 /dev/enocean0 -> ttyUSB1

ls -l /dev/xbee0
lrwxrwxrwx 1 root root 7 Feb 17 12:38 /dev/xbee0 -> ttyUSB2

ls -l /dev/rfxtrx0
lrwxrwxrwx 1 root root 7 Feb 16 16:04 /dev/rfxtrx0 -> ttyUSB0

And when I want to add the xbee USB key manually I don’t have the choice for the port:

When I do

sudo lsof | grep ttyUSB2

There is nothing

What should I do ?

Possibly it is the same problem - I suspct that maybe the Enocean is detecting this device as an Enocean dongle, and this might be holding the port.

Either way, this is a bug with the Enocean binding - if you don’t have Enocean, why do you have the binding installed?