Smartthings STS-IRM-250 Sensor - No temperature

I’m on openHAB 3.1.0.M4 and seeing the exact same behavior as described in the attached post. This device supports temperature when used in SmartThings.

I’m using the “Ember EM35x Coordinator” binding.

Any ideas?

Here is the DEBUG for the device … it does seem to report a Temperature capability but only “Illuminance” and Motion" are instantiated (and device doesn’t support Illuminance)

Hi @chris - Seems you may have knowledge about this device … do these logs help explain why the temperature capability is being missed?

2021-05-14 09:37:28.237 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Starting ZigBee device discovery
2021-05-14 09:37:28.240 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:7feb31b4e9
2021-05-14 09:37:28.242 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:device:7feb31b4e9:24fd5b0001038348' to inbox.
2021-05-14 09:37:28.248 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Node discovery not complete
2021-05-14 09:37:28.488 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 24FD5B0001038348: ZigBee saving network state complete.
2021-05-14 09:37:28.243 [INFO ] [openhab.event.InboxAddedEvent ] - Discovery Result with UID 'zigbee:device:7feb31b4e9:24fd5b0001038348' has been added.
2021-05-14 09:37:30.520 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Starting ZigBee device discovery
2021-05-14 09:37:30.521 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:7feb31b4e9
2021-05-14 09:37:30.523 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B0001038348: ZigBee node property discovery start
2021-05-14 09:37:30.525 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B0001038348: ZigBee node property discovery using basic cluster on endpoint A231/1
2021-05-14 09:37:30.810 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 24FD5B0001038348: ZigBee saving network state complete.
2021-05-14 09:37:32.261 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B0001038348: Hardware version failed
2021-05-14 09:37:32.769 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B0001038348: Stack version failed
2021-05-14 09:37:33.535 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B0001038348: Date code failed
2021-05-14 09:37:33.536 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B0001038348: ZigBee node property discovery using OTA cluster on endpoint A231/1
2021-05-14 09:37:33.766 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 24FD5B0001038348: ZigBee node property discovery complete: {zigbee_logicaltype=END_DEVICE, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x110a, modelId=motionv4, vendor=SmartThings, zigbee_networkaddress=41521, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], zigbee_powersource=MAINS, firmwareVersion=0x00000019, zigbee_zclversion=1, zigbee_applicationVersion=25}
2021-05-14 09:37:33.771 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:smartthings_motionv4:7feb31b4e9:24fd5b0001038348' to inbox.
2021-05-14 09:37:33.772 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Checking endpoint 1 channels
2021-05-14 09:37:33.770 [INFO ] [openhab.event.InboxAddedEvent ] - Discovery Result with UID 'zigbee:smartthings_motionv4:7feb31b4e9:24fd5b0001038348' has been added.
2021-05-14 09:37:34.286 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.290 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.292 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.293 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.519 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.521 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.523 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.525 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.526 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: IAS zone type STANDARD_CIE
2021-05-14 09:37:34.530 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Initializing channel zigbee:device:7feb31b4e9:24fd5b0001038348:24FD5B0001038348_1_system with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasCieSystem@6ffa0b
2021-05-14 09:37:34.531 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: Initialising device IAS Zone cluster for zigbee:ias_standard_system
2021-05-14 09:37:35.027 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Initializing channel zigbee:device:7feb31b4e9:24fd5b0001038348:24FD5B0001038348_1_temperature with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterTemperature@1b16e85
2021-05-14 09:37:35.773 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Initializing channel zigbee:device:7feb31b4e9:24fd5b0001038348:24FD5B0001038348_1_tamper with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasTamper@400544
2021-05-14 09:37:35.774 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: Initialising device IAS Zone cluster for zigbee:ias_tamper
2021-05-14 09:37:36.294 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Initializing channel zigbee:device:7feb31b4e9:24fd5b0001038348:24FD5B0001038348_1_batteryalarm with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryAlarm@104256
2021-05-14 09:37:36.295 [DEBUG] [onverter.ZigBeeConverterBatteryAlarm] - 24FD5B0001038348: Initialising device battery alarm converter
2021-05-14 09:37:37.280 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Initializing channel zigbee:device:7feb31b4e9:24fd5b0001038348:24FD5B0001038348_1_iaslowbattery with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasLowBattery@9ce606
2021-05-14 09:37:37.281 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B0001038348: Initialising device IAS Zone cluster for system:low-battery
2021-05-14 09:37:37.790 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Initializing channel zigbee:device:7feb31b4e9:24fd5b0001038348:24FD5B0001038348_1_batteryvoltage with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBatteryVoltage@f2d6e7
2021-05-14 09:37:37.791 [DEBUG] [verter.ZigBeeConverterBatteryVoltage] - 24FD5B0001038348: Initialising device battery voltage converter
2021-05-14 09:37:38.292 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B0001038348: Initializing channel zigbee:device:7feb31b4e9:24fd5b0001038348:24FD5B0001038348_1_binaryinput with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterBinaryInput@1f6a4a9
2021-05-14 09:37:38.293 [DEBUG] [converter.ZigBeeConverterBinaryInput] - 24FD5B0001038348: Initialising device binary input cluster
2021-05-14 09:37:38.799 [INFO ] [openhab.event.InboxRemovedEvent ] - Discovery Result with UID 'zigbee:device:7feb31b4e9:24fd5b0001038348' has been removed.
2021-05-14 09:37:39.086 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 24FD5B0001038348: ZigBee saving network state complete.

