Zigbee binding

There’s nothing obviously wrong that I can see from the log - it’s just not joining. Have you completely reset the bulb?

My ST motion sensors won’t join although they are in join mode. When I hold reset while connecting the battery, they sort of join, but only show the temperature channel.

Properties:
zigbee_logicaltype END_DEVICE
zigbee_powerlevel FULL
modelId motionv4
zigbee_networkaddress 5241
zigbee_powersource MAINS
zigbee_zclversion 1
zigbee_routes []
zigbee_lastupdate 2018-01-23T12:58:01Z
vendor SmartThings
zigbee_powermode RECEIVER_ON_IDLE
zigbee_permitjoining false
zigbee_powersources [MAINS]
zigbee_neighbors []
zigbee_devices []

Has the Hue bulb been used before? If it has, you might need to reset it for which you need the Hue dimmer remote.

It can’t “sort of join” - it looks like it’s joined ok…

There’s not much I can say without seeing a log. I can guess that maybe some request failed somewhere and it didn’t detect all the attributes it wanted to provide the other channel. At the moment these sort of requests aren’t retried so it isn’t as tolerant to errors as it should be (I’ll look at this in future).

If you restart, it should again detect the features and should work. Otherwise, I’ll need to see a log - maybe your device is different than the one I used for testing.

I tried restarting already, no new channels. I have a log, but it was too big for the forum. I’ll open an issue on github. I have this sensor: https://www.bestbuy.com/site/samsung-smartthings-motion-sensor-white/4481806.p?skuId=4481806

Ok, thanks. I’ll take a look once you post it…

Nope. Brand new out the box. Just turn them on during discovery mode correct?

Did you buy a single bulb or a pack with a hue hub?

Are there any other ZigBee networks around?

You will likely need to be able to reset it either way.

