Telegesis stick stopped working

Stehof · September 18, 2019, 1:02pm

Hi,

I hope somebody can help me out because I’m stuck now.
I’m using a Raspberry Pi with the last stable version at the moment (2.4.0-1), it’s installed with the default openhabian system image.

Recently I bought the Telegesis stick, after some time it worked and I could join devices to it.
But at an certain point it stopped working and went offline.
Already tried to remove everything including the binding and installing it all over again, but that didn’t solve the problem, this is the DEBUG log file I extracted after a reboot of the Pi:

2019-09-18 14:49:26.081 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:16d4295c507' changed from OFFLINE (COMMUNICATION_ERROR) to UNINITIALIZED
2019-09-18 14:49:26.131 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:16d4295c507' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2019-09-18 14:49:29.298 [temChannelLinkRemovedEvent] - Link 'WC_Sensor_Illuminance => zigbee:device:04000BB6:00158d00036b628a:00158D00036B628A_1_illuminance' has been removed.
2019-09-18 14:49:29.371 [temChannelLinkRemovedEvent] - Link 'Occupancy_WC_Sensor => zigbee:device:04000BB6:00158d00036b628a:00158D00036B628A_1_occupancy' has been removed.
2019-09-18 14:49:29.385 [temChannelLinkRemovedEvent] - Link 'Overloop_Occupancy_Sensor => zigbee:device:04000BB6:00158d00036cd4cc:00158D00036CD4CC_1_occupancy' has been removed.
2019-09-18 14:50:14.553 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee
2019-09-18 14:50:14.582 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=143, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=41} - org.openhab.binding.zigbee
2019-09-18 14:50:14.592 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTING - org.openhab.binding.zigbee
2019-09-18 14:50:14.596 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTED - org.openhab.binding.zigbee
2019-09-18 14:50:14.608 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService
2019-09-18 14:50:14.639 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory}={service.id=144, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory, component.id=42} - org.openhab.binding.zigbee
2019-09-18 14:50:14.717 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=145, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=43} - org.openhab.binding.zigbee
2019-09-18 14:50:14.740 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=146, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=44} - org.openhab.binding.zigbee
2019-09-18 14:50:14.751 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.converter.ZigBeeChannelConverterProvider}={service.id=147, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeDefaultChannelConverterProvider, component.id=45} - org.openhab.binding.zigbee
2019-09-18 14:50:14.788 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
2019-09-18 14:50:14.799 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=148, service.bundleid=262, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=46} - org.openhab.binding.zigbee.cc2531
2019-09-18 14:50:14.805 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
2019-09-18 14:50:14.808 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
2019-09-18 14:50:14.816 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
2019-09-18 14:50:14.828 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=149, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=47} - org.openhab.binding.zigbee.ember
2019-09-18 14:50:14.832 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=150, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=48} - org.openhab.binding.zigbee.ember
2019-09-18 14:50:14.836 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
2019-09-18 14:50:14.839 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
2019-09-18 14:50:14.846 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
2019-09-18 14:50:14.858 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=151, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=49} - org.openhab.binding.zigbee.telegesis
2019-09-18 14:50:14.862 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis
2019-09-18 14:50:14.865 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis
2019-09-18 14:50:14.876 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=152, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=50} - org.openhab.binding.zigbee.telegesis
2019-09-18 14:50:14.879 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee
2019-09-18 14:50:14.889 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=153, service.bundleid=265, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=51} - org.openhab.binding.zigbee.xbee
2019-09-18 14:50:14.892 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
2019-09-18 14:50:14.896 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
2019-09-18 14:50:36.936 [.ItemChannelLinkAddedEvent] - Link 'WC_Sensor_Illuminance-zigbee:device:04000BB6:00158d00036b628a:00158D00036B628A_1_illuminance' has been added.
2019-09-18 14:50:37.145 [.ItemChannelLinkAddedEvent] - Link 'Occupancy_WC_Sensor-zigbee:device:04000BB6:00158d00036b628a:00158D00036B628A_1_occupancy' has been added.
2019-09-18 14:50:37.181 [.ItemChannelLinkAddedEvent] - Link 'Overloop_Occupancy_Sensor-zigbee:device:04000BB6:00158d00036cd4cc:00158D00036CD4CC_1_occupancy' has been added.
2019-09-18 14:50:38.697 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=395, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
2019-09-18 14:50:38.731 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=396, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
2019-09-18 14:50:38.791 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:16d4295c507' changed from UNINITIALIZED to INITIALIZING
2019-09-18 14:50:38.806 [DEBUG] [e.telegesis.handler.TelegesisHandler] - Initializing ZigBee Telegesis serial bridge handler.
2019-09-18 14:50:38.809 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_telegesis:16d4295c507].
2019-09-18 14:50:38.814 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2019-09-18 14:50:38.816 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 11811
2019-09-18 14:50:38.821 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 4CD0FC84B9BAFBF5
2019-09-18 14:50:38.856 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 3FF9ED59361A0EE0C785A00F0E742EE0
2019-09-18 14:50:38.873 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2019-09-18 14:50:38.885 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=true
2019-09-18 14:50:38.887 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 3FF9ED59361A0EE0C785A00F0E742EE0
2019-09-18 14:50:38.892 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 3FF9ED59361A0EE0C785A00F0E742EE0
2019-09-18 14:50:38.895 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
2019-09-18 14:50:38.897 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network
2019-09-18 14:50:38.938 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
2019-09-18 14:50:38.947 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_telegesis:16d4295c507' has been updated.
2019-09-18 14:50:39.011 [DEBUG] [e.telegesis.handler.TelegesisHandler] - ZigBee Telegesis Coordinator opening Port:'/dev/ttyTeleUSB' PAN:2e23, EPAN:4CD0FC84B9BAFBF5, Channel:11
2019-09-18 14:50:39.061 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:16d4295c507' changed from INITIALIZING to UNKNOWN
2019-09-18 14:50:39.060 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2019-09-18 14:50:40.110 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2019-09-18 14:50:40.114 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2019-09-18 14:50:41.301 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2019-09-18 14:50:41.421 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2019-09-18 14:50:41.446 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyTeleUSB] at 19200 baud, flow control FLOWCONTROL_OUT_NONE.
2019-09-18 14:50:41.468 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=UNINITIALISED
2019-09-18 14:50:41.737 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyTeleUSB] is initialized.
2019-09-18 14:50:43.518 [home.event.InboxAddedEvent] - Discovery Result with UID 'zigbee:coordinator_telegesis:04000BB6' has been added.
2019-09-18 14:50:43.516 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:coordinator_telegesis:04000BB6' to inbox.
2019-09-18 14:50:44.820 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=null, PanID=0, EPanId=null
2019-09-18 14:50:44.821 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2019-09-18 14:50:44.825 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key initialise 5A6967426565416C6C69616E63653039
2019-09-18 14:50:44.828 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key initialise 3FF9ED59361A0EE0C785A00F0E742EE0
2019-09-18 14:50:45.384 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_INSECURE
2019-09-18 14:50:49.290 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2019-09-18 14:50:49.297 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:16d4295c507' changed from UNKNOWN to OFFLINE: Failed to startup ZigBee transport layer
2019-09-18 14:50:49.299 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:16d4295c507' changed from OFFLINE: Failed to startup ZigBee transport layer to OFFLINE (COMMUNICATION_ERROR)

Can anyone tell me what is going wrong?

Bruce_Osborne · September 18, 2019, 1:39pm

I do not use Zigbee but see if the log viewer points out anything obvious.

https://www.cd-jackson.com/index.php/openhab/zigbee-log-viewer

Andrew_Rowe · September 18, 2019, 1:58pm

So this was a running working zigbee network, then it stopped and now wiping it all out and starting from scratch doesn’t fix it. How long did it work? How many devices?
Nothing changed? You’re running 2.4 stable with the binding that came with it right? Not a jar file in add ons right?
Maybe a serial port issue. Nothing else got added to the system?

chris · September 18, 2019, 2:39pm

Can you provide the log with debug enabled as per the binding docs please?

Stehof · September 18, 2019, 4:50pm

yea of course I can, here you go, thanks in advance:

tail -f /var/log/openhab2/openhab.log /var/log/openhab2/events.log

