Item value turns the thing BASICZBR3 switch OFF

Mehdi_Marzougui · March 18, 2022, 3:31pm

Hello everyone,

I am using an XB3-24Z8PT-J as a bridge to connect to the BASICZBR3 switch, I detect it successfully and it is online, then I add a switch item to the provided channel:

15:50:26.352 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:f3dc8e65fc:00124b00226cad76' changed from ONLINE to UNKNOWN                                                                                            
15:50:26.359 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Coordinator is ONLINE. Starting device initialisation.                                                                                                    
15:50:26.377 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Start initialising ZigBee Thing handler                                                                                                                   
15:50:26.379 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00124B00226CAD76: ZigBee node property discovery start
15:50:26.384 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00124B00226CAD76: ZigBee node property discovery using basic cluster on endpoint D424/1                                                                                      
15:50:56.617 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00124B00226CAD76: Stack version failed
15:50:56.617 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00124B00226CAD76: Node doesn't support OTA cluster
15:50:56.617 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 00124B00226CAD76: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x0000, modelId=BASICZBR3, zigbee_networkaddress=54308, zigbee_powersource=MAINS, zigbee_datecode=20201026, zigbee_zclversion=1, vendor=SONOFF, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=1, zigbee_applicationVersion=4, zigbee_device_initialised=true}                                                                                                        
15:50:56.626 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Checking endpoint 1 channels
15:50:56.627 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Dynamically created 1 channels
15:50:56.627 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Device initialization will be skipped as the device is already initialized                                                                                 15:51:06.381 [DEBUG] [converter.config.ZclOnOffSwitchConfig] - D424/1: Unable to get supported attributes for On/Off
15:51:25.862 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Channel initialisation complete
15:51:25.862 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Setting ONLINE/OFFLINE timeout interval to: 1830                                                                                                          
15:51:25.868 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:f3dc8e65fc:00124b00226cad76                                                                                                
15:51:25.868 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:f3dc8e65fc:00124b00226cad76                                                                                   
15:51:25.873 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:f3dc8e65fc:00124b00226cad76 in 1830 seconds
15:51:35.593 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Error getting binding table
15:51:35.593 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Polling initialised at 986070ms
15:51:35.593 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:f3dc8e65fc:00124b00226cad76' changed from UNKNOWN to ONLINE                                                                                            
15:51:35.599 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Done initialising ZigBee Thing handler                                                                                                                    
15:51:35.878 [DEBUG] [nding.zigbee.internal.ZigBeeDataStore] - 00124B00226CAD76: ZigBee saving network state complete.

Given the channel is also online, when I toggle the item values OFF and ON nothing happens with the switch and the thing representing it goes offline, here are the logs:

16:17:38.892 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'Lamp_MAINSPOWEROUTLETSwitch' received command ON
16:17:38.895 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item 'Lamp_MAINSPOWEROUTLETSwitch' predicted to become ON                                                                                                                    16:17:38.895 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Command for channel zigbee:device:f3dc8e65fc:00124b00226cad76:00124B00226CAD76_1_switch --> ON [OnOffType] 
16:17:38.901 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 00124B00226CAD76: Channel zigbee:device:f3dc8e65fc:00124b00226cad76:00124B00226CAD76_1_switch waiting for response to ON
16:17:38.907 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Lamp_MAINSPOWEROUTLETSwitch' changed from NULL to ON                                                                                                                   16:17:46.080 [INFO ] [openhab.event.ItemCommandEvent       ] - Item 'Lamp_MAINSPOWEROUTLETSwitch' received command OFF
16:17:46.081 [INFO ] [openhab.event.ItemStatePredictedEvent] - Item 'Lamp_MAINSPOWEROUTLETSwitch' predicted to become OFF                                                                                                                   16:17:46.083 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 00124B00226CAD76: Command for channel zigbee:device:f3dc8e65fc:00124b00226cad76:00124B00226CAD76_1_switch --> OFF [OnOffType]
16:17:46.084 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Lamp_MAINSPOWEROUTLETSwitch' changed from ON to OFF                                                                                                                    16:17:46.091 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 00124B00226CAD76: Channel zigbee:device:f3dc8e65fc:00124b00226cad76:00124B00226CAD76_1_switch waiting for response to OFF
16:17:48.646 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 00124B00226CAD76: Channel zigbee:device:f3dc8e65fc:00124b00226cad76:00124B00226CAD76_1_switch received TIMEOUT in response to ON
16:17:48.652 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:f3dc8e65fc:00124b00226cad76' changed from ONLINE to OFFLINE                                                                                             16:17:58.361 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 00124B00226CAD76: Channel zigbee:device:f3dc8e65fc:00124b00226cad76:00124B00226CAD76_1_switch received TIMEOUT in response to OFF

I still can turn the physical switch ON and OFF, its just the issue with the remote control.

Thank you!

cheers

chris · March 18, 2022, 8:02pm

I’d need to see the full debug logs to confirm what’s happening, but it looks like the device is not communicating with the coordinator, and the commands are simply timing out.

Mehdi_Marzougui · March 22, 2022, 8:32am

Hey chris!

I might need a tip from you. I am a beginner in this, now that I have several devices on the network, the logs are full with messages from other devices that are not relevant to this issue, is there a way to filter that?

Also could you please let me know what level you want to see on each logger?

Right now with the three zigbee loggers on DEBUG its really hard to follow with anything

Thank you

Mehdi_Marzougui · March 22, 2022, 9:55am

Here are the logs @chris
Logs.txt (178.7 KB)
from the moment the device is initilized to the moment it is offline again, the logs were too much so i attached in a file

I hope these will help,

Thank you!

chris · March 23, 2022, 6:33am

What device address is this for? In general there’s very little data in the log - presumably the device goes offline as nothing is received.