Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

BJ 6711 U relay state not updated #49

Closed
weakfl opened this issue Dec 3, 2017 · 73 comments
Closed

BJ 6711 U relay state not updated #49

weakfl opened this issue Dec 3, 2017 · 73 comments

Comments

@weakfl
Copy link
Contributor

weakfl commented Dec 3, 2017

As discussed in the community thread, the state of the Busch-Jaeger 6711 U relay does not get updated in OH if the physical switch is used to change its state.

Here's the requested log:
oh_zigbee_binding_debug.log.zip

I've also changed the state of the relay to on/off twice. First time via OH, second time via the physical switch, should be at the end of the log.

@cdjackson
Copy link
Contributor

D85DEF11A1002826: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, modelId=RM01, zigbee_networkaddress=45692, zigbee_powersource=MAINS, zigbee_stkversion=1, zigbee_datecode=20161209, zigbee_zclversion=1, vendor=Busch-Jaeger, zigbee_appversion=1, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS, RECHARGABLE_BATTERY, DISPOSABLE_BATTERY], hardwareVersion=0}

D85DEF11A1001973: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, modelId=RM01, zigbee_networkaddress=31084, zigbee_powersource=MAINS, zigbee_stkversion=1, zigbee_datecode=20161209, zigbee_zclversion=1, vendor=Busch-Jaeger, zigbee_appversion=1, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS, RECHARGABLE_BATTERY, DISPOSABLE_BATTERY], hardwareVersion=0}

I'll move the conversation off the forum for this - it'll be easier to track...

Is there now an XML generated in the /userdata/zigbee folder? Can you post it please?

@cdjackson
Copy link
Contributor

cdjackson commented Dec 3, 2017

Just for my reference...

Device information...

2017-12-03 10:04:16.226 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [45692/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=45692, length=20, simpleDescriptor=SimpleDescriptor [endpoint=18, profileId=C05E, deviceId=0, deviceVersion=2, inputClusterList=[0, 4, 3, 5, 6, 8], outputClusterList=[]]]
2017-12-03 10:04:16.245 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/18: Setting input clusters [0, 4, 3, 5, 6, 8]
2017-12-03 10:04:16.259 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/18: Setting cluster BASIC as server
2017-12-03 10:04:16.262 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/18: Setting cluster GROUPS as server
2017-12-03 10:04:16.263 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/18: Setting cluster IDENTIFY as server
2017-12-03 10:04:16.329 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/18: Setting cluster SCENES as server
2017-12-03 10:04:16.332 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/18: Setting cluster ON_OFF as server
2017-12-03 10:04:16.391 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/18: Setting cluster LEVEL_CONTROL as server

2017-12-03 10:04:16.525 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [45692/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=45692, length=28, simpleDescriptor=SimpleDescriptor [endpoint=10, profileId=C05E, deviceId=2064, deviceVersion=2, inputClusterList=[0, 4096], outputClusterList=[4096, 3, 6, 8, 4, 5, 768, 25]]]
2017-12-03 10:04:16.530 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting input clusters [0, 4096]
2017-12-03 10:04:16.532 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster BASIC as server
2017-12-03 10:04:16.533 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster TOUCHLINK as server
2017-12-03 10:04:16.535 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting output clusters [4096, 3, 6, 8, 4, 5, 768, 25]
2017-12-03 10:04:16.538 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster TOUCHLINK as client
2017-12-03 10:04:16.540 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster IDENTIFY as client
2017-12-03 10:04:16.541 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster ON_OFF as client
2017-12-03 10:04:16.552 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster GROUPS as client
2017-12-03 10:04:16.553 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster SCENES as client
2017-12-03 10:04:16.606 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster COLOR_CONTROL as client
2017-12-03 10:04:16.688 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 45692/10: Setting cluster OTA_UPGRADE as client

Bind / Reporting commands [Level control]...

2017-12-03 10:04:16.812 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0/0 -> 45692/18, cluster=0021, TID=15, srcAddress=D85DEF11A1002826, srcEndpoint=18, bindCluster=8, dstAddrMode=3, dstAddress=000D6F000D03D29E, dstEndpoint=1]
2017-12-03 10:04:18.655 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: BindResponse [45692/0 -> 0/0, cluster=8021, TID=NULL, status=SUCCESS]

