New user trying to set up HUSBZB ZigBee

  • Platform information:
    • Hardware: raspberry pi 3B+
    • OS: openHabian v1.5
    • Java Runtime Environment: the Zulu that is automatically installed with openHabian
    • openHAB version: v 2
  • Issue of the topic: I can not seem to get the HUSBZB to work.
    I installed the ZigBee binding> then went to Things (using Paper UI) > click the '+ ’ > click ZigBee binding > click add manually > click Ember EM35x : changed flow control to software and baud to 57600; The only port option is /dev/ttyAMA0 so I pick it (not sure why no ttyUSBx options).
    But the coordinator remains as “unknown” in the Things list and will not discover a ZigBee light that I try to add.
LOG
2019-12-31 19:52:36.117 [thome.event.ExtensionEvent] - Extension 'ui-dashboard' has been uninstalled.

2020-01-01 10:15:38.432 [thome.event.ExtensionEvent] - Extension 'binding-serial1' has been installed.

2020-01-01 10:17:31.178 [thome.event.ExtensionEvent] - Extension 'binding-zigbee' has been installed.

2020-01-01 10:22:35.667 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from UNINITIALIZED to INITIALIZING

2020-01-01 10:22:35.750 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_ember:df56b300' has been updated.

2020-01-01 10:22:35.767 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_ember:df56b300' has been updated.

2020-01-01 10:22:35.779 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_ember:df56b300' has been updated.

2020-01-01 10:22:35.792 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_ember:df56b300' has been updated.

2020-01-01 10:22:35.910 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from INITIALIZING to UNKNOWN

