Zigbee connection CC2531 with ikea tradfri

Hi everyone,

I have trouble connecting my IKEA tradfri bulbs to my zigbee network.

  • Hardware: 2 Xiaomi TemperaturHumidity Sensors/3 ikea light bulbs/1ikea switch/TI cc2531 (flashed as described in binding info)/raspberry pi
  • Software: openhabian/ti zigbee home stack
  • What I did: Everything as it was described in the binding info: flashed the cc2531, changed JAVA_OPS, etc.
  • What works: I can discover Zigbee devices, add them as things (I use the configuration files) and they change to ONLINE and in PaperUI the right channels are added. One Xiaomi Sensor works perfectly, the other doesn’t… ok now none of them work.
  • What does not work: When I link a channel to an item and the item gets triggered nothing happens. Except a lot of debug messages getting generated.

Especially this one caught my attention.

Transaction timeout: OnCommand [On/Off: 0/0 -> 5215/1, cluster=0006, TID=60]

I don’t have enough experience with ZigBee or the binding to decode what my error could be. Here is the part of the log after a command (whole log attached as file at the bottom)

> 2019-02-26 20:13:20.084 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 90FD9FFFFE6B7A2E: Command for channel zigbee:device:7e898e26:90fd9ffffe6b7a2e:90FD9FFFFE6B7A2E_1_dimmer --> OFF [OnOffType]
> 2019-02-26 20:13:20.099 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 90FD9FFFFE8FEC8E: Command for channel zigbee:device:7e898e26:90fd9ffffe8fec8e:90FD9FFFFE8FEC8E_1_dimmer --> OFF [OnOffType]
> 2019-02-26 20:13:20.101 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OffCommand [On/Off: 0/0 -> 5215/1, cluster=0006, TID=62]
> 2019-02-26 20:13:20.105 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=98, commandId=0]
> 2019-02-26 20:13:20.108 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=5215/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=98, payload=01 62 00]
> 2019-02-26 20:13:20.111 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 5F 14 01 01 06 00 62 30 1F 03 01 62 00 48, checksum=48, error=false) 
> 2019-02-26 20:13:20.115 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: OffCommand [On/Off: 0/0 -> 57085/1, cluster=0006, TID=63]
> 2019-02-26 20:13:20.119 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=99, commandId=0]
> 2019-02-26 20:13:20.124 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=57085/1, profile=0104, cluster=6, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=99, payload=01 63 00]
> 2019-02-26 20:13:20.229 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
> 2019-02-26 20:13:20.232 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 FD DE 01 01 06 00 63 30 1F 03 01 63 00 20, checksum=20, error=false) 
> 2019-02-26 20:13:20.271 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 90FD9FFFFE6B7A2E: Node SVC Discovery: running
> 2019-02-26 20:13:20.274 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 5215/0, cluster=0032, TID=64, startIndex=0]
> 2019-02-26 20:13:20.277 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=5215/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=100, payload=00 00]
> 2019-02-26 20:13:20.353 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
> 2019-02-26 20:13:20.357 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 5F 14 00 00 32 00 64 30 1F 02 00 00 19, checksum=19, error=false) 
> 2019-02-26 20:13:20.483 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
> 2019-02-26 20:13:20.684 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 CD 01 63 68)
> 2019-02-26 20:13:20.686 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_NWK_NO_ROUTE(205), TransID=99)
> 2019-02-26 20:13:20.945 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 CD 01 62 69)
> 2019-02-26 20:13:20.946 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_NWK_NO_ROUTE(205), TransID=98)
> 2019-02-26 20:13:22.750 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00158D00022BC455: Node SVC Discovery: running
> 2019-02-26 20:13:22.753 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 50850/0, cluster=0031, TID=65, startIndex=0]
> 2019-02-26 20:13:22.757 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=50850/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=101, payload=00 00]
> 2019-02-26 20:13:22.761 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 A2 C6 00 00 31 00 65 30 1F 02 00 00 34, checksum=34, error=false) 
> 2019-02-26 20:13:22.887 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
> 2019-02-26 20:13:23.717 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ManagementLqiRequest [0/0 -> 6645/0, cluster=0031, TID=5F, startIndex=0]
> 2019-02-26 20:13:23.723 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 90FD9FFFFEE5FEC8: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT]
> 2019-02-26 20:13:23.726 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 90FD9FFFFEE5FEC8: Node SVC Discovery: request NEIGHBORS failed. Retry 9, wait 10625ms before retry.
> 2019-02-26 20:13:24.959 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: OnCommand [On/Off: 0/0 -> 5215/1, cluster=0006, TID=60]
> 2019-02-26 20:13:24.965 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: OnCommand [On/Off: 0/0 -> 57085/1, cluster=0006, TID=61]
> 2019-02-26 20:13:28.101 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: OffCommand [On/Off: 0/0 -> 5215/1, cluster=0006, TID=62]
> 2019-02-26 20:13:28.115 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: OffCommand [On/Off: 0/0 -> 57085/1, cluster=0006, TID=63]
> 2019-02-26 20:13:28.274 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ManagementRoutingRequest [0/0 -> 5215/0, cluster=0032, TID=64, startIndex=0]
> 2019-02-26 20:13:28.281 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 90FD9FFFFE6B7A2E: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [TIMEOUT]
> 2019-02-26 20:13:28.285 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 90FD9FFFFE6B7A2E: Node SVC Discovery: request ROUTES failed. Retry 10, wait 13674ms before retry.
> 2019-02-26 20:13:30.754 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ManagementLqiRequest [0/0 -> 50850/0, cluster=0031, TID=65, startIndex=0]
> 2019-02-26 20:13:30.760 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00158D00022BC455: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT]
> 2019-02-26 20:13:30.762 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 00158D00022BC455: Node SVC Discovery: request NEIGHBORS failed. Retry 9, wait 22090ms before retry.

openhab.log (991.7 KB)

Here are my configurations:

Things:

Bridge zigbee:coordinator_cc2531:7e898e26 "Zigbee coordinator" [ zigbee_port="/dev/ttyACM0", zigbee_baud=9600] {
    Things:
    zigbee:xiaomi_lumisensorht:7e898e26:00158d0002bf8990 "TempBad" [zigbee_macaddress="00158d0002bf8990"]
    zigbee:xiaomi_lumisensorht:7e898e26:00158D0002E6AB50 "TempBed" [zigbee_macaddress="00158D0002E6AB50"]
    zigbee:device:7e898e26:90fd9ffffee5fec8 "Ikea" [zigbee_macaddress="90fd9ffffee5fec8"]
    zigbee:device:7e898e26:90fd9ffffe6b7a2e "Bulb 1" [zigbee_macaddress="90fd9ffffe6b7a2e"]
    zigbee:device:7e898e26:90fd9ffffe8fec8e "Bulb 2" [zigbee_macaddress="90fd9ffffe8fec8e"]
    //zigbee:device:7e898e26:90fd9ffffe6b7a2e "Bulb 3" [zigbee_macaddress="90fd9ffffe6b7a2e"]
}

Items:

Dimmer Bulb1  { channel="zigbee:device:7e898e26:90fd9ffffe8fec8e:90FD9FFFFE8FEC8E_1_dimmer" }

Thanks for any help in advance!