2017-12-03 10:04:17.492 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ConfigureReportingCommand [Level Control: 0/0 -> 45692/18, cluster=0008, TID=1B, records=[Attribute Reporting Configuration Record: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, minimumReportingInterval=1, maximumReportingInterval=600, reportableChange=1, timeoutPeriod=0]]
2017-12-03 10:04:18.447 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ConfigureReportingResponse [Level Control: 45692/18 -> 0/0, cluster=0008, TID=1B, records=[Attribute Status Record: status=SUCCESS, direction=false, attributeIdentifier=0, Attribute Status Record: status=SUCCESS, direction=false, attributeIdentifier=0, Attribute Status Record: status=SUCCESS, direction=false, attributeIdentifier=0, Attribute Status Record: status=SUCCESS, direction=false, attributeIdentifier=0]]

Frames observed -:

2017-12-03 11:45:23.435 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=45692/18, destinationAddress=0/1, profile=49246, cluster=6, addressMode=null, radius=0, sequence=0, payload=18 1B 0A 00 00 10 00]
2017-12-03 11:45:23.475 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=45692/18, destinationAddress=0/1, profile=49246, cluster=8, addressMode=null, radius=0, sequence=0, payload=18 1C 0A 00 00 20 FE]

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

There are two xml files, I guess you're only interested in the zigbee-network.xml but here are both anyway: zigbee-network-xml.zip

@cdjackson
Copy link
Contributor

Thanks - they should be the same. The most recent version I changed the name from zigbee-network, to add the coordinator uid to the end so that there can be more than one coordinator without conflict.

@cdjackson
Copy link
Contributor

From the above it seems that the binding and reporting are configured (for the level command at least - I've not looked through every channel yet)...

Can you tell me what time you changed the relay position manually? (probably not ;) ). Alternatively, can you provide a short log that basically just shows what happens when you press the button.

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

Should be at the end of the previous log. However, here's what's in the log when pressing the button a couple of times (not much though I'm afraid):

2017-12-03 11:43:04.572 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: ManagementRoutingRequest returned null
2017-12-03 11:43:04.645 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2017-12-03 11:43:04.647 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Ending mesh update
2017-12-03 11:44:04.510 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2017-12-03 11:44:04.513 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=D0, startIndex=0]
2017-12-03 11:44:04.517 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=49, addressMode=DEVICE, radius=31, sequence=208, payload=00 00]
2017-12-03 11:44:04.521 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=null]
2017-12-03 11:44:04.524 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 93
2017-12-03 11:44:14.528 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: ManagementLqiRequest returned null
2017-12-03 11:44:14.530 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=D1, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-12-03 11:44:14.533 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=1, addressMode=DEVICE, radius=31, sequence=209, payload=00 00 00 01 00]
2017-12-03 11:44:14.537 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=1, messageData=00 00 00 01 00, messageId=null]
2017-12-03 11:44:14.540 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 94
2017-12-03 11:44:24.543 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Ieee Address request returned null
2017-12-03 11:44:24.545 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D2, startIndex=0]
2017-12-03 11:44:24.548 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0, cluster=50, addressMode=DEVICE, radius=31, sequence=210, payload=00 00]
2017-12-03 11:44:24.550 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=null]
2017-12-03 11:44:24.552 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 95
2017-12-03 11:44:34.555 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: ManagementRoutingRequest returned null
2017-12-03 11:44:34.626 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2017-12-03 11:44:34.629 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Ending mesh update

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

I don't know if it's relevant, but the physical switch actually has two buttons (or maybe two sides is more appropriate). Right side is for turning the relay on, left side for turning it off.

@cdjackson
Copy link
Contributor

This logging isn't related to the device :( . I guess that means the device isn't sending anything.

Hmm - something else is wrong as the TX queue is increasing in size. Can you ZIP up the log again please so I can take a better look?

Thanks.

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

I guess that means the device isn't sending anything.

That doesn't sound good :)