2020-01-01 10:22:35.918 [nt.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:coordinator_ember:df56b300 changed to UNKNOWN.

Did you add openhab to tty group? You can do this manually or use sudo openhabian-config tool and select “Apply Improvements” then pick the first 3 options e.g install needed packages, fix permissions, svr mounts.

Extra java opts?
Additionally it’s needed to allow the java environment to access the serial port of the connected peripheral. Therefore the following setting has to be added/adapted on your system in file /etc/default/openhab2 :

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

After that I would restart OH with sudo systemctl restart openhab2

This is the event log - it doesn’t really provide any information that allows us to see what is actually wrong. Can you provide the main log - preferably with debug enabled?

Thanks H102; I made the changes you suggested. Now I have a ttyUSB0 and ttyUSB1. I tried both and get same results as log below.
Thanks chris; I think this gets more of the log you suggest. But I have not yet found where to set for ‘debug’

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

2020-01-01 15:47:10.038 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007

2020-01-01 15:47:12.403 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.201:8080

2020-01-01 15:47:12.411 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.201:8443

2020-01-01 15:47:14.362 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin

2020-01-01 15:47:14.681 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui

2020-01-01 15:47:14.908 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel

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

2020-01-01 15:47:16.118 [hingStatusInfoChangedEvent] - ‘zigbee:coordinator_ember:df56b300’ changed from UNINITIALIZED to INITIALIZING

2020-01-01 15:47:16.298 [hingStatusInfoChangedEvent] - ‘zigbee:coordinator_ember:df56b300’ changed from INITIALIZING to UNKNOWN

2020-01-01 15:49:55.303 [nt.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:coordinator_ember:df56b300 changed to UNKNOWN.

Use the console to set a binding to DEBUG.

Thanks, but it seems I am still learning the terminology.
I am running a raspberry pi, and have been using the ‘Paper UI’ to interact with, and this is where I set up the ZigBee binding. I do not see a setting for DEBUG in the ZigBee binding.
When you say ‘console’ are you referring to Paper UI or the $OPENHAB_RUNTIME/bin/client that I can access using Putty to do a terminal logon to openhabian (https://www.openhab.org/docs/administration/console.html)?

Using Putty, ssh to OH and use sudo openhab-cli console to access karaf console. You will be prompted for a password and unless you have changed it the default is habopen.

Once in the console see the link for how to use commands.

Thanks to H102, with your help I’m making headway. In console I set ‘log:set DEBUG org.openhab.binding’.
Here is the DEBUG log after I did a restart:

020-01-02 07:54:31.485 [DEBUG] [ding.serial.internal.SerialActivator] - Serial binding has been stopped.

2020-01-02 07:54:31.803 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI

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

2020-01-02 07:54:46.087 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from UNKNOWN to UNINITIALIZED

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

2020-01-02 07:54:46.135 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN

2020-01-02 07:54:46.151 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE

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

2020-01-02 07:54:46.157 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from UNINITIALIZED to OFFLINE: Failed to open communications port

2020-01-02 07:54:46.163 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from OFFLINE: Failed to open communications port to OFFLINE (COMMUNICATION_ERROR)

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

2020-01-02 07:54:46.203 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.

2020-01-02 07:54:46.211 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_ember:df56b300] closed.

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

2020-01-02 07:54:46.298 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_rx_dat changed from NULL to 0

2020-01-02 07:54:46.303 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_tx_dat changed from NULL to 0

2020-01-02 07:54:46.305 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_rx_ack changed from NULL to 0

2020-01-02 07:54:46.308 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_tx_ack changed from NULL to 0

2020-01-02 07:54:46.310 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_rx_nak changed from NULL to 0

2020-01-02 07:54:46.313 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from OFFLINE (COMMUNICATION_ERROR) to UNINITIALIZED (HANDLER_MISSING_ERROR)

2020-01-02 07:54:46.315 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_tx_nak changed from NULL to 0

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

2020-01-02 07:54:46.611 [INFO ] [assic.internal.servlet.WebAppServlet] - Stopped Classic UI

2020-01-02 07:54:46.671 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Stopped HABmin servlet

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

2020-01-02 07:54:46.872 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_rx_ack => zigbee:coordinator_ember:df56b300:rx_ack' has been removed.

2020-01-02 07:54:46.874 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_tx_nak => zigbee:coordinator_ember:df56b300:tx_nak' has been removed.

2020-01-02 07:54:46.877 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_tx_dat => zigbee:coordinator_ember:df56b300:tx_dat' has been removed.

2020-01-02 07:54:46.880 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_tx_ack => zigbee:coordinator_ember:df56b300:tx_ack' has been removed.

2020-01-02 07:54:46.882 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_rx_dat => zigbee:coordinator_ember:df56b300:rx_dat' has been removed.

2020-01-02 07:54:46.885 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_rx_nak => zigbee:coordinator_ember:df56b300:rx_nak' has been removed.

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

2020-01-02 07:54:47.126 [INFO ] [panel.internal.HABPanelDashboardTile] - Stopped HABPanel

2020-01-02 07:54:47.162 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator

2020-01-02 07:54:47.165 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400

2020-01-02 07:54:47.170 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.

2020-01-02 07:54:47.171 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Stopped Paper UI

2020-01-02 07:54:47.175 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING

2020-01-02 07:54:47.194 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.

2020-01-02 07:54:47.215 [INFO ] [.dashboard.internal.DashboardService] - Stopped Dashboard

2020-01-02 07:55:14.300 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007

2020-01-02 07:55:16.848 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.201:8080

2020-01-02 07:55:16.853 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.201:8443

2020-01-02 07:55:17.055 [DEBUG] [ding.serial.internal.SerialActivator] - Serial binding has been started.

2020-01-02 07:55:18.927 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin

2020-01-02 07:55:19.189 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui

2020-01-02 07:55:19.398 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel

2020-01-02 07:55:20.007 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService

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

2020-01-02 07:55:20.635 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from UNINITIALIZED to INITIALIZING

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

2020-01-02 07:55:20.654 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.

2020-01-02 07:55:20.656 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:df56b300].

2020-01-02 07:55:20.658 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11

2020-01-02 07:55:20.660 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 35112

2020-01-02 07:55:20.662 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID CF5AB932B32CE585

2020-01-02 07:55:20.667 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 4A0BB91BD9CDC704E6D023CA43BB4F28

2020-01-02 07:55:20.669 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039

2020-01-02 07:55:20.671 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false

2020-01-02 07:55:20.674 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 4A0BB91BD9CDC704E6D023CA43BB4F28

2020-01-02 07:55:20.678 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 4A0BB91BD9CDC704E6D023CA43BB4F28

2020-01-02 07:55:20.680 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039

2020-01-02 07:55:20.683 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039

2020-01-02 07:55:20.782 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:8928, EPAN:CF5AB932B32CE585, Channel:11

2020-01-02 07:55:20.784 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timout set to (169 * 2^9) = 86528 seconds

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

2020-01-02 07:55:20.802 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from INITIALIZING to UNKNOWN

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

2020-01-02 07:55:20.801 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start

2020-01-02 07:55:21.815 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting

2020-01-02 07:55:21.819 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator

2020-01-02 07:55:21.901 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400

2020-01-02 07:55:21.932 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING

2020-01-02 07:55:21.933 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.

2020-01-02 07:55:21.974 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.

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

2020-01-02 07:56:20.562 [nt.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:coordinator_ember:df56b300 changed to UNKNOWN.

Did you add the EXTRA_JAVA_OPTS as posted above with the correct port?

Is openhab a member of tty and dialout groups? This can be done manually or use openhabian config tool and select fix permissions.

You may need to uninstall the binding, remove the stick, stop OH, clean the cache, start OH and after everything is back and running (will take a few minuets after cleaning cache) insert the stick and reinstall the binding.

RE: EXTRA_JAVA_OPTS
Yes I did add the line you posted (actually it was in the file but commented-out so I only had to uncomment) and there was another line EXTRA_JAVA_OPTS="-Xms250m -Xms350m" that I left in place. Should I have combined the two or commented it out?
RE: groups, Yes is a member of both.

It should be fine as is.

Have you uninstalled binding, cleaned cache, etc… yet?

This only provide a small amount of the information required since most of the magic happens within libraries that are not within this namespace. Please take a look at the binding documentation - there is a section on logging toward the bottom of the page if I remember correctly.

@Dave55: please post the output from dmesg -T|grep tty

@H102 - why are you focussing on the serial port here? I would strongly suggest to get the debug log as from what I can see above, the serial port is opening ok -:

2020-01-02 07:55:21.974 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.

@chris: Overlooked that in the log,:upside_down_face: guess I was focused on OP’s comment “The only port option is /dev/ttyAMA0 so I pick it (not sure why no ttyUSBx options).”

1 Like

The logs are your friend (didn’t Yoda say that ? :smile:).

I do understand though - there are a LOT of issues with getting serial ports running.

1 Like

@chris: Would setting a UDEV rule possibly help in this case?

For H102:

dmesg -T|grep tty
[Wed Jan  1 18:03:21 2020] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3f000000 vc_mem.mem_size=0x3f600000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=f6de938e-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[Wed Jan  1 18:03:21 2020] console [tty1] enabled
[Wed Jan  1 18:03:21 2020] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2
[Wed Jan  1 18:05:11 2020] usb 1-1.2: cp210x converter now attached to ttyUSB0
[Wed Jan  1 18:05:11 2020] usb 1-1.2: cp210x converter now attached to ttyUSB1
[Thu Jan  2 09:18:04 2020] cp210x ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
[Thu Jan  2 09:18:04 2020] cp210x ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
[Thu Jan  2 09:18:05 2020] cp210x ttyUSB0: failed get req 0x8 size 1 status: -32
[Thu Jan  2 09:18:05 2020] cp210x ttyUSB0: failed set request 0x12 status: -19
[Thu Jan  2 09:18:05 2020] cp210x ttyUSB0: failed set request 0x0 status: -19
[Thu Jan  2 09:18:05 2020] cp210x ttyUSB0: cp210x converter now disconnected from ttyUSB0
[Thu Jan  2 09:18:05 2020] cp210x ttyUSB1: cp210x converter now disconnected from ttyUSB1
[Thu Jan  2 09:30:52 2020] usb 1-1.2: cp210x converter now attached to ttyUSB0
[Thu Jan  2 09:30:52 2020] usb 1-1.2: cp210x converter now attached to ttyUSB1

and chris, I am looking for the binding documentation you mentioned. But here is the log output that Ii have for now, after doing the things H102 suggested.

2020-01-02 09:18:02.030 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from UNKNOWN to UNINITIALIZED

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

2020-01-02 09:18:02.058 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN

2020-01-02 09:18:02.072 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE

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

2020-01-02 09:18:02.085 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from UNINITIALIZED to OFFLINE: Failed to open communications port

2020-01-02 09:18:02.097 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from OFFLINE: Failed to open communications port to OFFLINE (COMMUNICATION_ERROR)

2020-01-02 09:18:02.175 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_rx_dat changed from NULL to 0

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

2020-01-02 09:18:02.178 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.

2020-01-02 09:18:02.185 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_ember:df56b300] closed.

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

2020-01-02 09:18:02.197 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_tx_dat changed from NULL to 0

2020-01-02 09:18:02.200 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_rx_ack changed from NULL to 0

2020-01-02 09:18:02.203 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_tx_ack changed from NULL to 0

2020-01-02 09:18:02.205 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_rx_nak changed from NULL to 0

2020-01-02 09:18:02.213 [vent.ItemStateChangedEvent] - zigbee_coordinator_ember_df56b300_tx_nak changed from NULL to 0

2020-01-02 09:18:02.230 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from OFFLINE (COMMUNICATION_ERROR) to UNINITIALIZED (HANDLER_MISSING_ERROR)

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

2020-01-02 09:18:03.104 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator

2020-01-02 09:18:03.107 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400

2020-01-02 09:18:03.110 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.

2020-01-02 09:18:03.115 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING

2020-01-02 09:18:03.141 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.

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

2020-01-02 09:18:03.556 [thome.event.ExtensionEvent] - Extension 'binding-zigbee' has been uninstalled.

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

2020-01-02 09:19:19.875 [DEBUG] [ding.serial.internal.SerialActivator] - Serial binding has been stopped.

2020-01-02 09:19:20.142 [INFO ] [basic.internal.servlet.WebAppServlet] - Stopped Basic UI

2020-01-02 09:19:36.519 [INFO ] [assic.internal.servlet.WebAppServlet] - Stopped Classic UI

2020-01-02 09:19:36.580 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Stopped HABmin servlet

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

2020-01-02 09:19:36.883 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_rx_ack => zigbee:coordinator_ember:df56b300:rx_ack' has been removed.

2020-01-02 09:19:36.889 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_tx_nak => zigbee:coordinator_ember:df56b300:tx_nak' has been removed.

2020-01-02 09:19:36.894 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_tx_dat => zigbee:coordinator_ember:df56b300:tx_dat' has been removed.

2020-01-02 09:19:36.897 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_tx_ack => zigbee:coordinator_ember:df56b300:tx_ack' has been removed.

2020-01-02 09:19:36.900 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_rx_dat => zigbee:coordinator_ember:df56b300:rx_dat' has been removed.

2020-01-02 09:19:36.905 [temChannelLinkRemovedEvent] - Link 'zigbee_coordinator_ember_df56b300_rx_nak => zigbee:coordinator_ember:df56b300:rx_nak' has been removed.

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

2020-01-02 09:19:37.157 [INFO ] [panel.internal.HABPanelDashboardTile] - Stopped HABPanel

2020-01-02 09:19:37.202 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Stopped Paper UI

2020-01-02 09:19:37.238 [INFO ] [.dashboard.internal.DashboardService] - Stopped Dashboard

2020-01-02 09:28:18.549 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007

2020-01-02 09:28:20.672 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.1.201:8080

2020-01-02 09:28:20.681 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.1.201:8443

2020-01-02 09:28:20.906 [DEBUG] [ding.serial.internal.SerialActivator] - Serial binding has been started.

2020-01-02 09:28:22.845 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin

2020-01-02 09:28:23.110 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui

2020-01-02 09:28:23.329 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel

2020-01-02 09:31:30.417 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService

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

2020-01-02 09:31:30.852 [thome.event.ExtensionEvent] - Extension 'binding-zigbee' has been installed.

2020-01-02 09:31:31.188 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from UNINITIALIZED to INITIALIZING

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

2020-01-02 09:31:31.216 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.

2020-01-02 09:31:31.218 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:df56b300].