I am having issues using https://www.amazon.com/SYLVANIA-Equivalent-73857-Formerly-LIGHTIFY/dp/B0196M5YAS?th=1 . I am not sure if I have somethign wrong with my ZigBee Controller since this is my first time using the Zigbee binding or if it is something to do with the bulb. I currently have no other devices to test with as the rest of my house is all using Z-Wave and Smart Switches.

  • Platform

  • Issue

    • Expected Behavior: Trying to pair SYLVANIA SMART+ ZigBee Full Color BR30 LED Bulb to CC2531 Dongle
    • Actual Behavior: No devices detected when in pairing mode. Z-WAve binding works fine using same USB Passthrough method on ESXi with the legacy drivers.
    • Steps: (Steps to reproduce the issue)
      1. Flash CC2531 and install ZigBee Binding via services/addons.cfg
      2. Create thing for controller, zigbee:coordinator_cc2531:Zigbee successfully goes ONLINE
      3. Using Paper UI or Habmin (tried both) start discovery for new item.
      4. No items found
  • What I have done so far

    • [A] I have tried falshing different firmware versions from the link above with no success.
    • [B] I have tried installing the newest 3.0 CC2531 firmware and the binding will not even talk to it.
    • [C] I have reset the bulb following the manufacturer instrucitons
    • [D] Multiple reboots and restarts of binding
  • Logs
    Log when enabling pairing mode

      2018-01-23 13:41:01.096 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
      2018-01-23 13:41:01.097 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=82, permitDuration=60, tcSignificance=true]
      2018-01-23 13:41:01.097 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=130, payload=00 3C 01]
      2018-01-23 13:41:01.097 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 36 00 82 30 1F 03 00 3C 01 8D, checksum=8D, error=false) 
      2018-01-23 13:41:01.216 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
      2018-01-23 13:41:01.217 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B6 00 00 00 F0)
      2018-01-23 13:41:01.217 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B6, data=FE 03 45 B6 00 00 00 F0, checksum=F0, error=false
      2018-01-23 13:41:01.217 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
      2018-01-23 13:41:01.217 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06)
      2018-01-23 13:41:01.218 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06, checksum=06, error=false
      2018-01-23 13:41:01.218 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, sequence=0, payload=00 00]
      2018-01-23 13:41:01.218 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]
    

    Log from a random mesh update

      2018-01-23 13:39:11.826 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
      2018-01-23 13:39:11.827 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=7B, startIndex=0]
      2018-01-23 13:39:11.828 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, sequence=123, payload=00 00]
      2018-01-23 13:39:11.828 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 31 00 7B 30 1F 02 00 00 4E, checksum=4E, error=false) 
      2018-01-23 13:39:12.061 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
      2018-01-23 13:39:12.062 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 06 45 B1 00 00 00 00 00 00 F2)
      2018-01-23 13:39:12.063 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=45 B1, data=FE 06 45 B1 00 00 00 00 00 00 F2, checksum=F2, error=false
      2018-01-23 13:39:12.063 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
      2018-01-23 13:39:12.063 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=0, startIndex=0, neighborTableListCount=0, neighborTableList=[]]
      2018-01-23 13:39:12.064 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 FF 00 00 00 31 80 00 00 00 00 00 00 00 00 06)
      2018-01-23 13:39:12.065 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 FF, data=FE 0D 45 FF 00 00 00 31 80 00 00 00 00 00 00 00 00 06, checksum=06, error=false
      2018-01-23 13:39:12.065 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=7C, nwkAddrOfInterest=0, requestType=1, startIndex=0]
      2018-01-23 13:39:12.065 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
      2018-01-23 13:39:12.065 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=124, payload=00 00 00 01 00]
      2018-01-23 13:39:12.066 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=0, startIndex=0, neighborTableListCount=0, neighborTableList=[]]
      2018-01-23 13:39:12.066 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 7C 30 1F 05 00 00 00 01 00 7C, checksum=7C, error=false) 
      2018-01-23 13:39:12.186 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
      2018-01-23 13:39:12.187 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 81 00 28 6B 89 0E 00 4B 12 00 00 00 12 00 46)
      2018-01-23 13:39:12.187 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 81, data=FE 0D 45 81 00 28 6B 89 0E 00 4B 12 00 00 00 12 00 46, checksum=46, error=false
      2018-01-23 13:39:12.187 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 28 6B 89 0E 00 4B 12 00 00 00 00 12]
      2018-01-23 13:39:12.188 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E896B28, nwkAddrRemoteDev=0, numAssocDev=0, startIndex=null, nwkAddrAssocDevList=[]]
      2018-01-23 13:39:12.188 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 15 45 FF 00 00 00 01 80 00 00 00 00 00 28 6B 89 0E 00 4B 12 00 00 00 00 B3)
      2018-01-23 13:39:12.188 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=21, apiId=45 FF, data=FE 15 45 FF 00 00 00 01 80 00 00 00 00 00 28 6B 89 0E 00 4B 12 00 00 00 00 B3, checksum=B3, error=false
      2018-01-23 13:39:12.188 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=7D, startIndex=0]
      2018-01-23 13:39:12.189 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 28 6B 89 0E 00 4B 12 00 00 00 00]
      2018-01-23 13:39:12.189 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E896B28, nwkAddrRemoteDev=0, numAssocDev=0, startIndex=null, nwkAddrAssocDevList=[]]
      2018-01-23 13:39:12.189 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, sequence=125, payload=00 00]
      2018-01-23 13:39:12.198 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 32 00 7D 30 1F 02 00 00 4B, checksum=4B, error=false) 
      2018-01-23 13:39:12.314 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
      2018-01-23 13:39:12.315 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 06 45 B2 00 00 00 00 00 00 F1)
      2018-01-23 13:39:12.316 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=45 B2, data=FE 06 45 B2 00 00 00 00 00 00 F1, checksum=F1, error=false
      2018-01-23 13:39:12.317 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
      2018-01-23 13:39:12.317 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableListCount=0, routingTableList=[]]
      2018-01-23 13:39:12.317 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05)
      2018-01-23 13:39:12.318 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 FF, data=FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05, checksum=05, error=false
      2018-01-23 13:39:12.318 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Ending mesh update
      2018-01-23 13:39:12.318 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
      2018-01-23 13:39:12.319 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableListCount=0, routingTableList=[]]
    

I’m afraid there’s not much I can say - the coordinator is not finding any devices, so there’s nothing in the logs to comment on.

One comment though is if you are using the 2.2 release version of the binding, then please consider using the 2.3 snapshot instead. There was an issue fixed at some point in the past relating to joining with the TI dongle - I think it was already in 2.2, but in any case there has been a lot of improvements so you should use the most recent version.

What is the process for installing the snapshot binding?

Nevermind, I was able to figure out how to install, but still no luck finding a device. There did seem to be more data in the log with the new binding