2019-09-18 18:45:18.204 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee
2019-09-18 18:45:18.230 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=143, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=41} - org.openhab.binding.zigbee
2019-09-18 18:45:18.239 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTING - org.openhab.binding.zigbee
2019-09-18 18:45:18.244 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTED - org.openhab.binding.zigbee
2019-09-18 18:45:18.257 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService
2019-09-18 18:45:18.288 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory}={service.id=144, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory, component.id=42} - org.openhab.binding.zigbee
2019-09-18 18:45:18.369 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=145, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=43} - org.openhab.binding.zigbee
2019-09-18 18:45:18.410 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=146, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=44} - org.openhab.binding.zigbee
2019-09-18 18:45:18.464 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.converter.ZigBeeChannelConverterProvider}={service.id=147, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeDefaultChannelConverterProvider, component.id=45} - org.openhab.binding.zigbee
2019-09-18 18:45:18.473 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
2019-09-18 18:45:18.489 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=148, service.bundleid=262, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=46} - org.openhab.binding.zigbee.cc2531
2019-09-18 18:45:18.504 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
2019-09-18 18:45:18.508 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
2019-09-18 18:45:18.527 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
2019-09-18 18:45:18.548 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=149, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=47} - org.openhab.binding.zigbee.ember
2019-09-18 18:45:26.837 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=150, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=48} - org.openhab.binding.zigbee.ember
2019-09-18 18:45:26.856 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
2019-09-18 18:45:26.859 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
2019-09-18 18:45:26.870 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
2019-09-18 18:45:26.893 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=151, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=49} - org.openhab.binding.zigbee.telegesis
2019-09-18 18:45:26.898 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis
2019-09-18 18:45:26.902 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis
2019-09-18 18:45:26.931 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=152, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=50} - org.openhab.binding.zigbee.telegesis
2019-09-18 18:45:26.935 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee
2019-09-18 18:45:26.953 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=153, service.bundleid=265, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=51} - org.openhab.binding.zigbee.xbee
2019-09-18 18:45:26.976 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
2019-09-18 18:45:26.979 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
2019-09-18 18:45:48.060 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=345, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
2019-09-18 18:45:48.072 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=346, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
2019-09-18 18:45:48.104 [DEBUG] [e.telegesis.handler.TelegesisHandler] - Initializing ZigBee Telegesis serial bridge handler.
2019-09-18 18:45:48.109 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_telegesis:04000BB6].
2019-09-18 18:45:48.113 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2019-09-18 18:45:48.115 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 644
2019-09-18 18:45:48.117 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 475D989ED84F5871
2019-09-18 18:45:48.123 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 1665267ADE06D844B3FBE7F8EE3D744A
2019-09-18 18:45:48.125 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
2019-09-18 18:45:48.128 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=true
2019-09-18 18:45:48.130 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 1665267ADE06D844B3FBE7F8EE3D744A
2019-09-18 18:45:48.134 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 1665267ADE06D844B3FBE7F8EE3D744A
2019-09-18 18:45:48.136 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
2019-09-18 18:45:48.139 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising network
2019-09-18 18:45:48.265 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
2019-09-18 18:45:48.298 [DEBUG] [e.telegesis.handler.TelegesisHandler] - ZigBee Telegesis Coordinator opening Port:'/dev/ttyTeleUSB' PAN:284, EPAN:475D989ED84F5871, Channel:11
2019-09-18 18:45:48.311 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2019-09-18 18:45:49.317 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2019-09-18 18:45:49.319 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2019-09-18 18:45:49.428 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
2019-09-18 18:45:49.440 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
2019-09-18 18:45:49.842 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE
2019-09-18 18:45:49.846 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
2019-09-18 18:45:49.859 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE
2019-09-18 18:45:49.875 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
2019-09-18 18:45:50.528 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00111700D0: Node 0 added to the network
2019-09-18 18:45:50.616 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00036B628A: Node 11911 added to the network
2019-09-18 18:45:50.621 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00036CD4CC: Node 12123 added to the network
2019-09-18 18:45:50.623 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
2019-09-18 18:45:50.628 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle initialize.
2019-09-18 18:45:50.632 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to UNINITIALISED
2019-09-18 18:45:50.636 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyTeleUSB] at 19200 baud, flow control FLOWCONTROL_OUT_NONE.
2019-09-18 18:45:50.645 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=UNINITIALISED
2019-09-18 18:45:50.815 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyTeleUSB] is initialized.
2019-09-18 18:45:50.856 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread started
2019-09-18 18:45:50.895 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:50.899 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2019-09-18 18:45:50.905 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1
2019-09-18 18:45:51.021 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:51.080 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null, status=SUCCESS]
2019-09-18 18:45:51.085 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:51.090 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
2019-09-18 18:45:51.094 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E1=0
2019-09-18 18:45:51.206 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:51.209 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null, status=SUCCESS]
2019-09-18 18:45:51.214 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:51.218 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
2019-09-18 18:45:51.223 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E0=0
2019-09-18 18:45:51.335 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:51.338 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null, status=SUCCESS]
2019-09-18 18:45:51.344 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:51.348 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
2019-09-18 18:45:51.352 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ
2019-09-18 18:45:51.749 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2019-09-18 18:45:51.756 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:JPAN:11,2E23,4CD0FC84B9BAFBF5
2019-09-18 18:45:51.763 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNetworkJoinedEvent [channel=11, panId=11811, epanId=4CD0FC84B9BAFBF5]
2019-09-18 18:45:51.769 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:51.773 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSoftwareResetCommand [status=SUCCESS]
2019-09-18 18:45:51.777 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:51.782 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2019-09-18 18:45:51.788 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1
2019-09-18 18:45:51.899 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:51.902 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null, status=SUCCESS]
2019-09-18 18:45:51.906 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:51.910 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
2019-09-18 18:45:51.914 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E1=0
2019-09-18 18:45:52.026 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:52.030 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null, status=SUCCESS]
2019-09-18 18:45:52.033 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:52.038 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
2019-09-18 18:45:52.041 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E0=0
2019-09-18 18:45:52.152 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:52.156 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null, status=SUCCESS]
2019-09-18 18:45:52.162 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:52.167 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804]
2019-09-18 18:45:52.171 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E=8804
2019-09-18 18:45:52.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:52.288 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804, status=SUCCESS]
2019-09-18 18:45:52.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:52.296 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password]
2019-09-18 18:45:52.301 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A=015E:password
2019-09-18 18:45:52.425 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:52.430 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password, status=SUCCESS]
2019-09-18 18:45:52.453 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:52.458 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9]
2019-09-18 18:45:52.464 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS10=56A9
2019-09-18 18:45:52.577 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:52.579 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9, status=SUCCESS]
2019-09-18 18:45:52.584 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:52.586 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetPromptEnable2Command [configuration=6B3C]
2019-09-18 18:45:52.588 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0F=6B3C
2019-09-18 18:45:52.701 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:52.703 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetPromptEnable2Command [configuration=6B3C, status=SUCCESS]
2019-09-18 18:45:52.708 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:52.711 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayProductIdentificationCommand [deviceName=null, firmwareRevision=null, ieeeAddress=null]
2019-09-18 18:45:52.715 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATI
2019-09-18 18:45:52.756 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
2019-09-18 18:45:52.856 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Telegesis ETRX357-LRS
2019-09-18 18:45:52.859 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:R309C
2019-09-18 18:45:52.862 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:000D6F00111700D0
2019-09-18 18:45:52.871 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:52.885 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayProductIdentificationCommand [deviceName=ETRX357-LRS, firmwareRevision=309C, ieeeAddress=000D6F00111700D0, status=SUCCESS]
2019-09-18 18:45:52.888 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=null, PanID=0, EPanId=null
2019-09-18 18:45:52.890 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key initialise 5A6967426565416C6C69616E63653039
2019-09-18 18:45:52.892 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key initialise 1665267ADE06D844B3FBE7F8EE3D744A
2019-09-18 18:45:52.909 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:52.911 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetTrustCentreLinkKeyCommand [linkKey=5A6967426565416C6C69616E63653039, password=password]
2019-09-18 18:45:52.927 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS09=5A6967426565416C6C69616E63653039:password
2019-09-18 18:45:53.047 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:53.049 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetTrustCentreLinkKeyCommand [linkKey=5A6967426565416C6C69616E63653039, password=password, status=SUCCESS]
2019-09-18 18:45:53.053 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:53.055 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetNetworkKeyCommand [networkKey=1665267ADE06D844B3FBE7F8EE3D744A, password=password]
2019-09-18 18:45:53.078 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS08=1665267ADE06D844B3FBE7F8EE3D744A:password
2019-09-18 18:45:53.182 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
2019-09-18 18:45:53.200 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:53.201 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetNetworkKeyCommand [networkKey=1665267ADE06D844B3FBE7F8EE3D744A, password=password, status=SUCCESS]
2019-09-18 18:45:53.207 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
2019-09-18 18:45:53.209 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
2019-09-18 18:45:53.217 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:53.361 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetChannelMaskCommand [channelMask=0001]
2019-09-18 18:45:53.363 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS00=0001
2019-09-18 18:45:53.476 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:53.478 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetChannelMaskCommand [channelMask=0001, status=SUCCESS]
2019-09-18 18:45:53.483 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:53.484 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:45:53.487 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:45:53.620 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-17,2E23,4CD0FC84B9BAFBF5
2019-09-18 18:45:53.625 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:53.628 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-17, panId=11811, epanId=4CD0FC84B9BAFBF5, status=SUCCESS]
2019-09-18 18:45:53.633 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:53.635 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisChangeChannelCommand [channel=11]
2019-09-18 18:45:53.638 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+CCHANGE:11
2019-09-18 18:45:53.752 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:53.754 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisChangeChannelCommand [channel=11, status=SUCCESS]
2019-09-18 18:45:53.757 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_INSECURE
2019-09-18 18:45:53.764 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Setting Telegesis trust centre link mode: TC_JOIN_INSECURE
2019-09-18 18:45:53.767 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:53.769 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisallowTcJoinCommand [disallowJoin=false, password=password]
2019-09-18 18:45:53.773 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A5=0:password
2019-09-18 18:45:53.889 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:53.891 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisallowTcJoinCommand [disallowJoin=false, password=password, status=SUCCESS]
2019-09-18 18:45:53.894 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:53.897 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisallowUnsecuredRejoinCommand [disallowRejoin=false, password=password]
2019-09-18 18:45:53.900 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A3=0:password
2019-09-18 18:45:54.015 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:54.016 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisallowUnsecuredRejoinCommand [disallowRejoin=false, password=password, status=SUCCESS]
2019-09-18 18:45:54.021 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:54.023 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetOutputClustersCommand [clusterList=[1280]]
2019-09-18 18:45:54.026 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS4C=0500
2019-09-18 18:45:54.140 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:54.141 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetOutputClustersCommand [clusterList=[1280], status=SUCCESS]
2019-09-18 18:45:54.144 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle startup.
2019-09-18 18:45:54.146 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Reinitialising Telegesis dongle and forming network.
2019-09-18 18:45:54.150 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:54.151 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisRestoreFactoryDefaultsCommand []
2019-09-18 18:45:54.154 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT&F
2019-09-18 18:45:54.781 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
2019-09-18 18:45:54.783 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:54.784 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisRestoreFactoryDefaultsCommand [status=SUCCESS]
2019-09-18 18:45:54.786 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:54.789 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
2019-09-18 18:45:54.791 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1
2019-09-18 18:45:54.903 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ATS124=1
2019-09-18 18:45:54.905 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:54.906 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null, status=SUCCESS]
2019-09-18 18:45:54.909 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:54.915 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
2019-09-18 18:45:54.922 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E1=0
2019-09-18 18:45:55.032 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:55.033 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null, status=SUCCESS]
2019-09-18 18:45:55.035 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:55.037 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
2019-09-18 18:45:55.039 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E0=0
2019-09-18 18:45:55.153 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:55.154 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null, status=SUCCESS]
2019-09-18 18:45:55.156 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:55.172 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804]
2019-09-18 18:45:55.175 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E=8804
2019-09-18 18:45:55.297 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:55.300 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804, status=SUCCESS]
2019-09-18 18:45:55.303 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:55.305 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password]
2019-09-18 18:45:55.308 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A=015E:password
2019-09-18 18:45:55.426 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:55.429 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password, status=SUCCESS]
2019-09-18 18:45:55.444 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:55.446 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9]
2019-09-18 18:45:55.449 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS10=56A9
2019-09-18 18:45:56.507 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:56.503 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetExtendedFunctionCommand [configuration=56A9]
2019-09-18 18:45:56.509 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9, status=SUCCESS]
2019-09-18 18:45:56.519 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Error setting Telegesis Extended Function register
2019-09-18 18:45:56.527 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
2019-09-18 18:45:56.534 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
2019-09-18 18:45:57.521 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:57.529 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:45:57.544 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:45:57.660 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:45:57.662 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:57.665 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:45:58.667 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:58.670 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:45:58.672 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:45:58.789 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:45:58.791 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:58.794 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:45:59.796 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:45:59.797 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:45:59.800 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:45:59.916 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:45:59.917 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:45:59.919 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:00.921 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:00.923 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:00.926 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:01.041 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:01.043 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:01.044 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:01.329 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTING - com.zsmartsystems.zigbee
2019-09-18 18:46:01.349 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTED - com.zsmartsystems.zigbee
2019-09-18 18:46:01.356 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.cc2531
2019-09-18 18:46:01.363 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.cc2531
2019-09-18 18:46:01.373 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.ember
2019-09-18 18:46:01.384 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.ember
2019-09-18 18:46:01.390 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.telegesis
2019-09-18 18:46:01.395 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.telegesis
2019-09-18 18:46:01.401 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.xbee
2019-09-18 18:46:01.407 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.xbee
2019-09-18 18:46:02.046 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:02.048 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:02.052 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:02.169 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:02.171 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:02.173 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:03.174 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:03.176 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:03.178 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:03.302 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:03.304 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:03.306 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:04.310 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:04.316 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:04.318 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:04.434 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:04.437 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:04.439 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:05.441 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:05.442 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:05.445 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:05.560 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:05.562 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:05.563 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:06.565 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:06.568 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:06.573 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:06.690 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:06.694 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:06.696 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:07.699 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:07.702 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:07.708 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:07.825 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:07.828 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:07.832 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:08.835 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:08.836 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:08.838 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:08.955 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:08.956 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:08.958 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:09.960 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:09.963 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:09.972 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:10.088 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:10.090 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:10.091 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:11.093 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:11.096 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:11.101 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:11.218 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:11.221 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:11.225 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:12.227 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:12.231 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:12.235 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
2019-09-18 18:46:12.352 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
2019-09-18 18:46:12.355 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
2019-09-18 18:46:12.359 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
2019-09-18 18:46:13.362 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
2019-09-18 18:46:13.364 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
2019-09-18 18:46:13.370 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

