I think now that you’ve got it online, you can probably switch back to config files and it will probably work. If not, it is possible to configure this via the config file as well - it just might be a bit more of a pain since you need to set it back to false before re-running it or it will wipe your network.
First just check if changing back to the config file now works or not…
I removed the UI added thing and restarted the container and the config file thing worked! Thank you! Do you mind telling what caused the issue? Did I do something wrong during the setup?
I don’t think you did anything wrong. As much as I’d love to say that you missed something in the docs, I think that for a new dongle this initialisation is required, but it’s not documented that I can see…
I’ll update the docs - I’m glad it’s working and apologies for the hassle.
Okay, that makes sense that this is required.
Thank you Chris, for your time helping with this issue and for the Zigbee binding in general.
Hello,
Unfortunately I was not able to succeed with my dongle.
My Sonoff USB dongle stays in “INITIALIZING” state and can not come to online.
I installed the binding and followed steps described in the zigbee binding configuration.
What I have problem with is the firmware flash.
Guideline says:
Upgrading firmware
Dongle firmware can be upgraded using the openHAB console with thezigbee firmwarecommand. The firmware file must be specified next. The dongle will be taken offline, put into firmware upgrade mode and then upgraded. You MUST ensure that you have the correct firmware for your dongle as programming the incorrect firmware can render the device inoperable - requiring a programmer to recover.
Going into konsole and typing zigbee firmware returns
Error: Exception during command execution (IllegalArgumentException): Invalid number of arguments
I am not familiar with husbzb-firmware and python etc. so I decided to flash the firmware with the tutorial from Sonoff
Flashing was successful. Unfortunately the device can not switch to online mode. I tried few setting with the binding configuration and few restarts and it did not bring me further 
My log file:
2024-12-04 21:10:45.277 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:393c17565b].
2024-12-04 21:10:45.278 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2024-12-04 21:10:45.278 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 46671
2024-12-04 21:10:45.279 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID C9656BCB1CB20148
2024-12-04 21:10:45.281 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key A01AD1348081A373337DA05E88969229
2024-12-04 21:10:45.282 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2024-12-04 21:10:45.283 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false
2024-12-04 21:10:45.284 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String A01AD1348081A373337DA05E88969229
2024-12-04 21:10:45.285 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array A01AD1348081A373337DA05E88969229
2024-12-04 21:10:45.286 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
2024-12-04 21:10:45.287 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
2024-12-04 21:10:45.287 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-12-04 21:10:45.335 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:b64f, EPAN:C9656BCB1CB20148, Channel:11
2024-12-04 21:10:45.336 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2024-12-04 21:10:45.341 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-12-04 21:10:45.271 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:coordinator_ember:393c17565b' changed from UNINITIALIZED (DISABLED) to INITIALIZING
2024-12-04 21:10:45.348 [INFO ] [penhab.event.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:coordinator_ember:393c17565b changed to UNKNOWN.
2024-12-04 21:10:46.343 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-12-04 21:10:46.344 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-12-04 21:10:46.373 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2024-12-04 21:10:46.375 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2024-12-04 21:10:46.435 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2024-12-04 21:11:23.446 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2024-12-04 21:11:23.448 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-12-04 21:11:28.540 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2024-12-04 21:11:28.542 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2024-12-04 21:11:28.544 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2024-12-04 21:11:28.545 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2024-12-04 21:11:28.546 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2024-12-04 21:11:28.547 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2024-12-04 21:11:28.548 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2024-12-04 21:11:28.548 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2024-12-04 21:11:28.550 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Processing zigbee_flowcontrol -> 0
2024-12-04 21:11:28.551 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2024-12-04 21:11:28.555 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2024-12-04 21:11:28.556 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2024-12-04 21:11:28.562 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2024-12-04 21:11:28.563 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2024-12-04 21:11:28.565 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2024-12-04 21:11:28.566 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2024-12-04 21:11:28.567 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2024-12-04 21:11:28.568 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2024-12-04 21:11:28.571 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_flowcontrol -> 0
2024-12-04 21:11:48.579 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2024-12-04 21:11:48.579 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-12-04 21:11:53.659 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2024-12-04 21:11:53.661 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2024-12-04 21:11:53.664 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2024-12-04 21:11:53.666 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2024-12-04 21:11:53.668 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2024-12-04 21:11:53.671 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2024-12-04 21:11:53.673 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2024-12-04 21:11:53.674 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2024-12-04 21:11:53.676 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Processing zigbee_flowcontrol -> 2
2024-12-04 21:11:53.677 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2024-12-04 21:11:53.678 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2024-12-04 21:11:53.679 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2024-12-04 21:11:53.679 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2024-12-04 21:11:53.680 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2024-12-04 21:11:53.681 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2024-12-04 21:11:53.682 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2024-12-04 21:11:53.683 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2024-12-04 21:11:53.684 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2024-12-04 21:11:53.685 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_flowcontrol -> 2
2024-12-04 21:12:17.699 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2024-12-04 21:12:17.700 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-12-04 21:12:22.733 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration received.
2024-12-04 21:12:22.734 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_port as no change
2024-12-04 21:12:22.735 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_channel as no change
2024-12-04 21:12:22.735 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_initialise as no change
2024-12-04 21:12:22.736 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_concentrator as no change
2024-12-04 21:12:22.737 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_trustcentremode as no change
2024-12-04 21:12:22.737 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_extendedpanid as no change
2024-12-04 21:12:22.738 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_baud as no change
2024-12-04 21:12:22.739 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Processing zigbee_flowcontrol -> 1
2024-12-04 21:12:22.739 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_panid as no change
2024-12-04 21:12:22.740 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_powermode as no change
2024-12-04 21:12:22.740 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_txpower as no change
2024-12-04 21:12:22.741 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networksize as no change
2024-12-04 21:12:22.742 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_linkkey as no change
2024-12-04 21:12:22.743 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_childtimeout as no change
2024-12-04 21:12:22.744 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_networkkey as no change
2024-12-04 21:12:22.744 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - null: Ember configuration update: Ignored zigbee_meshupdateperiod as no change
2024-12-04 21:12:22.745 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2024-12-04 21:12:22.746 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_flowcontrol -> 1
My Configuration:
UID: zigbee:coordinator_ember:393c17565b
label: Ember Coordinator
thingTypeUID: zigbee:coordinator_ember
configuration:
zigbee_port: /dev/ttyUSB0
zigbee_initialise: false
zigbee_channel: 11
zigbee_concentrator: 1
zigbee_trustcentremode: TC_JOIN_SECURE
zigbee_extendedpanid: C9656BCB1CB20148
zigbee_flowcontrol: 1
zigbee_baud: 115200
zigbee_panid: 46671
zigbee_powermode: 1
zigbee_txpower: 0
zigbee_networksize: 25
zigbee_linkkey: 5A6967426565416C6C69616E63653039
zigbee_childtimeout: 86400
zigbee_networkkey: A01AD1348081A373337DA05E88969229
zigbee_meshupdateperiod: 86400
channels:
- id: rx_dat
channelTypeUID: zigbee:ember_ash_rx_dat
label: Data frames received
description: Provides a count of DATA frames received by the host.
configuration: {}
- id: tx_dat
channelTypeUID: zigbee:ember_ash_tx_dat
label: Data frames sent
description: Provides a count of DATA frames sent by the host.
configuration: {}
- id: rx_nak
channelTypeUID: zigbee:ember_ash_rx_nak
label: NAK frames received
description: Provides a count of DATA frames with an error or that were
discarded by the NCP due to lack of memory.
configuration: {}
- id: tx_nak
channelTypeUID: zigbee:ember_ash_tx_nak
label: NAK frames sent
description: Provides a count of DATA frames with an error or that were
discarded by the host due to failed CRC.
configuration: {}
- id: rx_ack
channelTypeUID: zigbee:ember_ash_rx_ack
label: ACK frames received
description: Provides a count of DATA frames received successfully by the NCP.
configuration: {}
- id: tx_ack
channelTypeUID: zigbee:ember_ash_tx_ack
label: ACK frames sent
description: Provides a count of DATA frames received successfully by the host.
configuration: {}
Why do you want to update the firmware? This shouldn’t be needed - the stock firmware should work just fine.
Without more detailed logging it’s a bit hard to see what’s happening, but have you initialised the network? From the logs I see here, the network is not initialised during this log, but it’s impossible to know if you’ve already done this earlier? This is described earlier in this thread - the docs were updated to reflect this, but as it’s still not in the latest RELEASE, it may not be reflected in the online docs yet.
It was written this was in the guideline to be sure to have right firmware. So I did flashed the latest one.
What does it mean initilise the network? I just connected the USB dongle. it must be online to add new devices. I am waiting the dongle to be online in my things list
You must initialise the network - this is described above. If you don’t have an operational network, then the network will stay OFFLINE.
Hmmm. Wanted to get more logs for you @chris and end up with this problem…
I tried to reinitilise many times as you proposed:
Hmmm - I’m not sure what this is about - sorry.
What happens if you just type log:set DEBUG com.zsmartsystems.zigbee?
the problem is i can not see any logs. typing log:get or log: set ends with fatal error
Need to fix this to get some logs.
Openhab works well beside the logging.
I am back after fixing the problem with the logs. these are my logs settings:
Logger │ Level
───────────────────────────────────────────────────┼──────
ROOT │ INFO
com.zsmartsystems.zigbee │ DEBUG
com.zsmartsystems.zigbee.console │ INFO
com.zsmartsystems.zigbee.console.telegesis │ INFO
javax.jmdns │ ERROR
javax.mail │ ERROR
openhab.event │ INFO
openhab.event.AddonEvent │ ERROR
openhab.event.ChannelDescriptionChangedEvent │ ERROR
openhab.event.GroupStateUpdatedEvent │ ERROR
openhab.event.InboxUpdatedEvent │ ERROR
openhab.event.ItemAddedEvent │ ERROR
openhab.event.ItemChannelLinkAddedEvent │ ERROR
openhab.event.ItemChannelLinkRemovedEvent │ ERROR
openhab.event.ItemRemovedEvent │ ERROR
openhab.event.ItemStateEvent │ ERROR
openhab.event.ItemStateUpdatedEvent │ ERROR
openhab.event.RuleAddedEvent │ ERROR
openhab.event.RuleRemovedEvent │ ERROR
openhab.event.RuleStatusInfoEvent │ ERROR
openhab.event.StartlevelEvent │ ERROR
openhab.event.ThingAddedEvent │ ERROR
openhab.event.ThingRemovedEvent │ ERROR
openhab.event.ThingStatusInfoEvent │ ERROR
openhab.event.ThingUpdatedEvent │ ERROR
org.apache.cxf.jaxrs.sse.SseEventSinkImpl │ ERROR
org.apache.cxf.phase.PhaseInterceptorChain │ ERROR
org.apache.karaf.jaas.modules.audit │ INFO
org.apache.karaf.kar.internal.KarServiceImpl │ ERROR
org.apache.karaf.shell.ssh.SshUtils │ ERROR
org.apache.karaf.shell.support │ OFF
org.apache.sshd │ WARN
org.eclipse.lsp4j │ OFF
org.jupnp │ ERROR
org.openhab │ INFO
org.openhab.binding.zigbee │ DEBUG
org.openhab.binding.zigbee.cc2531 │ INFO
org.openhab.binding.zigbee.console │ INFO
org.openhab.binding.zigbee.console.ember │ DEBUG
org.openhab.binding.zigbee.ember │ DEBUG
org.openhab.binding.zigbee.firmware │ INFO
org.openhab.binding.zigbee.serial │ DEBUG
org.openhab.binding.zigbee.telegesis │ INFO
org.openhab.binding.zigbee.xbee │ INFO
org.openhab.binding.zwave │ INFO
org.ops4j.pax.url.mvn.internal.AetherBasedResolver │ ERROR
org.ops4j.pax.web.pax-web-runtime │ OFF
su.litvak.chromecast.api.v2.Channel │ ERROR
The communication is initialized I see a lot of messages comming but the dongle goes to OFFLINE
....Failed to startup ZigBee transport layer
2024-12-04 23:37:15.851 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:coordinator_ember:393c17565b' changed from OFFLINE (COMMUNICATION_ERROR) to OFFLINE: Failed to startup ZigBee transport layer
2024-12-04 23:37:15.853 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:coordinator_ember:393c17565b' changed from OFFLINE: Failed to startup ZigBee transport layer to OFFLINE (COMMUNICATION_ERROR)
2024-12-04 23:37:15.961 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Senec_Home_House_Power_Consumption' changed from 372.36 W to 368.9 W
2024-12-04 23:37:15.973 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Senec_Home_Grid_Power' changed from 365.39 W to 361.93 W
2024-12-04 23:37:15.976 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Senec_Home_Grid_Draw' changed from 365.39 W to 361.93 W
2024-12-04 23:37:16.914 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Systeminfo_System_Uptime' changed from 29.3 to 30.3
2024-12-04 23:37:16.920 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'RaspberryPiSystem_CPU_Temperature' changed from 50.6 °C to 51.6 °C
==> /var/log/openhab/openhab.log <==
2024-12-04 23:37:20.851 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2024-12-04 23:37:20.853 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-12-04 23:37:20.943 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2024-12-04 23:37:20.945 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-12-04 23:37:20.948 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:b64f, EPAN:C9656BCB1CB20148, Channel:11
2024-12-04 23:37:20.950 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2024-12-04 23:37:20.951 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-12-04 23:37:21.952 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-12-04 23:37:21.953 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-12-04 23:37:21.956 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 115200 baud, flow control FLOWCONTROL_OUT_NONE.
2024-12-04 23:37:21.956 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2024-12-04 23:37:22.029 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
==> /var/log/openhab/events.log <==
2024-12-04 23:37:22.122 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Office_Multisensor_6_Sensor_temperature' changed from 21.8 °C to 21.7 °C
==> /var/log/openhab/openhab.log <==
2024-12-04 23:37:26.275 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400
2024-12-04 23:37:26.290 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_00, PanID=0, EPanId=0000000000000000
2024-12-04 23:37:26.292 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_SECURE
2024-12-04 23:37:26.369 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
==> /var/log/openhab/events.log <==
2024-12-04 23:37:26.372 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:coordinator_ember:393c17565b' changed from OFFLINE (COMMUNICATION_ERROR) to OFFLINE: Failed to startup ZigBee transport layer
2024-12-04 23:37:26.373 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:coordinator_ember:393c17565b' changed from OFFLINE: Failed to startup ZigBee transport layer to OFFLINE (COMMUNICATION_ERROR)
2024-12-04 23:37:27.618 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'RaspberryPiSystem_Cpu_Load' changed from 1.5 to 1.6
==> /var/log/openhab/openhab.log <==
2024-12-04 23:37:31.373 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
2024-12-04 23:37:31.374 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=SHUTDOWN
2024-12-04 23:37:31.462 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2024-12-04 23:37:31.464 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Initializing ZigBee Ember serial bridge handler.
2024-12-04 23:37:31.465 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - ZigBee Ember Coordinator opening Port:'/dev/ttyUSB0' PAN:b64f, EPAN:C9656BCB1CB20148, Channel:11
2024-12-04 23:37:31.466 [DEBUG] [ng.zigbee.ember.handler.EmberHandler] - Ember end device poll timeout set to (169 * 2^9) = 86528 seconds
2024-12-04 23:37:31.467 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2024-12-04 23:37:32.468 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2024-12-04 23:37:32.469 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2024-12-04 23:37:32.472 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 115200 baud, flow control FLOWCONTROL_OUT_NONE.
2024-12-04 23:37:32.472 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2024-12-04 23:37:32.543 [DEBUG] [nding.zigbee.serial.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2024-12-04 23:37:36.791 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Mesh Update Period 86400
2024-12-04 23:37:36.804 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_00, PanID=0, EPanId=0000000000000000
2024-12-04 23:37:36.805 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_SECURE
2024-12-04 23:37:36.881 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
==> /var/log/openhab/events.log <==
2024-12-04 23:37:36.883 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:coordinator_ember:393c17565b' changed from OFFLINE (COMMUNICATION_ERROR) to OFFLINE: Failed to startup ZigBee transport layer
2024-12-04 23:37:36.885 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:coordinator_ember:393c17565b' changed from OFFLINE: Failed to startup ZigBee transport layer to OFFLINE (COMMUNICATION_ERROR)
2024-12-04 23:37:37.623 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'RaspberryPiSystem_Cpu_Load' changed from 1.6 to 1.2
Attaching configuration screenshots again may be my settings are not correct:
Thanks.
So, your logging looks correct - the com.zsmartsystems.zigbee is set to DEBUG - however - I don’t see any logging from that package, which is strange. Without this I can’t really see what is going on and why it’s not working.
What I’d like to see is a startup with this logging as it will tell me (hopefully) what is going on. We should see LOTS of logging when the binding starts - you’ll likely think it’s too much, but it would be good to get it in it’s entirety.
Depending on what that shows, we might want to do the network initialisation and get the logging again to see what’s happening there, but we need to see this detailed logging… Somehow…
Let me collect this today evening….
@chris I was able to collect log just after reboot of the system…
log file.txt (385.2 KB)
After reboot the USB Dongle goes to DISABLE mode. When i want to ENABLE it these logs are comming.
log file 2.txt (17.0 KB)
The following line in the log seems to indicate that the network has not been initialised.
2024-12-06 17:35:25.816 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=CHANNEL_00, PanID=0, EPanId=0000000000000000
Maybe something strange is happening during the initialisation (ie when you tick the “advanced” box, and “Reinitialise Controller”. Can you please do this again, and provide the log from this?
I’m also not sure why we don’t see the detailed logging from com.zsmartsystems.zigbee. Unfortunately without this it’s hard to know exactly what the issue is since there are a few things that can cause the COMMUNICATION_ERROR response.
One other point - I see you’re running 4.2.3 - if there’s any way to update to the snapshot version of the binding, that might also help with additional logging.
What have you done @chris ?
I enebled additional DEBUG logs for you, reinitilized again and it appear to ONLINE!
I attach logs anyway maybe you will be able to identify the problem. I actually did really nothing.
log3.txt (127.6 KB)
log4.txt (177.2 KB)
I activated com.zsmartsystems.zigbee to DEBUG