2018-01-23 14:23:07.129 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent INSTALLED - org.openhab.binding.zigbee
2018-01-23 14:23:28.555 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent RESOLVED - org.openhab.binding.zigbee
2018-01-23 14:23:28.558 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.zigbee.internal.ZigBeeHandlerFactory, component.id=242, service.id=406, service.bundleid=239, service.scope=bundle} - org.openhab.binding.zigbee
2018-01-23 14:23:28.571 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTING - org.openhab.binding.zigbee
2018-01-23 14:23:28.574 [DEBUG] [ding.zigbee.internal.ZigBeeActivator] - ZigBee binding started. Version 2.3.0.201801231534
2018-01-23 14:23:28.579 [DEBUG] [zigbee.internal.ZigBeeHandlerFactory] - Creating coordinator handler for org.eclipse.smarthome.core.thing.internal.BridgeImpl@f500a0e1
2018-01-23 14:23:28.583 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Creating ZigBee discovery service for zigbee:coordinator_cc2531:Zigbee
2018-01-23 14:23:28.583 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Activating ZigBee discovery service for zigbee:coordinator_cc2531:Zigbee
2018-01-23 14:23:28.584 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=407, service.bundleid=239, service.scope=singleton} - org.openhab.binding.zigbee
2018-01-23 14:23:28.588 [DEBUG] [org.openhab.binding.zigbee          ] - BundleEvent STARTED - org.openhab.binding.zigbee
2018-01-23 14:23:30.335 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:Zigbee' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2018-01-23 14:23:30.336 [DEBUG] [ndler.ZigBeeCoordinatorCC2531Handler] - Initializing ZigBee ZNP serial bridge handler.
2018-01-23 14:23:30.337 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_cc2531:Zigbee].
2018-01-23 14:23:30.337 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2018-01-23 14:23:30.337 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID -21837
2018-01-23 14:23:30.337 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 00124B000E896B28
2018-01-23 14:23:30.338 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-01-23 14:23:30.338 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key String 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-01-23 14:23:30.339 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialised BFFD3EC0DED163B8E1195D0225A9CD36
2018-01-23 14:23:30.339 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key final array BFFD3EC0DED163B8E1195D0225A9CD36
2018-01-23 14:23:30.345 [DEBUG] [ndler.ZigBeeCoordinatorCC2531Handler] - ZigBee Coordinator ZNP opening Port:'/dev/ttyACM1' PAN:ffffaab3, Channel:11
2018-01-23 14:23:30.347 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:Zigbee' changed from INITIALIZING to UNKNOWN
2018-01-23 14:23:30.348 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2018-01-23 14:23:31.349 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-01-23 14:23:31.349 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-01-23 14:23:31.354 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise BFFD3EC0DED163B8E1195D0225A9CD36
2018-01-23 14:23:31.355 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - CC2531 transport initialize
2018-01-23 14:23:31.356 [DEBUG] [ing.zigbee.internal.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyACM1] at 115200 baud, flow control FLOWCONTROL_OUT_NONE.
2018-01-23 14:23:31.369 [DEBUG] [ing.zigbee.internal.ZigBeeSerialPort] - Serial port [/dev/ttyACM1] is initialized.
2018-01-23 14:23:32.580 [DEBUG] [.cc2531.network.ZigBeeNetworkManager] - CC2531 version is Software=2.6 Product=0 Hardware=3 Transport=2
2018-01-23 14:23:32.581 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - initResponse is JOINED
2018-01-23 14:23:32.582 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - initializeNetwork is false
2018-01-23 14:23:32.942 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - CC2531 transport startup
2018-01-23 14:23:32.942 [DEBUG] [.cc2531.network.ZigBeeNetworkManager] - Creating network as Coordinator
2018-01-23 14:23:33.873 [INFO ] [.cc2531.network.ZigBeeNetworkManager] - Initialized ZigBee network with existing network state.
2018-01-23 14:23:33.875 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c0
2018-01-23 14:23:33.876 [DEBUG] [.cc2531.network.ZigBeeNetworkManager] - Started as ZigBee Coordinator
2018-01-23 14:23:34.037 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Registered endpoint 1 with profile: 260
2018-01-23 14:23:34.038 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Scheduling node discovery
2018-01-23 14:23:34.038 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:Zigbee' changed from UNKNOWN to ONLINE
2018-01-23 14:23:34.039 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Starting node discovery
2018-01-23 14:23:34.041 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=00, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2018-01-23 14:23:34.047 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=0, payload=00 00 00 01 00]
2018-01-23 14:23:34.284 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 28 6B 89 0E 00 4B 12 00 00 00 00 00]
2018-01-23 14:23:34.286 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E896B28, nwkAddrRemoteDev=0, startIndex=0, nwkAddrAssocDevList=[]]
2018-01-23 14:23:34.287 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 28 6B 89 0E 00 4B 12 00 00 00 00]
2018-01-23 14:23:34.287 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E896B28, nwkAddrRemoteDev=0, startIndex=null, nwkAddrAssocDevList=[]]
2018-01-23 14:23:34.291 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Ieee Address returned IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E896B28, nwkAddrRemoteDev=0, startIndex=0, nwkAddrAssocDevList=[]]
2018-01-23 14:23:34.292 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E896B28: Associated devices table unchanged
2018-01-23 14:23:34.292 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS successful. Advanced to NODE_DESCRIPTOR.
2018-01-23 14:23:34.293 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NodeDescriptorRequest [0/0 -> 0/0, cluster=0002, TID=01, nwkAddrOfInterest=0]
2018-01-23 14:23:34.293 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=2, addressMode=DEVICE, radius=31, sequence=1, payload=00 00 00]
2018-01-23 14:23:34.394 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialise done....... 11  -21837  00124B000E896B28
2018-01-23 14:23:34.396 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_cc2531:Zigbee' has been updated.
2018-01-23 14:23:34.515 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32770, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00]
2018-01-23 14:23:34.516 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[MAINS_POWER, RECEIVER_ON_WHEN_IDLE, FULL_FUNCTION_DEVICE, ALTERNATIVE_PAN], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-01-23 14:23:34.517 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32770, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00]
2018-01-23 14:23:34.517 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Node Descriptor returned NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[MAINS_POWER, RECEIVER_ON_WHEN_IDLE, FULL_FUNCTION_DEVICE, ALTERNATIVE_PAN], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-01-23 14:23:34.517 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[MAINS_POWER, RECEIVER_ON_WHEN_IDLE, FULL_FUNCTION_DEVICE, ALTERNATIVE_PAN], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-01-23 14:23:34.518 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request NODE_DESCRIPTOR successful. Advanced to POWER_DESCRIPTOR.
2018-01-23 14:23:34.518 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: PowerDescriptorRequest [0/0 -> 0/0, cluster=0003, TID=02, nwkAddrOfInterest=0]
2018-01-23 14:23:34.518 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=3, addressMode=DEVICE, radius=31, sequence=2, payload=00 00 00]
2018-01-23 14:23:34.909 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E896B28: ClusterMatcher starting
2018-01-23 14:23:35.031 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32771, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 10 C1]
2018-01-23 14:23:35.032 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: PowerDescriptorResponse [0/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, powerDescriptor=RECEIVER_ON_IDLE, [MAINS], MAINS, FULL]
2018-01-23 14:23:35.034 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Power Descriptor returned PowerDescriptorResponse [0/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, powerDescriptor=RECEIVER_ON_IDLE, [MAINS], MAINS, FULL]
2018-01-23 14:23:35.034 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request POWER_DESCRIPTOR successful. Advanced to ACTIVE_ENDPOINTS.
2018-01-23 14:23:35.034 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 0/0, cluster=0005, TID=03, nwkAddrOfInterest=0]
2018-01-23 14:23:35.035 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, sequence=3, payload=00 00 00]
2018-01-23 14:23:35.035 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32771, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 10 C1]
2018-01-23 14:23:35.036 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: PowerDescriptorResponse [0/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, powerDescriptor=RECEIVER_ON_IDLE, [MAINS], MAINS, FULL]
2018-01-23 14:23:35.150 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E896B28: ClusterMatcher adding cluster 1280
2018-01-23 14:23:35.271 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 01 01]
2018-01-23 14:23:35.271 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [0/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, activeEpList=[1]]
2018-01-23 14:23:35.272 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 01 01]
2018-01-23 14:23:35.272 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [0/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, activeEpList=[1]]
2018-01-23 14:23:35.272 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Active Endpoints returned ActiveEndpointsResponse [0/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, activeEpList=[1]]
2018-01-23 14:23:35.273 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Scheduling endpoint discovery
2018-01-23 14:23:35.274 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request ACTIVE_ENDPOINTS successful. Advanced to DISCOVERY_END.
2018-01-23 14:23:35.275 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Ending node discovery
2018-01-23 14:23:35.275 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Starting endpoint discovery
2018-01-23 14:23:35.275 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 0/0, cluster=0004, TID=04, nwkAddrOfInterest=0, endpoint=1]
2018-01-23 14:23:35.275 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, sequence=4, payload=00 00 00 01]
2018-01-23 14:23:35.397 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 08 01 04 01 00 00 00 00 00]
2018-01-23 14:23:35.397 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [0/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, length=8, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=0, deviceVersion=0, inputClusterList=[], outputClusterList=[]]]
2018-01-23 14:23:35.398 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Simple Descriptor returned SimpleDescriptorResponse [0/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, length=8, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=0, deviceVersion=0, inputClusterList=[], outputClusterList=[]]]
2018-01-23 14:23:35.398 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 08 01 04 01 00 00 00 00 00]
2018-01-23 14:23:35.399 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [0/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, length=8, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=0, deviceVersion=0, inputClusterList=[], outputClusterList=[]]]
2018-01-23 14:23:35.400 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 0/1: Setting input clusters []
2018-01-23 14:23:35.400 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 0/1: Setting output clusters []
2018-01-23 14:23:35.401 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery has completed all endpoints
2018-01-23 14:23:35.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00124B000E896B28: Node 0 is added to the network
2018-01-23 14:23:35.405 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2018-01-23 14:23:35.405 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Ending endpoint discovery
2018-01-23 14:23:47.991 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:Zigbee
2018-01-23 14:23:47.992 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
2018-01-23 14:23:47.992 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=05, permitDuration=60, tcSignificance=true]
2018-01-23 14:23:47.994 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=5, payload=00 3C 01]
2018-01-23 14:23:48.108 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=06, permitDuration=60, tcSignificance=true]
2018-01-23 14:23:48.109 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=6, payload=00 3C 01]
2018-01-23 14:23:48.229 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
2018-01-23 14:23:48.230 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, sequence=0, payload=00 00]
2018-01-23 14:23:48.230 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]
2018-01-23 14:24:48.218 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-01-23 14:24:52.936 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:Zigbee
2018-01-23 14:24:52.937 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
2018-01-23 14:24:52.937 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=07, permitDuration=60, tcSignificance=true]
2018-01-23 14:24:52.937 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=7, payload=00 3C 01]
2018-01-23 14:24:53.266 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=08, permitDuration=60, tcSignificance=true]
2018-01-23 14:24:53.267 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=8, payload=00 3C 01]
2018-01-23 14:24:53.387 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-01-23 14:24:53.387 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
2018-01-23 14:24:53.388 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, sequence=0, payload=00 00]
2018-01-23 14:24:53.388 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]