I have a very vague memory of some Smartthings device using a non-standard function for something, and I have a feeling it might be this. If it’s non-standard, then the binding won’t detect it since it only knows about the standards unless someone programs it differently.

The log however isn’t super useful since it misses a lot of useful data. There are some requests that say they failed - I’m not sure why - maybe the device was asleep and they timed out, or maybe the device rejected these requests as they aren’t supported (although that is strange as they as standard attributes that should be supported). Without the debug logs I can’t be sure, but if they timed out, then maybe the device is asleep, and it does support temperature, but the binding didn’t detect it as it was sleeping…

Sorry - that’s not especially useful. If you post the XML that the binding saves for this device I can take a look to see if it shows the temperature cluster. The XML is in the {userdata}/zigbee folder - there will be a subfolder for the network, then a file named after the device long address.

Hi Chris - Thanks for the quick response. Here is the XML … Thanks for helping, I have a bunch of these devices and all are behaving this way… they were working fine in a SmartThings setup previously. They have never worked in OH (meaning they only report motion and never temp)

24FD5B000103859E.xml (61.8 KB)

Actually I posted the other device that behaves the same … here is the one that matches the logs from above:

24FD5B0001038348.xml (62.1 KB)

Also, I turned on the Ember Debug to see low level data … it is indeed reporting temperature (I placed it in the freezer and watched it drop then took it out and it rose … reports every minute. I removed alot of repeating acks to make it smaller.

2021-05-14 16:15:17.690 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=7A 00 18]
2021-05-14 16:15:17.697 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=7A 80 18 02]
2021-05-14 16:15:17.699 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=2, reTx=false, data=7A 00 18]
2021-05-14 16:15:17.700 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
2021-05-14 16:15:18.085 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=7A 90 59 0F E6 9E 85 03 01 00 5B FD 24 FF B8 01 23 94]
2021-05-14 16:15:18.086 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=E60F, sourceEui=24FD5B000103859E, lastHopLqi=255, lastHopRssi=-72, relayList=9423]
2021-05-14 16:15:18.088 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
2021-05-14 16:15:18.399 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=7A 90 45 00 04 01 02 04 01 01 40 00 00 00 BF FF B8 0F E6 FF FF 08 18 13 0A 00 00 29 4B 0A 04]
2021-05-14 16:15:18.401 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0402, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=BF], lastHopLqi=255, lastHopRssi=-72, sender=E60F, bindingIndex=255, addressIndex=255, messageContents=18 13 0A 00 00 29 4B 0A]
2021-05-14 16:15:18.403 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=E60F/1, destinationAddress=0000/1, profile=0104, cluster=0402, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=BF, rssi=-72, lqi=FF, payload=18 13 0A 00 00 29 4B 0A]
2021-05-14 16:15:18.405 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=19, commandId=10]
2021-05-14 16:15:18.406 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=13, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=2635]]]
2021-05-14 16:15:18.408 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=13, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=2635]]] 
2021-05-14 16:15:18.410 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
2021-05-14 16:15:18.692 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
2021-05-14 16:15:18.693 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=5, reTx=false, data=7B 00 18]
2021-05-14 16:15:18.701 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=7B 80 18 02]