Stehof · September 18, 2019, 4:55pm

That’s right, I had it running for 2 day’s with 2 aquara motion sensors.
After it went offline and whatever I did it kept offline, I deleted everything included the binding, restarted and started from scratch, but that didn’t fix it as well…
The binding is installed though the PaperUI and not a jar file indeed.

When I look at the output of the port it seems to be working fine (with command cat /dev/ttyUSB1)
Don’t know what to do now…

Stehof · September 18, 2019, 4:55pm

Will do that as well thanks!

chris · September 18, 2019, 5:17pm

Thanks - can you please do this another couple of times? I just want to see if this is repeatable - ie I want to see if it stops at exactly the same place or if it does a similar thing in a slightly different place as this might help understand what’s happening.

Stehof · September 18, 2019, 5:59pm

Sure, this is the 2nd time:

>2019-09-18 19:55:43.058 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=143, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=41} - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.066 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTING - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.069 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTED - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.078 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService
> 2019-09-18 19:55:43.117 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory}={service.id=144, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory, component.id=42} - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.204 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=145, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=43} - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.236 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=146, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=44} - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.281 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.converter.ZigBeeChannelConverterProvider}={service.id=147, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeDefaultChannelConverterProvider, component.id=45} - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.283 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee
> 2019-09-18 19:55:43.294 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=148, service.bundleid=262, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=46} - org.openhab.binding.zigbee.cc2531
> 2019-09-18 19:55:43.298 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
> 2019-09-18 19:55:43.302 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
> 2019-09-18 19:55:43.310 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
> 2019-09-18 19:55:43.324 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=149, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=47} - org.openhab.binding.zigbee.ember
> 2019-09-18 19:55:43.329 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=150, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=48} - org.openhab.binding.zigbee.ember
> 2019-09-18 19:55:43.333 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
> 2019-09-18 19:55:43.337 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
> 2019-09-18 19:55:43.346 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
> 2019-09-18 19:55:43.359 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=151, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=49} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 19:55:43.365 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis
> 2019-09-18 19:55:43.369 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis
> 2019-09-18 19:55:43.379 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=152, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=50} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 19:55:43.383 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
> 2019-09-18 19:55:43.394 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=153, service.bundleid=265, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=51} - org.openhab.binding.zigbee.xbee
> 2019-09-18 19:55:43.398 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
> 2019-09-18 19:55:43.402 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
> 2019-09-18 19:55:43.411 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee
> 2019-09-18 19:56:16.406 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=345, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 19:56:16.453 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=346, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 19:56:16.473 [DEBUG] [e.telegesis.handler.TelegesisHandler] - Initializing ZigBee Telegesis serial bridge handler.
> 2019-09-18 19:56:16.478 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_telegesis:04000BB6].
> 2019-09-18 19:56:16.491 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
> 2019-09-18 19:56:16.493 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 644
> 2019-09-18 19:56:16.495 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 475D989ED84F5871
> 2019-09-18 19:56:16.509 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 1665267ADE06D844B3FBE7F8EE3D744A
> 2019-09-18 19:56:16.511 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
> 2019-09-18 19:56:16.530 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false
> 2019-09-18 19:56:16.532 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 1665267ADE06D844B3FBE7F8EE3D744A
> 2019-09-18 19:56:16.535 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 1665267ADE06D844B3FBE7F8EE3D744A
> 2019-09-18 19:56:16.537 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
> 2019-09-18 19:56:16.539 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
> 2019-09-18 19:56:16.576 [DEBUG] [e.telegesis.handler.TelegesisHandler] - ZigBee Telegesis Coordinator opening Port:'/dev/ttyUSB0' PAN:284, EPAN:475D989ED84F5871, Channel:11
> 2019-09-18 19:56:16.608 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
> 2019-09-18 19:56:17.619 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
> 2019-09-18 19:56:17.621 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
> 2019-09-18 19:56:17.698 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
> 2019-09-18 19:56:17.704 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
> 2019-09-18 19:56:18.133 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE
> 2019-09-18 19:56:18.136 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
> 2019-09-18 19:56:18.138 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE
> 2019-09-18 19:56:18.155 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
> 2019-09-18 19:56:18.437 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00111700D0: Node 0 added to the network
> 2019-09-18 19:56:18.531 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00036B628A: Node 11911 added to the network
> 2019-09-18 19:56:18.539 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00036CD4CC: Node 12123 added to the network
> 2019-09-18 19:56:18.541 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
> 2019-09-18 19:56:18.547 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to UNINITIALISED
> 2019-09-18 19:56:18.552 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle initialize.
> 2019-09-18 19:56:18.554 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=UNINITIALISED
> 2019-09-18 19:56:18.556 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB0] at 19200 baud, flow control FLOWCONTROL_OUT_NONE.
> 2019-09-18 19:56:18.669 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB0] is initialized.
> 2019-09-18 19:56:18.692 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread started
> 2019-09-18 19:56:18.711 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 19:56:18.716 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
> 2019-09-18 19:56:18.722 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1
> 2019-09-18 19:56:19.273 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ZBûçÿwú
> 2019-09-18 19:56:19.298 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Ò
> 2019-09-18 19:56:19.306 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Ò{BÒÒ#þÒ!þÂÒckþJZúHgHcøF	H
> 2019-09-18 19:56:19.311 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þÒ
> 2019-09-18 19:56:19.530 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒZ
> 2019-09-18 19:56:19.535 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:åZFÿR
> 2019-09-18 19:56:19.705 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
> 2019-09-18 19:56:19.715 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 19:56:20.273 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òkò£JþëþëJÿÒkeþÒJeþHZÿÆÿÂRÖîÿÿÒ
> 2019-09-18 19:56:20.281 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒÿÒ
> 2019-09-18 19:56:20.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÆþÒ
> 2019-09-18 19:56:20.292 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þÒ
> 2019-09-18 19:56:20.295 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿ
> 2019-09-18 19:56:20.302 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þÒ
> 2019-09-18 19:56:20.349 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.
> 2019-09-18 19:56:20.422 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒÒZFþÒ{+Rkþ
> 2019-09-18 19:56:20.710 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
> 2019-09-18 19:56:20.716 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2
> 2019-09-18 19:56:20.768 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.
> 2019-09-18 19:56:21.285 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òRkþJþRJþRJúþ£#þÒJþûJÿRúÿÿHûÖÊÿêçþZRøçÿú
> 2019-09-18 19:56:21.289 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒÒ
> 2019-09-18 19:56:21.294 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Æþ
> 2019-09-18 19:56:21.301 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:HþÒkKþÒJÿ{KþZÂÒZþJJZúØVèþÒ
> 2019-09-18 19:56:21.407 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þÒ
> 2019-09-18 19:56:21.412 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿÒ
> 2019-09-18 19:56:21.416 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þ¿
> 2019-09-18 19:56:21.421 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒÒ
> 2019-09-18 19:56:21.433 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Ò
> 2019-09-18 19:56:21.439 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒZFþÒBR
> 2019-09-18 19:56:21.453 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òR
> 2019-09-18 19:56:21.716 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
> 2019-09-18 19:56:21.721 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 3
> 2019-09-18 19:56:22.288 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿRcòëJÿRëþRJÿJþÒKþkÿRþãûçR@XZÿBÿúÚRZÒêïÿZRþÒ
> 2019-09-18 19:56:22.292 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒZk
> 2019-09-18 19:56:22.297 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:÷Ò
> 2019-09-18 19:56:22.302 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:HþÒ
> 2019-09-18 19:56:22.415 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:úJþÒ+þzÂÒþRJZBh£xHÿZ
> 2019-09-18 19:56:22.434 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þ
> 2019-09-18 19:56:22.437 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿÒ
> 2019-09-18 19:56:22.439 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þ
> 2019-09-18 19:56:22.444 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒÒkÒ
> 2019-09-18 19:56:22.447 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Ò{ÊÒBï
> 2019-09-18 19:56:22.721 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
> 2019-09-18 19:56:22.724 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 4
> 2019-09-18 19:56:23.279 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òkòRaïRJþ[JøþZJþ+þÚJþÒJþRþgHZÿòÿÂRÖJÿ
> 2019-09-18 19:56:23.282 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þZÚÿþÒ
> 2019-09-18 19:56:23.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒþÒ
> 2019-09-18 19:56:23.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÆÓÒ
> 2019-09-18 19:56:23.290 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:HþÒaHþÒJþJþZþJZúØèX
> 2019-09-18 19:56:23.291 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þÒ
> 2019-09-18 19:56:23.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þ
> 2019-09-18 19:56:23.298 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òÒ
> 2019-09-18 19:56:23.300 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òÒZFÿÒ{kR
> 2019-09-18 19:56:23.303 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿ
> 2019-09-18 19:56:23.305 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òR
> 2019-09-18 19:56:24.424 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
> 2019-09-18 19:56:24.424 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 0
> 2019-09-18 19:56:24.427 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
> 2019-09-18 19:56:24.426 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 5
> 2019-09-18 19:56:24.429 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E1=0
> 2019-09-18 19:56:24.548 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òJþëJþRJøþÒJþÒJþÒjãþzáÿ{XRò@XZÿÿBrJÿêïÈZÒøãþÒ
> 2019-09-18 19:56:24.550 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:zÒZ
> 2019-09-18 19:56:24.553 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:÷Ònk{ÿÒ
> 2019-09-18 19:56:24.561 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:HþwJÿJþZÂçZþ{jZòhgh»FkëþÒ
> 2019-09-18 19:56:24.565 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þÒ
> 2019-09-18 19:56:24.568 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿÿ
> 2019-09-18 19:56:24.596 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿÒ
> 2019-09-18 19:56:24.625 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Ò§kç§cÒZFÿÒBR
> 2019-09-18 19:56:24.629 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òï
> 2019-09-18 19:56:24.646 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òR
> 2019-09-18 19:56:25.279 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òJkþRJþRkûþJþ[KþJþRÿHZÿFÿBÂRÖJÿ
> 2019-09-18 19:56:25.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:êÿZRûþÒ
> 2019-09-18 19:56:25.290 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Òþ
> 2019-09-18 19:56:25.305 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:HþÒ
> 2019-09-18 19:56:25.419 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:HþFhÒJþJþZÂÒ{ÿ§kZòBØFþúkþþÿ
> 2019-09-18 19:56:25.426 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSoftwareResetCommand []
> 2019-09-18 19:56:25.428 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 5
> 2019-09-18 19:56:25.459 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þ
> 2019-09-18 19:56:25.463 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒÒ
> 2019-09-18 19:56:25.464 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ö
> 2019-09-18 19:56:25.470 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒZÊÒbR
> 2019-09-18 19:56:25.476 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿ¯
> 2019-09-18 19:56:26.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òRcïRJþRJþJÿÒJþJþÒJþRú@xZÿFÿrÖJÿRøçÿÒoÚþÒjÆþÒ
> 2019-09-18 19:56:26.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:HþÒ
> 2019-09-18 19:56:26.291 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒJþÒkþZÂÒZkþJÿòFHhÿ:
> 2019-09-18 19:56:26.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿ
> 2019-09-18 19:56:26.294 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿ[
> 2019-09-18 19:56:26.296 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:þ
> 2019-09-18 19:56:26.298 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Òÿ
> 2019-09-18 19:56:26.300 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒÒ
> 2019-09-18 19:56:26.303 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÒZVÿÒzÒBR
> 2019-09-18 19:56:26.305 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òO
> 2019-09-18 19:56:26.406 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:òr
> 2019-09-18 19:56:26.430 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
> 2019-09-18 19:56:26.432 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 6
> 2019-09-18 19:56:27.228 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ÿRJÿJþëJÿÒJþ£JþÒJþoRÿÒû²Rò@XZÿÿBÂvÞJÿ
> 2019-09-18 19:56:27.256 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:56:27.266 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:00007522*s)(190710081954S)(0000000426*s)
> 2019-09-18 19:56:27.271 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.32.0(00007)
> 2019-09-18 19:56:27.274 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.32.0(00007)
> 2019-09-18 19:56:27.276 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.32.0(00006)
> 2019-09-18 19:56:27.292 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.36.0(00000)
> 2019-09-18 19:56:27.432 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
> 2019-09-18 19:56:27.435 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 7
> 2019-09-18 19:56:28.434 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
> 2019-09-18 19:56:28.438 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 8
> 2019-09-18 19:56:29.431 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 1
> 2019-09-18 19:56:29.435 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
> 2019-09-18 19:56:29.440 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E0=0
> 2019-09-18 19:56:29.441 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Telegesis interrupted in sendRequest TelegesisSetPromptEnable1Command [configuration=8804]
> 2019-09-18 19:56:29.444 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Error setting Telegesis Prompt 1 register
> 2019-09-18 19:56:29.451 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
> 2019-09-18 19:56:29.454 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
> 2019-09-18 19:56:30.277 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1()
> 2019-09-18 19:56:30.279 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.7.0(236.0*V)
> 2019-09-18 19:56:30.281 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.7.0(236.0*V)
> 2019-09-18 19:56:30.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.7.0(236.0*V)
> 2019-09-18 19:56:30.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:31.7.0(000*A)
> 2019-09-18 19:56:30.288 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:51.7.0(000*A)
> 2019-09-18 19:56:30.291 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:71.7.0(000*A)
> 2019-09-18 19:56:30.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:21.7.0(00.064*kW)
> 2019-09-18 19:56:30.295 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:41.7.0(00.144*kW)
> 2019-09-18 19:56:30.297 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:61.7.0(00.000*kW)
> 2019-09-18 19:56:30.300 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:22.7.0(00.000*kW)
> 2019-09-18 19:56:30.302 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:42.7.0(00.000*kW)
> 2019-09-18 19:56:30.304 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:62.7.0(00.000*kW)
> 2019-09-18 19:56:30.306 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.1.0(003)
> 2019-09-18 19:56:30.308 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:96.1.0(47303031(1
> 2019-09-18 19:56:30.311 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.2.1(190918195502S)(00557.274*m3)
> 2019-09-18 19:56:30.420 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:!13BB
> 2019-09-18 19:56:30.853 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTING - com.zsmartsystems.zigbee
> 2019-09-18 19:56:30.876 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTED - com.zsmartsystems.zigbee
> 2019-09-18 19:56:30.887 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.cc2531
> 2019-09-18 19:56:30.899 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.cc2531
> 2019-09-18 19:56:30.909 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.ember
> 2019-09-18 19:56:30.920 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.ember
> 2019-09-18 19:56:30.928 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.telegesis
> 2019-09-18 19:56:30.934 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.telegesis
> 2019-09-18 19:56:30.939 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.xbee
> 2019-09-18 19:56:30.946 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.xbee
> 2019-09-18 19:56:32.095 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:6.0(00000)
> 2019-09-18 19:56:32.097 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.36.0(00000)
> 2019-09-18 19:56:33.423 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.13.0()
> 2019-09-18 19:56:33.427 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.7.0(236.0*V)
> 2019-09-18 19:56:33.430 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.7.0(236.0*V)
> 2019-09-18 19:56:33.432 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.7.0(236.0*V)
> 2019-09-18 19:56:33.434 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:31.7.0(000*A)
> 2019-09-18 19:56:34.443 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS Timer: Timeout 2
> 2019-09-18 19:56:34.450 [DEBUG] [gesis.internal.TelegesisFrameHandler] - Error: number of retries exceeded [3].
> 2019-09-18 19:56:35.273 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:56:35.275 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:00.000*kW)
> 2019-09-18 19:56:35.278 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:62.7.0(00.000*kW)
> 2019-09-18 19:56:35.280 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.1.0(003)
> 2019-09-18 19:56:35.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:96.1.0(4730303339303031383033353639373138)
> 2019-09-18 19:56:35.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.2.1(190918195502S)(00557.274*m3)
> 2019-09-18 19:56:36.422 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:!6F40
> 2019-09-18 19:56:37.412 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:/XMX5LGBBLA4415440609
> 2019-09-18 19:56:37.417 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-3:0.2.8(50)
> 2019-09-18 19:56:37.421 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:1.0.0(190918195637S)
> 2019-09-18 19:56:37.427 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.1.1(4530303436303034303730383631383137)
> 2019-09-18 19:56:37.431 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.1(001498.382*kWh)
> 2019-09-18 19:56:37.437 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.2(001260.612*kWh)
> 2019-09-18 19:56:37.441 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.1(000000.000*kWh)
> 2019-09-18 19:56:37.446 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.2(0000
> 2019-09-18 19:56:40.268 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1!2)
> 2019-09-18 19:56:40.271 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.7.0(00.210*kW)
> 2019-09-18 19:56:40.273 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.7.0(00.000*kW)
> 2019-09-18 19:56:42.280 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:56:42.282 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:*s)
> 2019-09-18 19:56:42.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.32.0(00007)
> 2019-09-18 19:56:42.287 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.32.0(00007)
> 2019-09-18 19:56:42.289 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.3(200)
> 2019-09-18 19:56:42.291 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.36.0(00000)
> 2019-09-18 19:56:42.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.36.0(00000)
> 2019-09-18 19:56:42.295 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.13.0()
> 2019-09-18 19:56:42.297 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.7.0(236.0*V)
> 2019-09-18 19:56:46.272 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:56:46.274 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:*kW)
> 2019-09-18 19:56:46.277 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:22.7.0(00.000*kW)
> 2019-09-18 19:56:46.279 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:42.7.0(00.000*kW)
> 2019-09-18 19:56:46.282 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:62.7.0(00.000*kW)
> 2019-09-18 19:56:46.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.1.0(003)
> 2019-09-18 19:56:46.290 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:01(1303330
> 2019-09-18 19:56:46.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1918195502S)(00557.274*m3)
> 2019-09-18 19:56:46.414 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:!D04A
> 2019-09-18 19:56:49.415 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:/XMX5LGBBLA4415440609
> 2019-09-18 19:56:49.420 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-3:0.2.8(50061
> 2019-09-18 19:56:49.425 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.1.1(4530303436303034303730383631383137)
> 2019-09-18 19:56:49.431 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.1(001498.(1(14*kWh)
> 2019-09-18 19:56:49.435 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.1(000000.000*kWh)
> 2019-09-18 19:56:49.441 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.2(000000.000*kWh)
> 2019-09-18 19:56:50.118 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.14.0(0002)(1!(0002)
> 2019-09-18 19:56:50.121 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.7.0(00.207*kW)
> 2019-09-18 19:56:50.123 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.7.0(00.000*kW)
> 2019-09-18 19:56:50.126 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.7.21(00020)
> 2019-09-18 19:56:50.128 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.7.9(00004)
> 2019-09-18 19:56:53.243 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:56:53.246 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0(00006)
> 2019-09-18 19:56:53.250 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.36.0(00000)
> 2019-09-18 19:56:53.254 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.36.0(00000)
> 2019-09-18 19:56:53.258 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.36.0(00000)
> 2019-09-18 19:56:53.261 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.13.0()
> 2019-09-18 19:56:55.814 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.7.00
> 2019-09-18 19:56:55.818 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:31.7.0(000*A)
> 2019-09-18 19:56:55.822 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:51.7.0(000*A)
> 2019-09-18 19:56:55.826 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:71.7.0(000*A)
> 2019-09-18 19:56:55.830 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:21.7.0(00.065*kW)
> 2019-09-18 19:56:55.834 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:41.7.0(00.140*kW)
> 2019-09-18 19:56:55.838 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:61.7.0(00.000*kW)
> 2019-09-18 19:56:55.843 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:22.7.0(00.000*kW)
> 2019-09-18 19:56:57.288 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:42.7.0(00.000*k01.0(4730303339303031383033353639373138)
> 2019-09-18 19:56:57.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.2.1(190918195502S)(00557.274*m3)
> 2019-09-18 19:56:57.413 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:!AFF5
> 2019-09-18 19:56:57.875 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:/XMX5LGBBLA4415440609
> 2019-09-18 19:56:57.879 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-3:0.2.8(50)
> 2019-09-18 19:56:57.884 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:1.0.0(190918195658S)
> 2019-09-18 19:56:57.890 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.1.1(4530303436303034303730383631383137)
> 2019-09-18 19:56:57.895 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.1(001498.382*kWh)
> 2019-09-18 19:56:57.900 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.2(001260.614*kWh)
> 2019-09-18 19:56:57.905 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.1(000000.000*kWh)
> 2019-09-18 19:56:57.909 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.2(000000.000*kWh)
> 2019-09-18 19:56:57.913 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.14.0(0002)
> 2019-09-18 19:56:57.918 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.7.0(00.207*kW)
> 2019-09-18 19:57:01.271 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.7.0(00.01:24.1.0(003)
> 2019-09-18 19:57:01.275 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:96.1.0(4730303339303031383033353639373138)
> 2019-09-18 19:57:01.277 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.2.1(190918195502S)(00557.274*m3)
> 2019-09-18 19:57:06.254 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:1.0.0(190918195704S)
> 2019-09-18 19:57:06.257 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.1.1(4530303436303034303730383631383137)
> 2019-09-18 19:57:06.259 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.1(001498.382*kWh)
> 2019-09-18 19:57:06.262 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.2(001260.614*kWh)
> 2019-09-18 19:57:06.265 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.1(000000.000*kWh)
> 2019-09-18 19:57:06.267 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.2(000000.000*kWh)
> 2019-09-18 19:57:06.271 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.14.0(0002)
> 2019-09-18 19:57:06.273 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.7.0(00.207*kW)
> 2019-09-18 19:57:06.276 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.71100020)
> 2019-09-18 19:57:06.281 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.7.9(00004)
> 2019-09-18 19:57:07.273 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:57:07.277 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:2.32.0(00007)
> 2019-09-18 19:57:07.280 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.32.0(00006)
> 2019-09-18 19:57:07.282 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.36.0(00000)
> 2019-09-18 19:57:07.285 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.36.0(00000)
> 2019-09-18 19:57:07.288 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.36.0(00000)
> 2019-09-18 19:57:07.291 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.13.0()
> 2019-09-18 19:57:07.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:32.7.0(235.0*V)
> 2019-09-18 19:57:07.296 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.7.0(236.0*V)
> 2019-09-18 19:57:07.298 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.7.0(235.0*V)
> 2019-09-18 19:57:08.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:101-0:51.7.0(000*A)
> 2019-09-18 19:57:08.291 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:71.7.0(000*A)
> 2019-09-18 19:57:08.300 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:21.7.0(00.064*kW)
> 2019-09-18 19:57:08.308 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:41.7.0(00.140*kW)
> 2019-09-18 19:57:08.313 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:61.7.0(00.000*kW)
> 2019-09-18 19:57:08.318 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:22.7.0(00.000*kW)
> 2019-09-18 19:57:08.324 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:42.7.0(00.000*kW)
> 2019-09-18 19:57:08.329 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:62.7.0(00.000*kW)
> 2019-09-18 19:57:08.334 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.1.0(003)
> 2019-09-18 19:57:08.341 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:96.1.0(4730303339303031383033353639373138)
> 2019-09-18 19:57:08.348 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.2.1(190918195502S)(00557.274*m3)
> 2019-09-18 19:57:10.415 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:609
> 2019-09-18 19:57:10.417 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-3:0.2.8(50)
> 2019-09-18 19:57:10.420 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:1.0.0(190918195710S)
> 2019-09-18 19:57:10.423 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.1.1(4530303436303034303730383631383137)
> 2019-09-18 19:57:10.426 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.1(001498.382*kWh)
> 2019-09-18 19:57:10.428 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.2(001260.615*kWh)
> 2019-09-18 19:57:14.283 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:57:17.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:57:17.292 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:10(1-0:32.36.0(00000)
> 2019-09-18 19:57:17.297 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.36.0(00000)
> 2019-09-18 19:57:17.303 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:72.36.0(00000)
> 2019-09-18 19:57:17.307 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.13.0()
> 2019-09-18 19:57:18.068 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-001((23620-0:96.14.0(0002)
> 2019-09-18 19:57:18.073 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:52.32.0(00007)
> 2019-09-18 19:57:20.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:71.7.0(000*A)
> 2019-09-18 19:57:20.289 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:21.7.0(00.064*kW)
> 2019-09-18 19:57:20.298 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TELEGESIS RX buffer overrun - resetting!
> 2019-09-18 19:57:20.305 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1.0(4730303339303031383033353639373138)
> 2019-09-18 19:57:20.310 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-1:24.2.1(190918195502S)(00557.274*m3)
> 2019-09-18 19:57:21.417 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:!F4F3
> 2019-09-18 19:57:22.934 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-3:0.2.8(50)
> 2019-09-18 19:57:22.940 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:1.0.0(190918195723S)
> 2019-09-18 19:57:25.422 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0:96.1.1(45(.382*kWh)
> 2019-09-18 19:57:25.425 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:1.8.2(001260.615*kWh)
> 2019-09-18 19:57:25.428 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.1(000000.000*kWh)
> 2019-09-18 19:57:25.432 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.8.2(000000.000*kWh)
> 2019-09-18 19:57:25.434 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:0-0
> 2019-09-18 19:57:26.239 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:7!1-0:1.7.0(00.209*kW)
> 2019-09-18 19:57:26.245 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:1-0:2.7.0(00.000*kW)

Stehof · September 18, 2019, 6:02pm

And the 3th time, the second seemed different…:

> 2019-09-18 20:00:24.717 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=143, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryService, component.id=41} - org.openhab.binding.zigbee
> 2019-09-18 20:00:24.725 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTING - org.openhab.binding.zigbee
> 2019-09-18 20:00:24.730 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTED - org.openhab.binding.zigbee
> 2019-09-18 20:00:24.739 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBeeDiscoveryService
> 2019-09-18 20:00:24.778 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory}={service.id=144, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeChannelConverterFactory, component.id=42} - org.openhab.binding.zigbee
> 2019-09-18 20:00:24.889 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=145, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=43} - org.openhab.binding.zigbee
> 2019-09-18 20:00:24.926 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.discovery.ZigBeeDiscoveryParticipant}={service.id=146, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.discovery.internal.ZigBeeDefaultDiscoveryParticipant, component.id=44} - org.openhab.binding.zigbee
> 2019-09-18 20:00:25.017 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.converter.ZigBeeChannelConverterProvider}={service.id=147, service.bundleid=261, service.scope=bundle, component.name=org.openhab.binding.zigbee.internal.converter.ZigBeeDefaultChannelConverterProvider, component.id=45} - org.openhab.binding.zigbee
> 2019-09-18 20:00:25.022 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee
> 2019-09-18 20:00:25.040 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=148, service.bundleid=262, service.scope=bundle, component.name=org.openhab.binding.zigbee.cc2531.internal.CC2531HandlerFactory, component.id=46} - org.openhab.binding.zigbee.cc2531
> 2019-09-18 20:00:25.058 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTING - org.openhab.binding.zigbee.cc2531
> 2019-09-18 20:00:25.064 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent STARTED - org.openhab.binding.zigbee.cc2531
> 2019-09-18 20:00:25.086 [DEBUG] [org.openhab.binding.zigbee.cc2531   ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.cc2531
> 2019-09-18 20:00:25.110 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=149, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.discovery.ZigBeeEmberUsbSerialDiscoveryParticipant, component.id=47} - org.openhab.binding.zigbee.ember
> 2019-09-18 20:00:25.128 [DEBUG] [org.openhab.binding.zigbee.ember    ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=150, service.bundleid=263, service.scope=bundle, component.name=org.openhab.binding.zigbee.ember.internal.EmberHandlerFactory, component.id=48} - org.openhab.binding.zigbee.ember
> 2019-09-18 20:00:25.148 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTING - org.openhab.binding.zigbee.ember
> 2019-09-18 20:00:25.153 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent STARTED - org.openhab.binding.zigbee.ember
> 2019-09-18 20:00:25.163 [DEBUG] [org.openhab.binding.zigbee.ember    ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.ember
> 2019-09-18 20:00:25.180 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=151, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.TelegesisHandlerFactory, component.id=49} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 20:00:25.185 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTING - org.openhab.binding.zigbee.telegesis
> 2019-09-18 20:00:25.188 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent STARTED - org.openhab.binding.zigbee.telegesis
> 2019-09-18 20:00:25.200 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.usbserial.UsbSerialDiscoveryParticipant}={service.id=152, service.bundleid=264, service.scope=bundle, component.name=org.openhab.binding.zigbee.telegesis.internal.discovery.ZigBeeTelegesisUsbSerialDiscoveryParticipant, component.id=50} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 20:00:25.203 [DEBUG] [org.openhab.binding.zigbee.telegesis] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.telegesis
> 2019-09-18 20:00:25.213 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=153, service.bundleid=265, service.scope=bundle, component.name=org.openhab.binding.zigbee.xbee.internal.XBeeHandlerFactory, component.id=51} - org.openhab.binding.zigbee.xbee
> 2019-09-18 20:00:25.219 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTING - org.openhab.binding.zigbee.xbee
> 2019-09-18 20:00:25.223 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent STARTED - org.openhab.binding.zigbee.xbee
> 2019-09-18 20:00:25.243 [DEBUG] [org.openhab.binding.zigbee.xbee     ] - BundleEvent [unknown:512] - org.openhab.binding.zigbee.xbee
> 2019-09-18 20:00:56.728 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler}={service.id=340, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 20:00:56.757 [DEBUG] [org.openhab.binding.zigbee.telegesis] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=341, service.bundleid=264, service.scope=singleton} - org.openhab.binding.zigbee.telegesis
> 2019-09-18 20:00:56.780 [DEBUG] [e.telegesis.handler.TelegesisHandler] - Initializing ZigBee Telegesis serial bridge handler.
> 2019-09-18 20:00:56.782 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_telegesis:04000BB6].
> 2019-09-18 20:00:56.786 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
> 2019-09-18 20:00:56.788 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 644
> 2019-09-18 20:00:56.790 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 475D989ED84F5871
> 2019-09-18 20:00:56.806 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key 1665267ADE06D844B3FBE7F8EE3D744A
> 2019-09-18 20:00:56.809 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key 5A6967426565416C6C69616E63653039
> 2019-09-18 20:00:56.811 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_initialise found, initializeNetwork=false
> 2019-09-18 20:00:56.813 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network Key String 1665267ADE06D844B3FBE7F8EE3D744A
> 2019-09-18 20:00:56.816 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Network key final array 1665267ADE06D844B3FBE7F8EE3D744A
> 2019-09-18 20:00:56.818 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link Key String 5A6967426565416C6C69616E63653039
> 2019-09-18 20:00:56.821 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Link key final array 5A6967426565416C6C69616E63653039
> 2019-09-18 20:00:56.887 [DEBUG] [e.telegesis.handler.TelegesisHandler] - ZigBee Telegesis Coordinator opening Port:'/dev/ttyUSB1' PAN:284, EPAN:475D989ED84F5871, Channel:11
> 2019-09-18 20:00:56.920 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
> 2019-09-18 20:00:57.955 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
> 2019-09-18 20:00:57.957 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
> 2019-09-18 20:00:58.052 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 1280
> 2019-09-18 20:00:58.060 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher starting
> 2019-09-18 20:00:58.421 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster IAS_ZONE
> 2019-09-18 20:00:58.426 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Adding supported cluster 25
> 2019-09-18 20:00:58.429 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - ClusterMatcher adding cluster OTA_UPGRADE
> 2019-09-18 20:00:58.441 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Start.
> 2019-09-18 20:00:58.839 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00111700D0: Node 0 added to the network
> 2019-09-18 20:00:58.962 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00036B628A: Node 11911 added to the network
> 2019-09-18 20:00:58.968 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D00036CD4CC: Node 12123 added to the network
> 2019-09-18 20:00:58.970 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Loading ZigBee network state: Done.
> 2019-09-18 20:00:58.978 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle initialize.
> 2019-09-18 20:00:58.978 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to UNINITIALISED
> 2019-09-18 20:00:58.980 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB1] at 19200 baud, flow control FLOWCONTROL_OUT_NONE.
> 2019-09-18 20:00:58.984 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=UNINITIALISED
> 2019-09-18 20:00:59.089 [DEBUG] [ding.zigbee.handler.ZigBeeSerialPort] - Serial port [/dev/ttyUSB1] is initialized.
> 2019-09-18 20:00:59.103 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TelegesisFrameHandler thread started
> 2019-09-18 20:00:59.123 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:00:59.125 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
> 2019-09-18 20:00:59.129 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1
> 2019-09-18 20:00:59.244 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:00:59.270 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null, status=SUCCESS]
> 2019-09-18 20:00:59.278 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:00:59.281 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
> 2019-09-18 20:00:59.283 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E1=0
> 2019-09-18 20:00:59.505 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:00:59.509 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null, status=SUCCESS]
> 2019-09-18 20:00:59.517 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:00:59.519 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
> 2019-09-18 20:00:59.522 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E0=0
> 2019-09-18 20:00:59.634 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:00:59.636 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null, status=SUCCESS]
> 2019-09-18 20:00:59.641 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:00:59.643 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSoftwareResetCommand []
> 2019-09-18 20:00:59.646 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATZ
> 2019-09-18 20:01:00.024 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:
> 2019-09-18 20:01:00.027 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.029 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSoftwareResetCommand [status=SUCCESS]
> 2019-09-18 20:01:00.032 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.034 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null]
> 2019-09-18 20:01:00.037 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS124=1
> 2019-09-18 20:01:00.148 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.150 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=18, bit=4, state=true, password=null, status=SUCCESS]
> 2019-09-18 20:01:00.153 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.156 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null]
> 2019-09-18 20:01:00.158 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E1=0
> 2019-09-18 20:01:00.275 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.277 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=1, state=false, password=null, status=SUCCESS]
> 2019-09-18 20:01:00.280 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.281 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null]
> 2019-09-18 20:01:00.283 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E0=0
> 2019-09-18 20:01:00.416 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.418 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=14, bit=0, state=false, password=null, status=SUCCESS]
> 2019-09-18 20:01:00.422 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.424 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804]
> 2019-09-18 20:01:00.426 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0E=8804
> 2019-09-18 20:01:00.543 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.545 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetPromptEnable1Command [configuration=8804, status=SUCCESS]
> 2019-09-18 20:01:00.554 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.556 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password]
> 2019-09-18 20:01:00.559 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A=015E:password
> 2019-09-18 20:01:00.676 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.678 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetMainFunctionCommand [configuration=350, password=password, status=SUCCESS]
> 2019-09-18 20:01:00.684 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.686 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9]
> 2019-09-18 20:01:00.689 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS10=56A9
> 2019-09-18 20:01:00.801 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.803 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetExtendedFunctionCommand [configuration=56A9, status=SUCCESS]
> 2019-09-18 20:01:00.810 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.814 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetPromptEnable2Command [configuration=6B3C]
> 2019-09-18 20:01:00.821 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0F=6B3C
> 2019-09-18 20:01:00.930 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:00.932 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetPromptEnable2Command [configuration=6B3C, status=SUCCESS]
> 2019-09-18 20:01:00.937 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:00.945 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayProductIdentificationCommand [deviceName=null, firmwareRevision=null, ieeeAddress=null]
> 2019-09-18 20:01:00.951 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATI
> 2019-09-18 20:01:01.088 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:Telegesis ETRX357-LRS
> 2019-09-18 20:01:01.092 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:R309C
> 2019-09-18 20:01:01.095 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:000D6F00111700D0
> 2019-09-18 20:01:01.097 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:01.099 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayProductIdentificationCommand [deviceName=ETRX357-LRS, firmwareRevision=309C, ieeeAddress=000D6F00111700D0, status=SUCCESS]
> 2019-09-18 20:01:01.115 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee Initialise: Previous device configuration was: channel=null, PanID=0, EPanId=null
> 2019-09-18 20:01:01.117 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to INITIALISING
> 2019-09-18 20:01:01.119 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=INITIALISING
> 2019-09-18 20:01:01.118 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Config: zigbee_trustcentremode=TC_JOIN_INSECURE
> 2019-09-18 20:01:01.253 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:01.255 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetOutputClustersCommand [clusterList=[1280]]
> 2019-09-18 20:01:01.258 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS4C=0500
> 2019-09-18 20:01:01.371 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:01.373 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetOutputClustersCommand [clusterList=[1280], status=SUCCESS]
> 2019-09-18 20:01:01.376 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Setting Telegesis trust centre link mode: TC_JOIN_INSECURE
> 2019-09-18 20:01:01.380 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:01.382 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisallowTcJoinCommand [disallowJoin=false, password=password]
> 2019-09-18 20:01:01.385 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A5=0:password
> 2019-09-18 20:01:01.501 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:01.502 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisallowTcJoinCommand [disallowJoin=false, password=password, status=SUCCESS]
> 2019-09-18 20:01:01.506 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:01.508 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisallowUnsecuredRejoinCommand [disallowRejoin=false, password=password]
> 2019-09-18 20:01:01.512 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS0A3=0:password
> 2019-09-18 20:01:01.628 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:01.630 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisallowUnsecuredRejoinCommand [disallowRejoin=false, password=password, status=SUCCESS]
> 2019-09-18 20:01:01.632 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis dongle startup.
> 2019-09-18 20:01:01.634 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:01.636 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetRegisterBitCommand [register=17, bit=14, state=true, password=null]
> 2019-09-18 20:01:01.638 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS11E=1
> 2019-09-18 20:01:01.750 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:01.751 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetRegisterBitCommand [register=17, bit=14, state=true, password=null, status=SUCCESS]
> 2019-09-18 20:01:01.755 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:01.757 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSetOutputPowerCommand [powerLevel=-7]
> 2019-09-18 20:01:01.760 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:ATS01=-7
> 2019-09-18 20:01:01.871 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:01.873 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSetOutputPowerCommand [powerLevel=-7, status=SUCCESS]
> 2019-09-18 20:01:01.878 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:01.880 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:01.882 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:01.998 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:02.002 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:02.004 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:02.012 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Network state is updated to OFFLINE
> 2019-09-18 20:01:02.015 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - null: networkStateUpdated called with state=OFFLINE
> 2019-09-18 20:01:03.016 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:03.018 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:03.029 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:03.147 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:03.148 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:03.150 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:04.448 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:04.450 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:04.454 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:04.570 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:04.573 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:04.574 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:05.576 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:05.596 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:05.610 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:05.730 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:05.732 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:05.733 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:06.735 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:06.739 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:06.743 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:06.860 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:06.862 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:06.864 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:07.868 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:07.870 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:07.872 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:07.989 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:07.991 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:07.992 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:08.994 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:08.995 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:08.997 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:09.114 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:09.116 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:09.117 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:09.541 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTING - com.zsmartsystems.zigbee
> 2019-09-18 20:01:09.568 [DEBUG] [com.zsmartsystems.zigbee            ] - BundleEvent STARTED - com.zsmartsystems.zigbee
> 2019-09-18 20:01:09.584 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.cc2531
> 2019-09-18 20:01:09.594 [DEBUG] [m.zsmartsystems.zigbee.dongle.cc2531] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.cc2531
> 2019-09-18 20:01:09.604 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.ember
> 2019-09-18 20:01:09.613 [DEBUG] [om.zsmartsystems.zigbee.dongle.ember] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.ember
> 2019-09-18 20:01:09.626 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.telegesis
> 2019-09-18 20:01:09.631 [DEBUG] [smartsystems.zigbee.dongle.telegesis] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.telegesis
> 2019-09-18 20:01:09.637 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTING - com.zsmartsystems.zigbee.dongle.xbee
> 2019-09-18 20:01:09.642 [DEBUG] [com.zsmartsystems.zigbee.dongle.xbee] - BundleEvent STARTED - com.zsmartsystems.zigbee.dongle.xbee
> 2019-09-18 20:01:10.119 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:10.120 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:10.122 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:10.238 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:10.239 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:10.241 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:11.242 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:11.245 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:11.248 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:11.366 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:11.368 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK
> 2019-09-18 20:01:11.371 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=NOPAN, channel=null, power=null, panId=null, epanId=null, status=SUCCESS]
> 2019-09-18 20:01:12.373 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1
> 2019-09-18 20:01:12.374 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]
> 2019-09-18 20:01:12.376 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?
> 2019-09-18 20:01:12.495 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=NoPAN
> 2019-09-18 20:01:12.498 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