I've attached a log from restarting OH earlier today until now. However, I had set the log level to ERROR for a while, sorry. Let me know if you need a fresh debug log and I'll restart OH and let it run for a while.

oh_log_2017-12-03_12-01.log.zip

@cdjackson
Copy link
Contributor

Thanks.

So, good news, and bad news...

Firstly, the log doesn't show the error that I was looking for - the TX queue seems to be stalled and you will need to restart the binding to solve this. If this happens again, please post the log (the indicator is the log entry TX Telegesis queue: 96 - basically this number should normally only be 1/2/3 sort of level (maybe more for short periods, but it should not just increase like I see in your log.

Good news though - I do see the commands in your log that I suspect are from the switches - it's from just after the log you attached above. It looks like they are using the ZLL profile so are bing dropped by the binding (easy issue to solve) - I need to decode the messages to see what they are - I think they are the reports I was looking for so there's some hope :)

If I provide you a debug version are you able to give it a try?

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

If this happens again, please post the log (the indicator is the log entry TX Telegesis queue: 96 - basically this number should normally only be 1/2/3 sort of level (maybe more for short periods, but it should not just increase like I see in your log.

Ok, thanks for the info. I'll restart OH with debug log level and if I notice something like that I'll post the log.

Good news though - I do see the commands in your log that I suspect are from the switches - it's from just after the log you attached above. It looks like they are using the ZLL profile so are bing dropped by the binding (easy issue to solve) - I need to decode the messages to see what they are - I think they are the reports I was looking for so there's some hope :)

Nice 👍

If I provide you a debug version are you able to give it a try?

If you could provide a jar I'll be happy to test it. I haven't set up an OH development environment, so unfortunately I can't build myself.

@cdjackson
Copy link
Contributor

cdjackson commented Dec 3, 2017

A test version is here. Let me know if this works, and if not please provide another short log showing any messages from the device...

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

Thanks Chris, I'll test it and report back.

Regarding the issue with the growing TX queue, this has happened again since the last restart.
Here's a full debug log since the last OH restart:
oh_zigbee_binding_debug_03122017_1434.log.zip

@cdjackson
Copy link
Contributor

cdjackson commented Dec 3, 2017 via email

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

Raspi with stretch:

Raspberry Pi 3 Model B Rev 1.2
Raspbian GNU/Linux 9 (stretch)
Linux raspberrypi 4.9.59-v7+ #1047 SMP Sun Oct 29 12:19:23 GMT 2017 armv7l GNU/Linux

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

Unfortunately it's not working with the test version either.
I've used the OH app first to determine everything is still working as expected. Then I've used a combination of physical/software.

Here's a brief timeline of what I did:

15:26 ON then OFF - openhab app
15:27 ON - physical switch
15:30 OFF - physical switch
15:31 ON - openhab app
15:31 OFF - physical switch

And the log:
oh_zigbee_binding_debug_03122017_1535.log.zip

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

Just to make sure I've used the right version, the link you posted above was broken but I figured the correct URL should be http://www.cd-jackson.com/downloads/openhab2/org.openhab.binding.zigbee_2.2.0.201712031320.jar

@cdjackson
Copy link
Contributor

I've fixed the link, but looks fine (seems if I don't add http on the front of the link, github assumes it's a local address!).

Anyway, with these logs, I don't see the ZLL messages I saw before, so the version doesn't matter. In your original log, they might not have been directly linked to the button presses as I'm not sure they were at the same time given you originally didn't post them as something that happened when the button was pressed. Your log when you said you changed the switch was at 11:44:04 to 11:44:34, but the ZLL frames are at 11:45:23 so I guess it's not linked and we're back to the "nothing is being sent" situation :(

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

so I guess it's not linked and we're back to the "nothing is being sent" situation :(

I'm afraid you're right as I don't see any Received message or Incoming message events in the log when I press the switch. I could contact BJ and try to get some infos. This looks like a major oversight to me, I thought one of the advantages of zigbee would be some kind of "back channel" compared to cheaper 433 mhz relays.

Do you think polling could work to update the relay state in OH, even if the state update wouldn't be immediate?

Another route might be the switch attached to the relay. I'm still a bit puzzled by those. I was expecting the switches to be discoverable as separate zigbee devices (just like the battery powered switch), but they're not showing up in the inbox. Especially because some of the switches have two/four rockers with the top rocker switching the relay and the other rockers being usable for scenes...

Maybe you'll be able find out more when you receive the switch you've ordered.

Anyway, thank you so much for taking the time to look into the issue!

@cdjackson
Copy link
Contributor

cdjackson commented Dec 3, 2017 via email

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

Great, so there's still hope :)

That arrived this morning, but unfortunately it’s not so useful as it seems likely it only speaks ZLL - I need to find more time to play with that though. I have some other devices that might do a similar thing so I might power up one of them.

One note about the battery powered switch. You should be able to "pair" it with a ZLL device like a HUE bulb.
I did pair my battery powered switch with one of the relays today and it became ONLINE in OH. It worked for a while and then suddenly stopped. I'm only guessing here, but it might be the same issue you described regarding the CT motion sensor (removing itself from the network). Thought I'll let you know, just in case...

@cdjackson
Copy link
Contributor

Great, so there's still hope :)

Always (well, for now ;) ).

I've found a small bug, but it only confirms that the device is responding correctly to the ConfigureReporting command and the above is incorrectly displayed - it doesn't change anything though...

One note about the battery powered switch. You should be able to "pair" it with a ZLL device like a HUE bulb.

Yep, I know that, but that's not what I wanted to do (but thanks).

That said, if I can do this with a Hue bulb, and use the remote to control the bulb, it might help debug the reporting... I'll try this out...

@cdjackson
Copy link
Contributor

If you get a chance, please give this version a try. It will hopefully give you another switch channel (switch out) - it probably won't do anything, but if it sends any commands (or anything!!) when you click the switch, I'd be interested to see the log...

Thanks.

@weakfl
Copy link
Contributor Author

weakfl commented Dec 3, 2017

Thanks for the update Chris, I gave it a quick try.
Unfortunately instead of increasing the channel count to 3 channels, the test version actually decreased the channel count to just one dimmer/switch_level channel. The second channel (switch_onoff) disappeared. I still pressed the physical switch but didn't notice anything particular in the log.

However, I accidentally made an interesting discovery reverting to the snapshot version. I even double checked to make sure I wasn't fooled by item persistence:

  • turned the relay OFF in the OH app
  • shut down OH
  • used the physical switch to turn ON the lights
  • started OH
  • button in the OH app was ON (!?!)

So on OH startup the relay/item state seems to be initialized correctly?

@cdjackson
Copy link
Contributor

cdjackson commented Dec 3, 2017 via email

@cdjackson
Copy link
Contributor

cdjackson commented Dec 3, 2017 via email

@cdjackson
Copy link
Contributor

Here's another version to test... Again, I don't expect it to work - you should get an extra channel this time I hope and I'd be interested to see what the log shows (if anything) when you click the buttons.

@weakfl
Copy link
Contributor Author

weakfl commented Dec 5, 2017

Thanks Chris, and sorry for the late reply. I got caught up at the office today...

The new test version was quite a success, but maybe not as expected :)

As I've mentioned I have two relays installed, one with a 1-channel switch attached and one with a 2-channel switch.
The 1-channel relay got one new channel, the 2-channel relay got two(!). All are Switch (out) channels.

I created some dummy switch items and started pressing buttons. I think the switches which are turning the relay on/off didn't do anything, but maybe you can take a look at
this log to make sure. There were some TelegesisReceiveMessageEvents, but I think they were unrelated.

However, the second channel on the 2-channel switch definitely does something:

1:06:50.021 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TELEGESIS RX: Data 52 58 3A 37 39 36 43 2C 30 31 30 34 2C 30 31 2C 30 42 2C 30 30 30 36 2C 30 33 3A 11 D9 01 2C 2D 33 38 2C 46 46
21:06:50.035 [DEBUG] [egesis.internal.TelegesisFrameHandler] - Telegesis event received: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=31084, profileId=260, destinationEp=1, sourceEp=11, clusterId=6, messageData=11 D9 01, rssi=-56, lqi=255]
21:06:50.049 [DEBUG] [ongle.telegesis.ZigBeeDongleTelegesis] - Telegesis RX: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=31084, profileId=260, destinationEp=1, sourceEp=11, clusterId=6, messageData=11 D9 01, rssi=-56, lqi=255]
21:06:50.063 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=31084/11, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, sequence=0, payload=11 D9 01]
21:06:50.075 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=217, commandId=1]
21:06:50.088 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: OnCommand [On/Off: 31084/11 -> 0/1, cluster=0006, TID=D9]
21:06:52.540 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TELEGESIS RX: Data 52 58 3A 37 39 36 43 2C 30 31 30 34 2C 30 31 2C 30 42 2C 30 30 30 36 2C 30 33 3A 11 DA 00 2C 2D 34 31 2C 46 46
21:06:52.551 [DEBUG] [egesis.internal.TelegesisFrameHandler] - Telegesis event received: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=31084, profileId=260, destinationEp=1, sourceEp=11, clusterId=6, messageData=11 DA 00, rssi=-65, lqi=255]
21:06:52.562 [DEBUG] [ongle.telegesis.ZigBeeDongleTelegesis] - Telegesis RX: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=31084, profileId=260, destinationEp=1, sourceEp=11, clusterId=6, messageData=11 DA 00, rssi=-65, lqi=255]
21:06:52.573 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=31084/11, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, sequence=0, payload=11 DA 00]
21:06:52.582 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=218, commandId=0]
21:06:52.589 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: OffCommand [On/Off: 31084/11 -> 0/1, cluster=0006, TID=DA]