2021-05-14 16:28:17.667 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
2021-05-14 16:28:17.669 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=false, data=8A 00 18]
2021-05-14 16:28:17.677 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=8A 80 18 02]
2021-05-14 16:28:17.678 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=4, reTx=false, data=8A 00 18]
2021-05-14 16:28:17.679 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
2021-05-14 16:28:17.841 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=8A 90 59 0F E6 9E 85 03 01 00 5B FD 24 FF B7 01 23 94]
2021-05-14 16:28:17.842 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=E60F, sourceEui=24FD5B000103859E, lastHopLqi=255, lastHopRssi=-73, relayList=9423]
2021-05-14 16:28:17.843 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
2021-05-14 16:28:18.263 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=8A 90 45 00 04 01 02 04 01 01 40 00 00 00 DE FF B8 0F E6 FF FF 08 18 32 0A 00 00 29 70 04 04]
2021-05-14 16:28:18.264 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0402, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=DE], lastHopLqi=255, lastHopRssi=-72, sender=E60F, bindingIndex=255, addressIndex=255, messageContents=18 32 0A 00 00 29 70 04]
2021-05-14 16:28:18.265 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=E60F/1, destinationAddress=0000/1, profile=0104, cluster=0402, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=DE, rssi=-72, lqi=FF, payload=18 32 0A 00 00 29 70 04]
2021-05-14 16:28:18.266 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=50, commandId=10]
2021-05-14 16:28:18.267 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=32, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=1136]]]
2021-05-14 16:28:18.268 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=32, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=1136]]] 
2021-05-14 16:28:18.269 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
2021-05-14 16:28:18.670 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
xxx
Repeating acks ...
xxx
2021-05-14 16:29:16.798 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
2021-05-14 16:29:16.799 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=1, reTx=false, data=C5 00 18]
2021-05-14 16:29:16.805 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=C5 80 18 02]
2021-05-14 16:29:16.806 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=1, reTx=false, data=C5 00 18]
2021-05-14 16:29:16.807 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
2021-05-14 16:29:17.634 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=C5 90 59 0F E6 9E 85 03 01 00 5B FD 24 FF B7 01 23 94]
2021-05-14 16:29:17.635 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=E60F, sourceEui=24FD5B000103859E, lastHopLqi=255, lastHopRssi=-73, relayList=9423]
2021-05-14 16:29:17.636 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
2021-05-14 16:29:17.800 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
2021-05-14 16:29:17.801 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=C6 00 18]
2021-05-14 16:29:17.809 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=false, data=C6 80 18 02]
2021-05-14 16:29:17.810 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=3, reTx=false, data=C6 00 18]
2021-05-14 16:29:17.810 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
2021-05-14 16:29:17.901 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=C6 90 45 00 04 01 02 04 01 01 40 00 00 00 DF FF B7 0F E6 FF FF 08 18 33 0A 00 00 29 4F 04 04]
2021-05-14 16:29:17.902 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0402, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=DF], lastHopLqi=255, lastHopRssi=-73, sender=E60F, bindingIndex=255, addressIndex=255, messageContents=18 33 0A 00 00 29 4F 04]
2021-05-14 16:29:17.903 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=E60F/1, destinationAddress=0000/1, profile=0104, cluster=0402, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=DF, rssi=-73, lqi=FF, payload=18 33 0A 00 00 29 4F 04]
2021-05-14 16:29:17.904 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=51, commandId=10]
2021-05-14 16:29:17.905 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=33, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=1103]]]
2021-05-14 16:29:17.906 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=33, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=1103]]] 
2021-05-14 16:29:17.907 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
2021-05-14 16:29:18.803 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
2021-05-14 16:29:18.804 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=5, reTx=false, data=C7 00 18]
2021-05-14 16:29:18.811 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=0, reTx=false, data=C7 80 18 02]
2021-05-14 16:29:18.811 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=5, reTx=false, data=C7 00 18]
2021-05-14 16:29:18.812 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
2021-05-14 16:29:19.805 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
xxx
Repeating acks ...
xxx
-05-14 16:30:16.937 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
2021-05-14 16:30:16.938 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=7, reTx=false, data=01 00 18]
2021-05-14 16:30:16.945 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=01 80 18 02]
2021-05-14 16:30:16.946 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=7, reTx=false, data=01 00 18]
2021-05-14 16:30:16.947 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
2021-05-14 16:30:17.425 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=2, reTx=false, data=01 90 59 0F E6 9E 85 03 01 00 5B FD 24 FF B5 01 23 94]
2021-05-14 16:30:17.427 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=E60F, sourceEui=24FD5B000103859E, lastHopLqi=255, lastHopRssi=-75, relayList=9423]
2021-05-14 16:30:17.427 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
2021-05-14 16:30:17.793 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=01 90 45 00 04 01 02 04 01 01 40 00 00 00 E0 FF B6 0F E6 FF FF 08 18 34 0A 00 00 29 98 04 04]
2021-05-14 16:30:17.794 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0402, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=E0], lastHopLqi=255, lastHopRssi=-74, sender=E60F, bindingIndex=255, addressIndex=255, messageContents=18 34 0A 00 00 29 98 04]
2021-05-14 16:30:17.795 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=E60F/1, destinationAddress=0000/1, profile=0104, cluster=0402, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=E0, rssi=-74, lqi=FF, payload=18 34 0A 00 00 29 98 04]
2021-05-14 16:30:17.796 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=52, commandId=10]
2021-05-14 16:30:17.797 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=34, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=1176]]]
2021-05-14 16:30:17.798 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Temperature Measurement: E60F/1 -> 0000/1, cluster=0402, TID=34, reports=[AttributeReport [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, attributeValue=1176]]] 
2021-05-14 16:30:17.799 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
2021-05-14 16:30:17.939 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
2021-05-14 16:30:17.940 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=02 00 18]