How did you install it? copy and paste in the addons? Did you have to delete anything? link?

First I removed the existing binding via /etc/openhab2/services/addons.cfg (you can use PaperUI or whatever you used to originally install the binding to remove). To install the latest I did the following in the Karaf Console:

bundle:install https://openhab.ci.cloudbees.com/job/openHAB2-Bundles/lastSuccessfulBuild/artifact/bindings/org.openhab.binding.zigbee/target/org.openhab.binding.zigbee-2.3.0-SNAPSHOT.jar
bundle:start "ZigBee Binding"

JACKPOT!!!

Where do I send you and Chris Jackson a pizza?

Has anyone else had success with the SYLVANIA SMART+ line of bulbs?

From what I see on the web, these are made by Osram so I don’t think there should be any reason they shouldn’t work (I’ve tested against a number of different Osram bulbs).

How were you able to set the USB to high priority?

I have new devices:
Trust tunable bulbs

From the properties:
zigbee_logicaltype ROUTER
zigbee_powerlevel FULL
modelId ZLL-ExtendedColo
zigbee_networkaddress 48977
zigbee_powersource MAINS
zigbee_stkversion 1
zigbee_datecode 20160916
zigbee_zclversion 1
vendor Trust International B.V.

They work without issue, but produce warnings:

2018-01-24 16:16:50.813 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D00011E871A: Failed discovering attributes in color control cluster
2018-01-24 16:16:51.126 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D0000AF412C: Failed discovering attributes in color control cluster
2018-01-24 16:16:51.530 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D00011E871A: Cannot determine whether device supports RGB color. Assuming it supports HUE/SAT
2018-01-24 16:16:51.965 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D0000AF412C: Cannot determine whether device supports RGB color. Assuming it supports HUE/SAT

Please can you provide a debug log of this. It means that they probably aren’t responding to one of the commands used to work out what functions the device supports so we make some assumptions. Given the device works ok, it’s probably the right assumption, but it would be good to understand it.