Zigbee scanning / binding problems

plewzdt · April 4, 2021, 7:27pm

Platform information:
- Hardware: armv7
- OS: balenaOS
- Java Runtime Environment: openjdk 11.0.10 2021-01-19 LTS
  OpenJDK Runtime Environment Zulu11.45+27-CA (build 11.0.10+9-LTS)
  OpenJDK Client VM Zulu11.45+27-CA (build 11.0.10+9-LTS, mixed mode)
- openHAB version: 3.1.0 M3

I use a CC2531 dongle with CC2531ZNP-Prod.hex

I add zigbee binding, coordinator says “ONLINE”. I press can and this is the log. I find no zigbee devices.
I try to bind a brand new Philips Hue lwa001.
I make sure it’s turned on right after scan is started.

2021-04-04 21:13:06.310 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:6185b508dd
2021-04-04 21:13:06.315 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to FFFC/0 for 60 seconds.
2021-04-04 21:13:06.319 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=--, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:06.322 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2021-04-04 21:13:06.325 [DEBUG] [transaction.ZigBeeTransactionManager] - FFFC/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=5, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=0E, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:06.327 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2021-04-04 21:13:06.330 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=0E, permitDuration=60, tcSignificance=true]
2021-04-04 21:13:06.333 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=FFFC/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=16, rssi=--, lqi=--, payload=0E 3C 01]
2021-04-04 21:13:06.337 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B000FFB7FB2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=--, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:06.339 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
2021-04-04 21:13:06.342 [DEBUG] [transaction.ZigBeeTransactionManager] - 0000/0: Sending ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=6, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:06.345 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
2021-04-04 21:13:06.348 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]
2021-04-04 21:13:06.351 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=17, rssi=--, lqi=--, payload=0F 3C 01]
2021-04-04 21:13:16.331 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=10011, state=FAILED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=0E, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:16.336 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
2021-04-04 21:13:16.338 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete, state=FAILED, outstanding=0
2021-04-04 21:13:16.340 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete exceeded max retries 1
2021-04-04 21:13:16.343 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=null queueTime=10023, state=CANCELLED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=0E, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:16.345 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=10025, state=CANCELLED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=0E, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:16.347 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
2021-04-04 21:13:16.348 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=10011, state=FAILED, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:16.350 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-04-04 21:13:16.353 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
2021-04-04 21:13:16.355 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
2021-04-04 21:13:16.357 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B000FFB7FB2: transactionComplete, state=FAILED, outstanding=0
2021-04-04 21:13:16.360 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B000FFB7FB2: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=10023, state=WAITING, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:16.362 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
2021-04-04 21:13:16.369 [DEBUG] [transaction.ZigBeeTransactionManager] - 0000/0: Sending ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=10027, state=WAITING, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:16.372 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2021-04-04 21:13:16.375 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]
2021-04-04 21:13:16.378 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=18, rssi=--, lqi=--, payload=0F 3C 01]
2021-04-04 21:13:16.380 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=0, sleepy=0/3
2021-04-04 21:13:26.376 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=20039, state=FAILED, sendCnt=2, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:26.380 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-04-04 21:13:26.384 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B000FFB7FB2: transactionComplete, state=FAILED, outstanding=0
2021-04-04 21:13:26.389 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B000FFB7FB2: transactionComplete exceeded max retries 2
2021-04-04 21:13:26.394 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=20057, state=CANCELLED, sendCnt=2, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:26.406 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B000FFB7FB2 queueTime=20069, state=CANCELLED, sendCnt=2, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=0F, permitDuration=60, tcSignificance=true]]
2021-04-04 21:13:26.409 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2021-04-04 21:13:26.411 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 00124B000FFB7FB2: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
2021-04-04 21:13:26.413 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
2021-04-04 21:13:26.415 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3

Bruce_Osborne · April 4, 2021, 7:36pm

I am not a Zigbee user but the log viewer may help.

plewzdt · April 4, 2021, 7:45pm

Thanks.

I am not sure if this explains the CANCELLED / FAILED stuff.

Update - I set everything to debug, so at least get RX commands etc.

chris · April 4, 2021, 8:10pm

My guess is that for these devices you will need a newer firmware for the coordinator you have, or a better coordinator.