Stehof · September 24, 2019, 12:55pm

And… any clue what is going wrong?
When I’m reading the serial port with “cat /dev/ttyTeleUSB” then the stick seem to work just fine, when I push a pair button on a device it gets recognised by the stick itself but openhaal won’t do anything with it…

chris · September 24, 2019, 1:31pm

No really - all three of these are different, so it makes me think that it’s something like timing, or something variable within your system.

What do you mean exactly by this?

Stehof · September 24, 2019, 2:11pm

Well, if you read the communication of the stick with command “cat /dev/ttyTeleUSB” and then when I push the pair button of the motion sensor for example, you’ll see on the console that the stick tries to connect with the motion sensor. So the stick seems to work but something in the binding seems to have a problem.

Would it be an idea to completely start over again with the stick?
So that I will erase everything that is in the system including the binding and the start all over again?

chris · September 24, 2019, 2:12pm

Please provide a log of this - I don’t know what data is being sent, so I really can’t comment. The binding may not have a problem at all - it might just be that the data is not of any use? Do you know what the data is?

Stehof · September 24, 2019, 2:21pm

Here is some of the data:

+N=COO,11,-07,F63B,222F13E83B125A2C

OK

+N=COO,11,-07,F63B,222F13E83B125A2C

OK

+N=COO,11,-07,F63B,222F13E83B125A2C