2020-01-02 09:31:31.220 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11

2020-01-02 09:31:31.222 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 35112

2020-01-02 09:31:31.224 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID CF5AB932B32CE585

2020-01-02 09:31:31.228 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 4A0BB91BD9CDC704E6D023CA43BB4F28

2020-01-02 09:31:31.230 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039

2020-01-02 09:31:31.232 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false

2020-01-02 09:31:31.234 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 4A0BB91BD9CDC704E6D023CA43BB4F28

2020-01-02 09:31:31.238 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 4A0BB91BD9CDC704E6D023CA43BB4F28

2020-01-02 09:31:31.240 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039

2020-01-02 09:31:31.243 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039

2020-01-02 09:31:31.340 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:8928, EPAN:CF5AB932B32CE585, Channel:11

2020-01-02 09:31:31.343 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timout set to (169 * 2^9) = 86528 seconds

2020-01-02 09:31:31.365 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start

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

2020-01-02 09:31:31.376 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_ember:df56b300' changed from INITIALIZING to UNKNOWN

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

2020-01-02 09:31:32.372 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting

2020-01-02 09:31:32.375 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator

2020-01-02 09:31:32.453 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400

2020-01-02 09:31:32.490 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING

2020-01-02 09:31:32.495 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 57600 baud, flow control FLOWCONTROL_OUT_XONOFF.

2020-01-02 09:31:32.533 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.

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

2020-01-02 09:31:39.393 [nt.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:coordinator_ember:df56b300 changed to UNKNOWN.

Thank you both

What serial port did you select in PaperUI drop down?

It would help in pretty much every case - the only time it won’t help is if there is only ever one USB serial dongle on a machine, and even in that case, it would not hurt :slight_smile:

1 Like