It didn't change the state of the switch item attached to the chanel, but it is obviously sending messages 👍

@cdjackson
Copy link
Contributor

cdjackson commented Dec 5, 2017 via email

@weakfl
Copy link
Contributor Author

weakfl commented Jan 21, 2018

That will allow me to test the consolidation here as I'm not sure I understand what's going on from your report ;)

Well, the relay was reporting two channels before you introduced channel consolidation (on/off + level). After consolidation on/off was gone and only the level channel was left. Now both channels are back :)

So the second channel is the one with no load? Great - that was the main thing this was meant to fix :)

Yep, the second button on the switch with no load is now working, nice work. Already controlling the terrace awning with it ;)

EDIT:

  • OH channels on relay with 1-channel switch:

    • level
    • on/off
  • OH channels on relay with 2-channel switch:

    • level
    • on/off
    • on/off

@cdjackson
Copy link
Contributor

Now both channels are back :)

Strange - so there is an on/off and a dimmer channel? I'll try and set up one a device here that might allow me to test at least a bit.

@weakfl
Copy link
Contributor Author

weakfl commented Jan 21, 2018

Strange - so there is an on/off and a dimmer channel?

Yep. We've talked about this, my guess is that BJ is using the same firmware for the simple and the dimmable relays, maybe with some minor modifications. It doesn't make much sense, both channels are just switching the relay on and off.