My guess is that the binding was unable to get the information it needed during initialisation, so it therefore didn’t show this channel. That’s a guess as we don’t have logs, but we see failures in reading attributes from teh basic cluster so I suspect the device was probably asleep already and therefore uncontactable.

Thanks Chris - So to restate, you’re saying that in the initial polling / reading of the device, the temperature channel was not seen therefore it was never added. So the subsequent data from the device, showing the temperature, is ignored.

Let me collect the logs from the very first interactions after a device reset to see if that shows anything useful.

Hi Chris - I’ve uploaded the log file from when the Zigbee Scan is first started and the device is first seen. The only device on the network is the ST MotionV4 sensor.

ZigbeeScanningDebugLog.txt (404.0 KB)

Reading the log, from what I see, the Temperature capability is first advertised at:

2021-05-15 09:16:27.745 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 24FD5B000103859E: Endpoint 1. Setting server cluster 0402 Temperature Measurement

Then 5 seconds later, apparently it is Initializing the Temperature Channel:

2021-05-15 09:16:32.247 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B000103859E: Initializing channel zigbee:device:7feb31b4e9:24fd5b000103859e:24FD5B000103859E_1_temperature with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterTemperature@6a86f3

Then shortly later, there is a series of exchanges regarding Temperature:

2021-05-15 09:16:32.741 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B000103859E: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=24FD5B000103859E queueTime=0, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Temperature Measurement: 0000/0 -> BCC0/1, cluster=0402, TID=--, records=[AttributeReportingConfigurationRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200, reportableChange=0.1]]]]

2021-05-15 09:16:32.741 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding

2021-05-15 09:16:32.742 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=1/3

2021-05-15 09:16:32.742 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]

2021-05-15 09:16:32.743 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - 24FD5B000103859E: transactionComplete, state=COMPLETE, outstanding=0

2021-05-15 09:16:32.744 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3

2021-05-15 09:16:32.744 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=0, reTx=false, data=DF 90 3F 00 C0 BC 00 00 21 00 00 00 40 11 00 00 93 F0 00 00]

2021-05-15 09:16:32.744 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=BCC0, apsFrame=EmberApsFrame [profileId=0000, clusterId=0021, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=93], messageTag=F0, status=EMBER_SUCCESS, messageContents=]

2021-05-15 09:16:32.745 [DEBUG] [transaction.ZigBeeTransactionManager] - BCC0/1: Sending ZigBeeTransaction [ieeeAddress=24FD5B000103859E queueTime=4, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Temperature Measurement: 0000/0 -> BCC0/1, cluster=0402, TID=F1, records=[AttributeReportingConfigurationRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200, reportableChange=0.1]]]]

2021-05-15 09:16:32.745 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]

2021-05-15 09:16:32.745 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=F0 state=RX_ACK

2021-05-15 09:16:32.746 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding

2021-05-15 09:16:32.746 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=F0, state=RX_ACK, outstanding=1

2021-05-15 09:16:32.747 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ConfigureReportingCommand [Temperature Measurement: 0000/0 -> BCC0/1, cluster=0402, TID=F1, records=[AttributeReportingConfigurationRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200, reportableChange=0.1]]]

2021-05-15 09:16:32.748 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=241, commandId=6]

2021-05-15 09:16:32.749 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=BCC0/1, profile=0104, cluster=0402, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=8E, rssi=--, lqi=--, payload=00 F1 06 00 00 00 29 01 00 20 1C 00 00]

2021-05-15 09:16:32.750 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=BCC0, apsFrame=EmberApsFrame [profileId=0104, clusterId=0402, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=8E], messageTag=F1, messageContents=00 F1 06 00 00 00 29 01 00 20 1C 00 00]

2021-05-15 09:16:32.751 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1