OK

NEWNODE:BECB,00158D000359CB36,0000

RX:BECB,0000,00,00,0013,0C:?˾6?Y??,-17,FF

RX:BECB,0104,01,01,0000,19:
Blumi.sensor_magnet,-18,FF

RX:BECB,0104,01,01,0000,07:
 
,-18,FF

RX:BECB,0104,01,01,0000,1D:4
?L!?
    !?$!4 \,-18,FF

I don’t know if you mean this data?
Here you’ll that I pushed the pair button of a door sensor
RX:BECB,0104,01,01,0000,19:
Blumi.sensor_magnet,-18,FF

chris · September 24, 2019, 2:27pm

Yes

The binding expects certain traffic - there is a protocol specification that should be followed. When a device joins, the binding gets notified and will then interrogate the device to find out what it is. If the device sends “random” messages, then that may not help if the binding is not requesting this data.

The binding works to the ZigBee protocol. I’m not sure what this device is, but there are some devices that do this sort of thing (eg Xiaomi devices) that are not ZigBee compliant. They work differently based on their own requirements and own system spec with their own hub. The binding will not necessarily process this sort of data.

I’m not of course 100% sure without trying to decode what this data is though, so this is a general explanation of what I think might be happening.

Stehof · September 24, 2019, 3:02pm