Btw, I've ordered one of the dimmable relays too. I guess it should work out of the box, but I'll let you know.

@cdjackson
Copy link
Contributor

cdjackson commented Jan 21, 2018 via email

@weakfl
Copy link
Contributor Author

weakfl commented Jan 21, 2018

I’m just surprised that the channel consolidation in the binding has allowed both the switch and the dimmer.

Maybe it hasn't and the additional on/off channel(s) I'm seeing are from the client (the attached button)?

@cdjackson
Copy link
Contributor

cdjackson commented Jan 21, 2018 via email

@weakfl
Copy link
Contributor Author

weakfl commented Jan 22, 2018

I'm having trouble with the battery-powered switch, I can't get it to join anymore, or at least it doesn't show up in the Inbox. Could you please take a look at the log Chris? I have no idea what's going on :(

EDIT: Is it possible the coordinator thinks the device is still part of the network and doesn't add it to the inbox? Is it possible to remove a device manually?

@cdjackson
Copy link
Contributor

cdjackson commented Jan 23, 2018 via email

@weakfl
Copy link
Contributor Author

weakfl commented Jan 24, 2018

Thanks Chris. I've tried version 2.3.0.201801232258 of the binding, but the switch is still not added to the Inbox.
Looks like the IeeeAddressRequest is successful, but the NodeDescriptorRequest fails.

Here's a log: zigbee_issue49_20180124095420.log.zip

@cdjackson
Copy link
Contributor

The device seems to join multiple times, so it keeps changing network address. I don't know why it's doing this - I might check with Silabs.

@weakfl
Copy link
Contributor Author

weakfl commented Jan 24, 2018

Yep. I've noticed that the switch is also communicating with the other routers (relays) in the network, but didn't know if that's normal.
I'll remove power from the relays later to see if that works.

@cdjackson
Copy link
Contributor

The parent is always the same, so I don't think that is the issue. The binding isn't handling the changing of the address this quick - it's changing faster than it is running the discovery (every 7 seconds)...

@weakfl
Copy link
Contributor Author

weakfl commented Jan 24, 2018

You are right, it still is getting a new network address every few seconds:
zigbee_issue49_20180124144039.log.zip

Out of curiosity, why does the coordinator assign a new network address for the same mac address?

@cdjackson
Copy link
Contributor

cdjackson commented Jan 24, 2018 via email

@weakfl
Copy link
Contributor Author

weakfl commented Jan 24, 2018

It's a 6735 battery powered transmitter. I think you have one of these yourself?

@weakfl
Copy link
Contributor Author

weakfl commented Jan 24, 2018

Btw, I‘ve added and removed the switch multiple times with previous versions, so I‘m not sure why it doesn‘t work anymore.

@cdjackson
Copy link
Contributor

Can you reset the switch completely? I wonder if it's not leaving the network and the coordinator is getting confused or something. In theory, when you delete the thing, the binding ought to send a leave command to the device, but I've noticed recently that it doesn't always respond to that...

@weakfl
Copy link
Contributor Author

weakfl commented Jan 24, 2018

I've always reset the switch before trying to add it. What I could try is resetting the coordinator and start from scratch. Let me know if I should try that.

@cdjackson
Copy link
Contributor

Worth a try if you like - it would be interesting to see if it changes anything.

@weakfl
Copy link
Contributor Author

weakfl commented Jan 24, 2018

I tried to start from scratch, but that didn't work out the way i was hoping :)

Now the relays are not found either. The behaviour is the same, they're permanently getting a new network address.

I made a backup but didn't consider that the relays would be removed from the network when deleting them in PaperUI...

@weakfl
Copy link
Contributor Author

weakfl commented Jan 25, 2018

Removing the coordinator and adding it back again did the trick. All devices were found immediately, even the Tradfri from #93

@cdjackson
Copy link
Contributor

Removing the coordinator and adding it back again did the trick.

Just to be clear, what do you mean by this? Did you reset the coordinator and add the thing back in?

Devices do have a maximum number of children they can handle directly - I think they will not allow joining if they are out of "slots", and the joining device would then be expected to join via another router. I personally wouldn't have expected to see what we saw here though where it seemingly joins, then rejoins...

I opened a ticket with Silabs yesterday so let's see what they say.

@weakfl
Copy link
Contributor Author

weakfl commented Jan 25, 2018

Just to be clear, what do you mean by this? Did you reset the coordinator and add the thing back in?

I removed the coordinator thing in OH and added it back. The only noticeable change was the channel, which was 28 before removing the coordinator and "auto" after adding it again (I never changed the channel, no idea why it was 28).

Devices do have a maximum number of children they can handle directly

I only have 4 devices in the network. From what I've read other users have far more than that.

@weakfl
Copy link
Contributor Author

weakfl commented Jan 25, 2018

I've opened PR #116 in case you want to add the BJ devices to the readme. All fully working now.

@weakfl
Copy link
Contributor Author

weakfl commented Nov 19, 2018

@cdjackson I've meanwhile got confirmation from the deCONZ developers that they have the same problem with the BJ dimmers/relays and it doesn't look like BJ will provide a firmware that fixes the issue (device not sending reports on dimmer/relay state change).

So it looks like the only workaround would be to make the polling/report interval configurable. If it's ok with you I'd close this issue and open a feature request?

@cdjackson
Copy link
Contributor

Thanks for the feedback - yes, let's close and open an enhancement request.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants