[SOLVED] Telegesis ETRX3: Status "OFFLINE" Failed to initialize ZigBee transport layer

Christian_Hofstatter · September 22, 2018, 12:46pm

Ich have a new Telegesis ETRX357USB-LRS on my Openhabian, running “openHAB 2.4.0 Build #1369” ( I also tested it on a Windows system, with the same result).
The Problem: After initializing the thing is going to “Status: OFFLINE Failed to initialize ZigBee transport layer”. The serial port should be okay, at least answers come back from the stick. With Minicom I can also connect to ttyUSB0 (if i disconnect OH) and send the stick AT commands to which he responds.

After 3 days fruitless search, I hope to find help here.

here the logging:

2018-09-22 10:57:34.275 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-09-22 10:57:34.279 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-09-22 10:57:34.283 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
2018-09-22 10:57:34.285 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
2018-09-22 10:57:34.288 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE
2018-09-22 10:57:34.291 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
2018-09-22 10:57:34.294 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE
2018-09-22 10:57:34.301 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle initialize.
2018-09-22 10:57:34.304 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 19200 baud, flow control FLOWCONTROL_OUT_NONE.
2018-09-22 10:57:34.346 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2018-09-22 10:57:34.370 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread started
2018-09-22 10:57:34.384 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:34.387 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2018-09-22 10:57:34.391 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ

2018-09-22 10:57:34.770 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2018-09-22 10:57:34.795 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:34.803 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSoftwareResetCommand [status=SUCCESS]
2018-09-22 10:57:34.812 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:34.814 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2018-09-22 10:57:34.820 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1

2018-09-22 10:57:34.931 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:34.933 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null, status=SUCCESS]
2018-09-22 10:57:34.939 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:34.941 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804]
2018-09-22 10:57:34.947 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E=8804

2018-09-22 10:57:35.057 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:35.059 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804, status=SUCCESS]
2018-09-22 10:57:35.064 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:35.067 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password]
2018-09-22 10:57:35.076 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A=015E:password

2018-09-22 10:57:35.188 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:35.189 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password, status=SUCCESS]
2018-09-22 10:57:35.194 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:35.197 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9]
2018-09-22 10:57:35.201 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS10=56A9

2018-09-22 10:57:35.313 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:35.316 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9, status=SUCCESS]
2018-09-22 10:57:35.321 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:35.324 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetPromptEnable2Command [configuration=6B3C]
2018-09-22 10:57:35.329 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0F=6B3C

2018-09-22 10:57:35.441 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:35.443 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetPromptEnable2Command [configuration=6B3C, status=SUCCESS]
2018-09-22 10:57:35.448 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:35.452 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayProductIdentificationCommand [deviceName=null, firmwareRevision=null, ieeeAddress=null]
2018-09-22 10:57:35.456 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATI

2018-09-22 10:57:35.596 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Telegesis ETRX357-LRS
2018-09-22 10:57:35.599 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:R309C
2018-09-22 10:57:35.604 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:000D6F000D949788
2018-09-22 10:57:35.609 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:35.612 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayProductIdentificationCommand [deviceName=ETRX357-LRS, firmwareRevision=309C, ieeeAddress=000D6F000D949788, status=SUCCESS]
2018-09-22 10:57:35.617 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:35.620 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2018-09-22 10:57:35.624 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2018-09-22 10:57:35.741 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2018-09-22 10:57:35.747 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:35.751 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2018-09-22 10:57:35.754 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2018-09-22 10:57:35.755 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: File does not exist
2018-09-22 10:57:35.759 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000D949788: Adding local coordinator node to network
2018-09-22 10:57:35.812 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000D949788: Node 0 added to the network
2018-09-22 10:57:35.814 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task starting
2018-09-22 10:57:36.753 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:36.777 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2018-09-22 10:57:36.779 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2018-09-22 10:57:36.897 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2018-09-22 10:57:36.901 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2018-09-22 10:57:36.902 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2018-09-22 10:57:37.904 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-09-22 10:57:37.906 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2018-09-22 10:57:37.910 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

chris · September 22, 2018, 5:30pm

I can see where this issue is - it will need an update to the driver to resolve which will likely take a few days to get merged.

Christian_Hofstatter · September 22, 2018, 6:00pm

Thanks, I’ll just wait.
I’m glad that the problem is not sitting in front of my computer.

chris · September 24, 2018, 4:57pm

For reference -;

Christian_Hofstatter · September 28, 2018, 9:13pm

Thank you Chris, Telegesis is online and has already found the first lamps.

Syn · November 16, 2018, 11:56am

I have a similar problem with my “Qivicon ZigBee Funkstick”.
Added it to my raspberry pi following the steps described here..
It shows up as Telegesis USB Device (ZigBee USB dongle) in PaperUI. After adding the thing it remains offline Failed to initialize ZigBee transport layer.

I’ve attached the logs after resetting the controller:

openhab.log

2018-11-16 12:35:20.616 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2018-11-16 12:35:20.620 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_port as no change
2018-11-16 12:35:20.622 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_baud as no change
2018-11-16 12:35:20.625 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_initialise -> true
2018-11-16 12:35:20.627 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_channel as no change
2018-11-16 12:35:20.630 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_password as no change
2018-11-16 12:35:20.632 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_panid as no change
2018-11-16 12:35:20.637 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_trustcentremode as no change
2018-11-16 12:35:20.640 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_linkkey as no change
2018-11-16 12:35:20.644 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_extendedpanid as no change
2018-11-16 12:35:20.647 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_networkkey as no change
2018-11-16 12:35:20.704 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-11-16 12:35:20.717 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-11-16 12:35:20.725 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task shutdown
2018-11-16 12:35:20.736 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2018-11-16 12:35:20.823 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread exited.
2018-11-16 12:35:20.823 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2018-11-16 12:35:20.826 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler closed.
2018-11-16 12:35:20.828 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle shutdown.
2018-11-16 12:35:20.830 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_telegesis:706002137] closed.
2018-11-16 12:35:20.833 [DEBUG] [e.telegesis.handler.TelegesisHandler] - Initializing ZigBee Telegesis serial bridge handler.
2018-11-16 12:35:20.835 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_telegesis:706002137].
2018-11-16 12:35:20.839 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2018-11-16 12:35:20.842 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 16299
2018-11-16 12:35:20.845 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 35EEA372E209490E
2018-11-16 12:35:20.848 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key B0895D8D4D8E1A3217D7F2E0C0FCD6DF
2018-11-16 12:35:20.851 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2018-11-16 12:35:20.854 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=true
2018-11-16 12:35:20.857 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String B0895D8D4D8E1A3217D7F2E0C0FCD6DF
2018-11-16 12:35:20.861 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array B0895D8D4D8E1A3217D7F2E0C0FCD6DF
2018-11-16 12:35:20.864 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
2018-11-16 12:35:20.867 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network
2018-11-16 12:35:20.928 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
2018-11-16 12:35:20.934 [DEBUG] [e.telegesis.handler.TelegesisHandler] - ZigBee Telegesis Coordinator opening Port:'/dev/ttyUSB0' PAN:3fab, EPAN:35EEA372E209490E, Channel:11
2018-11-16 12:35:20.940 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2018-11-16 12:35:21.948 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-11-16 12:35:21.950 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-11-16 12:35:21.952 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
2018-11-16 12:35:21.953 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
2018-11-16 12:35:21.955 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE
2018-11-16 12:35:21.957 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
2018-11-16 12:35:21.959 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE
2018-11-16 12:35:21.960 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle initialize.
2018-11-16 12:35:21.962 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=UNINITIALISED
2018-11-16 12:35:21.963 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 19200 baud, flow control FLOWCONTROL_OUT_NONE.
2018-11-16 12:35:21.977 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2018-11-16 12:35:21.979 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread started
2018-11-16 12:35:21.980 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-16 12:35:21.982 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2018-11-16 12:35:21.985 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ

2018-11-16 12:35:22.380 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2018-11-16 12:35:22.392 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:JPAN:14,F102,4A09AF4DC0B09EFE
2018-11-16 12:35:22.398 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNetworkJoinedEvent [channel=14, panId=61698, epanId=4A09AF4DC0B09EFE]
2018-11-16 12:35:22.980 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
2018-11-16 12:35:22.986 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-16 12:35:23.982 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
2018-11-16 12:35:23.993 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2
2018-11-16 12:35:24.986 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
2018-11-16 12:35:24.988 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 3
2018-11-16 12:35:25.989 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2018-11-16 12:35:25.991 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Error setting Telegesis port echo
2018-11-16 12:35:25.995 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to configure Telegesis dongle
2018-11-16 12:35:26.006 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2018-11-16 12:35:26.025 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2018-11-16 12:35:26.040 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task starting
2018-11-16 12:35:26.988 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 0
2018-11-16 12:35:26.992 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2018-11-16 12:35:26.996 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ

2018-11-16 12:35:27.390 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2018-11-16 12:35:27.397 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:JPAN:14,F102,4A09AF4DC0B09EFE
2018-11-16 12:35:27.400 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNetworkJoinedEvent [channel=14, panId=61698, epanId=4A09AF4DC0B09EFE]
2018-11-16 12:35:32.000 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 0
2018-11-16 12:35:32.003 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2018-11-16 12:35:32.008 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ

2018-11-16 12:35:32.408 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2018-11-16 12:35:32.415 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:JPAN:14,F102,4A09AF4DC0B09EFE
2018-11-16 12:35:32.425 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNetworkJoinedEvent [channel=14, panId=61698, epanId=4A09AF4DC0B09EFE]
2018-11-16 12:35:37.011 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 0
2018-11-16 12:35:37.014 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2018-11-16 12:35:37.017 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1

2018-11-16 12:35:37.428 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-16 12:35:42.020 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 1
2018-11-16 12:35:42.023 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2018-11-16 12:35:42.029 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2018-11-16 12:35:42.167 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,14,03,F102,4A09AF4DC0B09EFE
2018-11-16 12:35:42.428 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-16 12:35:42.548 [ERROR] [ing.ipcamera.handler.IpCameraHandler] - Can not connect with HTTP to the camera at 192.168.188.40:88 check your network for issues.
2018-11-16 12:35:47.032 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 2
2018-11-16 12:35:47.036 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2018-11-16 12:35:47.037 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Error: number of retries exceeded [3].

events.log

2018-11-16 12:35:20.658 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_telegesis:706002137' has been updated.
2018-11-16 12:35:20.924 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_telegesis:706002137' has been updated.
2018-11-16 12:35:20.942 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:706002137' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
2018-11-16 12:35:26.083 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:706002137' changed from UNKNOWN to OFFLINE: Failed to initialize ZigBee transport layer
2018-11-16 12:35:47.043 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:706002137' changed from OFFLINE: Failed to initialize ZigBee transport layer to OFFLINE (COMMUNICATION_ERROR)

Thanks for your help!

algo4711 · November 17, 2018, 12:58pm

I have sometimes also trouble with my stick. It could help to shutdown the PI, disconnect AC and reconnect. A reboot only did not solved the problem.
Mayby this work also for you?

Syn · November 17, 2018, 4:41pm

I tried that several times. But it doesn’t help.

Syn · November 18, 2018, 3:02pm

Here’s the log set to trace. Maybe it is helpful:
openhab.log:

2018-11-18 15:53:29.451 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration received (Coordinator).
2018-11-18 15:53:29.457 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_port as no change
2018-11-18 15:53:29.478 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_baud as no change
2018-11-18 15:53:29.492 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Processing zigbee_initialise -> true
2018-11-18 15:53:29.498 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_channel as no change
2018-11-18 15:53:29.502 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_password as no change
2018-11-18 15:53:29.505 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_panid as no change
2018-11-18 15:53:29.509 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_trustcentremode as no change
2018-11-18 15:53:29.513 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_linkkey as no change
2018-11-18 15:53:29.516 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_extendedpanid as no change
2018-11-18 15:53:29.525 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: Configuration update: Ignored zigbee_networkkey as no change
2018-11-18 15:53:29.681 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2018-11-18 15:53:29.747 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2018-11-18 15:53:29.755 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task shutdown
2018-11-18 15:53:29.804 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2018-11-18 15:53:29.879 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread exited.
2018-11-18 15:53:29.879 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port '/dev/ttyUSB0' closed.
2018-11-18 15:53:29.883 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler closed.
2018-11-18 15:53:29.887 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle shutdown.
2018-11-18 15:53:29.890 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network [zigbee:coordinator_telegesis:706002137] closed.
2018-11-18 15:53:29.894 [DEBUG] [e.telegesis.handler.TelegesisHandler] - Initializing ZigBee Telegesis serial bridge handler.
2018-11-18 15:53:29.901 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_telegesis:706002137].
2018-11-18 15:53:29.904 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2018-11-18 15:53:29.908 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 16299
2018-11-18 15:53:29.911 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 35EEA372E209490E
2018-11-18 15:53:29.915 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key B0895D8D4D8E1A3217D7F2E0C0FCD6DF
2018-11-18 15:53:29.918 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2018-11-18 15:53:29.921 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=true
2018-11-18 15:53:29.925 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String B0895D8D4D8E1A3217D7F2E0C0FCD6DF
2018-11-18 15:53:29.931 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array B0895D8D4D8E1A3217D7F2E0C0FCD6DF
2018-11-18 15:53:29.934 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
2018-11-18 15:53:29.938 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network
2018-11-18 15:53:30.037 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
2018-11-18 15:53:30.043 [DEBUG] [e.telegesis.handler.TelegesisHandler] - ZigBee Telegesis Coordinator opening Port:'/dev/ttyUSB0' PAN:3fab, EPAN:35EEA372E209490E, Channel:11
2018-11-18 15:53:30.047 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2018-11-18 15:53:31.055 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-11-18 15:53:31.057 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-11-18 15:53:31.060 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
2018-11-18 15:53:31.061 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
2018-11-18 15:53:31.064 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE
2018-11-18 15:53:31.065 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
2018-11-18 15:53:31.067 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE
2018-11-18 15:53:31.069 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle initialize.
2018-11-18 15:53:31.071 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=UNINITIALISED
2018-11-18 15:53:31.072 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 19200 baud, flow control FLOWCONTROL_OUT_NONE.
2018-11-18 15:53:31.087 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
2018-11-18 15:53:31.089 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread started
2018-11-18 15:53:31.090 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-18 15:53:31.091 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2018-11-18 15:53:31.093 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ

2018-11-18 15:53:31.095 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Start
2018-11-18 15:53:31.096 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:31.269 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 02 
2018-11-18 15:53:31.271 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 03 
2018-11-18 15:53:31.487 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:31.490 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2018-11-18 15:53:31.493 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:31.496 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:31.499 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4A J
2018-11-18 15:53:31.502 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 50 P
2018-11-18 15:53:31.505 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:31.508 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4E N
2018-11-18 15:53:31.511 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 3A :
2018-11-18 15:53:31.514 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:31.517 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:31.520 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:31.524 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:31.526 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:31.530 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:31.532 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 32 2
2018-11-18 15:53:31.536 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:31.538 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:31.542 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:31.544 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:31.548 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:31.550 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:31.554 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:31.556 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:31.560 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 44 D
2018-11-18 15:53:31.562 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 43 C
2018-11-18 15:53:31.566 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:31.568 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 42 B
2018-11-18 15:53:31.580 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:31.582 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:31.585 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:31.588 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:31.592 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:31.594 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:31.600 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:JPAN:14,F102,4A09AF4DC0B09EFE
2018-11-18 15:53:31.602 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNetworkJoinedEvent [channel=14, panId=61698, epanId=4A09AF4DC0B09EFE]
2018-11-18 15:53:31.607 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:31.609 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:32.090 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
2018-11-18 15:53:32.097 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-18 15:53:33.093 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
2018-11-18 15:53:33.096 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2
2018-11-18 15:53:34.096 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
2018-11-18 15:53:34.099 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 3
2018-11-18 15:53:35.099 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2018-11-18 15:53:35.100 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Error setting Telegesis port echo
2018-11-18 15:53:35.102 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to configure Telegesis dongle
2018-11-18 15:53:35.103 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2018-11-18 15:53:35.118 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2018-11-18 15:53:35.120 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Network discovery task starting
2018-11-18 15:53:36.096 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 0
2018-11-18 15:53:36.098 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2018-11-18 15:53:36.100 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ

2018-11-18 15:53:36.102 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Start
2018-11-18 15:53:36.275 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 02 
2018-11-18 15:53:36.278 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 03 
2018-11-18 15:53:36.494 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:36.498 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2018-11-18 15:53:36.501 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:36.506 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:36.510 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4A J
2018-11-18 15:53:36.514 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 50 P
2018-11-18 15:53:36.518 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:36.522 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4E N
2018-11-18 15:53:36.526 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 3A :
2018-11-18 15:53:36.530 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:36.533 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:36.538 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:36.541 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:36.546 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:36.549 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:36.553 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 32 2
2018-11-18 15:53:36.556 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:36.560 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:36.564 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:36.568 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:36.571 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:36.575 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:36.578 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:36.583 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:36.586 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 44 D
2018-11-18 15:53:36.590 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 43 C
2018-11-18 15:53:36.594 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:36.597 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 42 B
2018-11-18 15:53:36.599 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:36.601 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:36.603 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:36.606 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:36.608 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:36.611 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:36.614 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:JPAN:14,F102,4A09AF4DC0B09EFE
2018-11-18 15:53:36.617 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNetworkJoinedEvent [channel=14, panId=61698, epanId=4A09AF4DC0B09EFE]
2018-11-18 15:53:36.619 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:36.622 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:41.104 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 0
2018-11-18 15:53:41.105 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2018-11-18 15:53:41.107 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ

2018-11-18 15:53:41.109 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Start
2018-11-18 15:53:41.282 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 02 
2018-11-18 15:53:41.284 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 03 
2018-11-18 15:53:41.502 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:41.504 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2018-11-18 15:53:41.507 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:41.510 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:41.513 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4A J
2018-11-18 15:53:41.516 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 50 P
2018-11-18 15:53:41.519 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:41.522 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4E N
2018-11-18 15:53:41.525 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 3A :
2018-11-18 15:53:41.528 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:41.531 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:41.534 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:41.538 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:41.540 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:41.544 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:41.546 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 32 2
2018-11-18 15:53:41.550 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:41.552 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:41.556 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:41.558 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:41.562 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:41.564 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:41.568 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:41.571 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:41.574 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 44 D
2018-11-18 15:53:41.577 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 43 C
2018-11-18 15:53:41.581 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:41.584 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 42 B
2018-11-18 15:53:41.587 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:41.591 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:41.595 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:41.597 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:41.601 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:41.603 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:41.607 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:JPAN:14,F102,4A09AF4DC0B09EFE
2018-11-18 15:53:41.609 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNetworkJoinedEvent [channel=14, panId=61698, epanId=4A09AF4DC0B09EFE]
2018-11-18 15:53:41.611 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:41.613 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:46.110 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 0
2018-11-18 15:53:46.112 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2018-11-18 15:53:46.114 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1

2018-11-18 15:53:46.116 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Start
2018-11-18 15:53:46.612 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-18 15:53:51.118 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 1
2018-11-18 15:53:51.120 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2018-11-18 15:53:51.123 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2018-11-18 15:53:51.124 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Start
2018-11-18 15:53:51.249 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:51.251 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:51.253 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2B +
2018-11-18 15:53:51.254 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4E N
2018-11-18 15:53:51.256 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 3D =
2018-11-18 15:53:51.257 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 43 C
2018-11-18 15:53:51.259 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4F O
2018-11-18 15:53:51.260 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 4F O
2018-11-18 15:53:51.262 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:51.264 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:51.265 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:51.267 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:51.269 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:51.271 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 33 3
2018-11-18 15:53:51.272 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:51.274 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:51.276 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 31 1
2018-11-18 15:53:51.277 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:51.279 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 32 2
2018-11-18 15:53:51.280 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 2C ,
2018-11-18 15:53:51.282 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:51.283 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:51.285 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:51.286 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:51.288 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 41 A
2018-11-18 15:53:51.289 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:51.291 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 34 4
2018-11-18 15:53:51.292 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 44 D
2018-11-18 15:53:51.294 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 43 C
2018-11-18 15:53:51.296 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:51.297 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 42 B
2018-11-18 15:53:51.299 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 30 0
2018-11-18 15:53:51.300 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 39 9
2018-11-18 15:53:51.303 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:51.305 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 46 F
2018-11-18 15:53:51.307 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 45 E
2018-11-18 15:53:51.310 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0D 
2018-11-18 15:53:51.316 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,14,03,F102,4A09AF4DC0B09EFE
2018-11-18 15:53:51.318 [TRACE] [gesis.internal.TelegesisFrameHandler] - TELEGESIS: Get Packet
2018-11-18 15:53:51.320 [TRACE] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: 0A 

2018-11-18 15:53:51.612 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2018-11-18 15:53:56.126 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 2
2018-11-18 15:53:56.132 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Error: number of retries exceeded [3].
2018-11-18 15:53:56.131 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE

chris · November 18, 2018, 3:33pm

I would suggest to hard reset the stick as it seems to be trying to join a network by itself. Also, please confirm what firmware it has (it should be 309C for the DT stick, but it’s best to check).

Syn · November 18, 2018, 10:16pm

Thank you for your answer.

Firmware is 309C, in Telegesis Terminal I get:

Telegesis ETRX357
R309C-DTAG build12
000D6F0003E381A1

Also after some search in the forum and in the internet I have no idea, how to hard reset the stick. Could you point me in the right direction?

chris · November 18, 2018, 10:19pm

This firmware should be fine - I’ve used it during development here.

You can reset the stick with the AT&F command in the terminal. I think the binding should send this if you select the RESET option, although I’d need to check to be 100% sure.

Syn · November 19, 2018, 7:58am

Ok, I did that several times before without success.

Did that too. Got a Ok message in Telegesis Terminal, but again the same error.
I just noticed a message in the event log, that the firmware isn’t recognized.

2018-11-19 08:36:02.718 [nt.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:coordinator_telegesis:706002137 changed to UNKNOWN.

I also noticed, that idProduct is different from the setup I used following the guide mentioned above.

[08:46:52] openhabian@openHABianPi:~$ sudo lsusb -D /dev/bus/usb/001/004
Device: ID 10c4:8293 Cygnal Integrated Products, Inc. 
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               1.10
  bDeviceClass            0 (Defined at Interface level)
  bDeviceSubClass         0 
  bDeviceProtocol         0 
  bMaxPacketSize0        64
  idVendor           0x10c4 Cygnal Integrated Products, Inc.
  idProduct          0x8293 
  bcdDevice            1.00
  iManufacturer           1 Silicon Labs
  iProduct                2 Telegesis USB Device
  iSerial                 3 706002137
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           32
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0 
    bmAttributes         0x80
      (Bus Powered)
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           2
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass      0 
      bInterfaceProtocol      0 
      iInterface              2 Telegesis USB Device
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0040  1x 64 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x01  EP 1 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0040  1x 64 bytes
        bInterval               0
Device Status:     0x0000
  (Bus Powered)

so I changed

sudo modprobe cp210x vendor=0x10c4 product=0x89fb
echo 10c4 89fb > /sys/bus/usb-serial/drivers/cp210x/new_id

to

sudo modprobe cp210x vendor=0x10c4 product=0x8293
echo 10c4 8293 > /sys/bus/usb-serial/drivers/cp210x/new_id

But this changes nothing also.

chris · November 19, 2018, 8:07am

That’s normal - it just means that the status of updates is unknown which is because there are none.

That won’t matter - the device is communicating, it’s just not responding as expected.

Syn · November 19, 2018, 8:53am

Looks like it was the key to get the stick online after all.
I remembered, that I stepped through commands one by one and this line

echo 10c4 89fb > /sys/bus/usb-serial/drivers/cp210x/new_id

could only executed when logged in as root.
So I did that again with

echo 10c4 8293 > /sys/bus/usb-serial/drivers/cp210x/new_id

selected the RESET option in the binding and the stick came ONLINE.

Thanks again for your support!

chris · November 19, 2018, 9:17am

I’m not sure how this can be related . The device was clearly communicating with the binding as a serial port, so the device driver was clearly not the problem.

Anyway, if it’s working now, then that’s the main thing…

Syn · November 19, 2018, 9:50am

I agree with you.

I only tried that, because you mentioned the stick isn’t responding in the right way. Perhaps there are some differences in the sticks communication that are related to the different idProducts? I’m not a programmer or otherwise educated in computer science, I’m even a linux noob, so that’s just a guess.

Sidenote: I now also have a properties tab in PaperUI, which wasn’t the case before (just normal behavior I think, but if not, it’s maybe a helpful piece of information).

chris · November 19, 2018, 9:56am

There shouldn’t be. The ids are used by the system to decide if it is a serial driver, a keyboard, etc. Clearly it was showing up as a serial port as we were able to communicate. My guess is that it is not related, but I could be wrong…

There should be no difference in Paper UI, but can you show me what you actually mean by this new tab?

Syn · November 19, 2018, 10:04am

Sure:

chris · November 19, 2018, 10:59am

Ok, so these properties are just added now that the dongle is initialising - that’s expected.

Thanks.