2021-05-15 09:16:32.753 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=E0 00 34 00 C0 BC 04 01 02 04 01 01 40 11 00 00 8E F1 0D 00 F1 06 00 00 00 29 01 00 20 1C 00 00]

2021-05-15 09:16:32.766 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=E0 80 34 00 94]

2021-05-15 09:16:32.767 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=3, reTx=false, data=E0 00 34 00 C0 BC 04 01 02 04 01 01 40 11 00 00 8E F1 0D 00 F1 06 00 00 00 29 01 00 20 1C 00 00]

2021-05-15 09:16:32.767 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=94]

2021-05-15 09:16:32.767 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]

2021-05-15 09:16:32.993 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=false, data=E0 90 45 00 04 01 02 04 01 01 40 01 00 00 0A FF C8 C0 BC FF FF 04 18 F1 07 00]

2021-05-15 09:16:32.994 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0402, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0A], lastHopLqi=255, lastHopRssi=-56, sender=BCC0, bindingIndex=255, addressIndex=255, messageContents=18 F1 07 00]

2021-05-15 09:16:32.994 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=BCC0/1, destinationAddress=0000/1, profile=0104, cluster=0402, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=0A, rssi=-56, lqi=FF, payload=18 F1 07 00]

2021-05-15 09:16:32.995 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=241, commandId=7]

2021-05-15 09:16:32.996 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ConfigureReportingResponse [Temperature Measurement: BCC0/1 -> 0000/1, cluster=0402, TID=F1, status=SUCCESS, records=null]

2021-05-15 09:16:32.996 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ConfigureReportingResponse [Temperature Measurement: BCC0/1 -> 0000/1, cluster=0402, TID=F1, status=SUCCESS, records=null]

2021-05-15 09:16:32.997 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]

2021-05-15 09:16:33.001 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 24FD5B000103859E: Initializing channel zigbee:device:7feb31b4e9:24fd5b000103859e:24FD5B000103859E_1_tamper with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasTamper@89feac

2021-05-15 09:16:33.002 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=24FD5B000103859E queueTime=262, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [Temperature Measurement: 0000/0 -> BCC0/1, cluster=0402, TID=F1, records=[AttributeReportingConfigurationRecord [attributeDataType=SIGNED_16_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200, reportableChange=0.1]]]]

Then finally, about 30 seconds later, (and 'm guessing this may be the reason for the behavior), it uses a “Static Definition with Existing 2 channels” … and then it adds Illuminance and Motion.

**2021-05-15 09:17:03.221 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B000103859E: Using static definition with existing 2 channels**

2021-05-15 09:17:03.227 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B000103859E: Initializing device

2021-05-15 09:17:03.228 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B000103859E: Initializing channel zigbee:smartthings_motionv4:SN1355:illuminance with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIlluminance@1be8ec4

2021-05-15 09:17:03.228 [ERROR] [converter.ZigBeeConverterIlluminance] - 24FD5B000103859E: Error opening device illuminance measurement cluster

2021-05-15 09:17:03.229 [INFO ] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B000103859E: Channel zigbee:smartthings_motionv4:SN1355:illuminance failed to initialise device

2021-05-15 09:17:03.230 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 24FD5B000103859E: Initializing channel zigbee:smartthings_motionv4:SN1355:motion with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterIasMotionIntrusion@1811eeb

2021-05-15 09:17:03.230 [DEBUG] [nternal.converter.ZigBeeConverterIas] - 24FD5B000103859E: Initialising device IAS Zone cluster for zigbee:ias_motionintrusion

Can you tell from the log that the device is asleep when it is being initialized? It seems that it should be seeing the advertised “Temperature”.

Is there somewhere I can see where the “Static Definition” is defined? Alternatively, can I manually add this device with my own static definition that would include temperature?

Thanks for helping me work through this!

James

I know I’m poking in the dark here … but the github file “motionv4.xml”:

Seems to show that it has only two channels: Illuminance and Motion … I believe that Illuminance should simply be changed to Temperature … no?

I do not use Zigbee but the log viewer may help you.

1 Like

Thanks Bruce thats an excellent resource and helps decode the logs.

I am really curious about that motion4.xml file. I don’t really know this all fits together, but in following the code and comparing it to the logs, it sure seems likely/possible that this is the reason that temperature is not working and why Illuminance is added as a channel.

Hoping Chris or someone with direct knowledge of the zigbee binding can confirm.

Just wanted to bump up this post to see if anyone had any insight … to summarize, I believe the device definition is using:

a “Static Definition with Existing 2 channels” … and then it adds Illuminance and Motion."

Seems like this could be changed to Temperature and Motion and it could be just a mistake in the file definition.

James