Ah ok, is there a list somewhere of manufacturers with devices will work with the current Zigbee binding?

I managed to get the Telegesis stick online again, the only thing is when I reboot the system, the Telegesis properties of the port of the stick changes by itself. When I open the properties after a reboot it get set to /dev/ttyUSB0 although sometimes the DSMR is on that port, that’s why I created alias /dev/ttyTeleUSB for it, when I change the property of the port to /dev/ttyTeleUSB, it is online again.

The thing I did was deleting the stick, reboot the system, created the file /etc/default/openhab2 again with the java option for the different serial ports, and after that I rebooted again and added the stick again, now it is working.
I noticed before that the file /etc/default/openhab2 kept restoring to a older version were I did not had the Java options set, that problem is now solved.

The only problem I’m still experiencing is that the Xiaomi devices won’t work with the Telegesis stick, sometimes when I add the motion sensor for example, it works for 1,5 minutes and then stops working, it is still online then but it don’t detect motion anymore…

chris · September 24, 2019, 3:12pm

Not really - most ZigBee compliant devices should work.

You mean the port changes? Does the alias not resolve this (with udev rules?).

These devices are problematic unfortunately. They normally work, although getting them paired can be difficult as they don’t tend to follow the ZigBee requirements. I have heard they can drop off the network, but I can’t really comment too much on them. I know some people have no problems, but others do…

Stehof · September 24, 2019, 3:34pm

No because when the devices isn’t specified in /etc/default/openhab2, it won’t be shown at all in openhaal so the system isn’t able to select it.

So lucky me, I’m one of those who has problems with the devices, now they are all offline, whatever I do, they won’t come up.

Sometimes when I delete them and add them again they are online, but they stop working after about 1,5 minutes, they keep online then but also in the logviewer, you won’t see anything happen with the device.

But is there a list of tested devices then?

chris · September 24, 2019, 3:58pm

You also need to add this alias port to the EXTRA_JAVA_OPTS parameter on the command line when starting OH.

eg

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

There is a list in the binding documentation, but I would not say that it is complete since it relies on people updating the list, and most people don’t do this.