Ubisys S2: Reporting of only one channel / OTA fails

theFork commented 4 years ago

One of my Ubisys S2 devices only reports the first of two channels. As you can see in the log, only state_l1 is mentioned.

Prior to this, the OTA update failed.

Here is what happened:

{"zigbee2mqtt:info  2020-03-22 09:39:28: Device '0x001fee00000017c6' joined
zigbee2mqtt:info  2020-03-22 09:39:28: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x001fee00000017c6"}}'
zigbee2mqtt:info  2020-03-22 09:39:28: Starting interview of '0x001fee00000017c6'
zigbee2mqtt:info  2020-03-22 09:39:28: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x001fee00000017c6"}}'
zigbee2mqtt:info  2020-03-22 09:39:30: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_announced","message":"announce","meta":{"friendly_name":"0x001fee00000017c6"}}'
zigbee2mqtt:info  2020-03-22 09:39:38: MQTT publish: topic 'zigbee2mqtt/eg_wohnzimmer_hinten_stimmungslicht', payload '{"state":"OFF","linkquality":42,"color_temp":454,"brightness":222}'
zigbee2mqtt:info  2020-03-22 09:39:40: Successfully setup reporting for '0x001fee00000017c6' - 1 - genOnOff
zigbee2mqtt:info  2020-03-22 09:39:41: Successfully interviewed '0x001fee00000017c6', device has successfully been paired
zigbee2mqtt:info  2020-03-22 09:39:41: Device '0x001fee00000017c6' is supported, identified as: Ubisys Power switch S2 (S2)
zigbee2mqtt:info  2020-03-22 09:39:41: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_successful","meta":{"friendly_name":"0x001fee00000017c6","model":"S2","vendor":"Ubisys","description":"Power switch S2","supported":true}}'
zigbee2mqtt:info  2020-03-22 09:39:41: Configuring '0x001fee00000017c6'
zigbee2mqtt:info  2020-03-22 09:39:41: Successfully configured '0x001fee00000017c6'
zigbee2mqtt:info  2020-03-22 09:39:41: MQTT publish: topic 'zigbee2mqtt/0x001fee00000017c6', payload '{"state_l1":"OFF","linkquality":28}'
zigbee2mqtt:info  2020-03-22 09:39:43: MQTT publish: topic 'zigbee2mqtt/0x001fee00000017c6', payload '{"state_l1":"OFF","linkquality":31,"power":0}'
zigbee2mqtt:error 2020-03-22 09:40:06: Failed to call 'OTAUpdate' 'onZigbeeEvent' (AssertionError [ERR_ASSERTION]: No image available for imageType '0x7b03' with hardware version 5
    at getImageMeta (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman-converters/ota/ubisys.js:22:5)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:94:5)
    at async isNewImageAvailable (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman-converters/ota/ubisys.js:49:18)
    at async Object.isUpdateAvailable (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman-converters/ota/common.js:159:23)
    at async OTAUpdate.onZigbeeEvent (/zigbee2mqtt-1.12.0/lib/extension/otaUpdate.js:33:31)
    at async Controller.callExtensionMethod (/zigbee2mqtt-1.12.0/lib/controller.js:346:21))

Edit: This is only affecting reporting. I can switch both channels via MQTT.

Koenkk commented 4 years ago

Can you share your database.db?

theFork commented 4 years ago

Of course:

{"id":1,"type":"Coordinator","ieeeAddr":"0x00124b001938aa2d","nwkAddr":0,"manufId":0,"epList":[12,110,11,8,6,5,4,3,2,1],"endpoints":{"1":{"profId":260,"epId":1,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"2":{"profId":257,"epId":2,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"3":{"profId":261,"epId":3,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"4":{"profId":263,"epId":4,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"5":{"profId":264,"epId":5,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"6":{"profId":265,"epId":6,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"8":{"profId":260,"epId":8,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"11":{"profId":260,"epId":11,"devId":1024,"inClusterList":[],"outClusterList":[1280],"clusters":{},"binds":[]},"12":{"profId":49246,"epId":12,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"110":{"profId":260,"epId":110,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]}},"interviewCompleted":false,"meta":{},"lastSeen":null}
{"id":2,"type":"Router","ieeeAddr":"0x001fee00000030b7","nwkAddr":11735,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,3,4,5,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20180517-DE-FB0","swBuildId":""}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20180517-DE-FB0","swBuildId":"","zclVersion":2,"interviewCompleted":true,"meta":{"configured":3,"reporting":1},"lastSeen":1584264733084}
{"id":3,"type":"Router","ieeeAddr":"0x001fee0000002cad","nwkAddr":31645,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"J1 (5502)","epList":[1,2,3,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":514,"inClusterList":[0,3,4,5,258],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"J1 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20180920-DE-FB0"}},"closuresWindowCovering":{"attributes":{"currentPositionLiftPercentage":255,"currentPositionTiltPercentage":255}}},"binds":[{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{},"binds":[]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20180920-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1581111523411}
{"id":4,"type":"Router","ieeeAddr":"0x001fee0000001815","nwkAddr":48359,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,3,4,5,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"configured":3,"reporting":1},"lastSeen":1581011852803}
{"id":5,"type":"Router","ieeeAddr":"0x001fee0000002c65","nwkAddr":44273,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"J1 (5502)","epList":[1,2,3,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":514,"inClusterList":[0,3,4,5,258],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"J1 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20180920-DE-FB0"}},"closuresWindowCovering":{"attributes":{"currentPositionLiftPercentage":22,"currentPositionTiltPercentage":255}}},"binds":[{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{},"binds":[]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20180920-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1581011849640}
{"id":6,"type":"Router","ieeeAddr":"0x001fee0000001850","nwkAddr":14156,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,3,4,5,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20180509-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20180509-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"configured":3,"reporting":1},"lastSeen":1584302018178}
{"id":7,"type":"Router","ieeeAddr":"0x001fee00000058bf","nwkAddr":24681,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,3,4,5,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20191127-DE-FB0","swBuildId":""}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20191127-DE-FB0","swBuildId":"","zclVersion":2,"interviewCompleted":true,"meta":{"configured":3,"reporting":1},"lastSeen":1581011853514}
{"id":8,"type":"Router","ieeeAddr":"0x001fee0000005747","nwkAddr":23127,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,3,4,5,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"zclVersion":2,"interviewCompleted":false,"meta":{"configured":3,"reporting":1},"lastSeen":1581011853894}
{"id":9,"type":"Router","ieeeAddr":"0x001fee000000210f","nwkAddr":48084,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S1 (5501)","epList":[1,2,3,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":9,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S1 (5501)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20161129-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20161129-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1583228993886}
{"id":10,"type":"Router","ieeeAddr":"0x001fee000000182d","nwkAddr":13089,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1581097831071}
{"id":11,"type":"Router","ieeeAddr":"0x001fee00000039c6","nwkAddr":21183,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"C4 (5504)","epList":[1,2,3,4,5,6,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{"genBasic":{"attributes":{"modelId":"C4 (5504)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":3,"dateCode":"20190211-DE-FB0"}}},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x000b57fffe99aa9f","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x000b57fffe99aa9f","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x000b57fffe99aa9f","endpointID":1}]},"5":{"profId":260,"epId":5,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"6":{"profId":260,"epId":6,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":97,"inClusterList":[],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":3,"dateCode":"20190211-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"configured":1,"reporting":1},"lastSeen":1583084707276}
{"id":12,"ieeeAddr":"0x001fee000000181e","nwkAddr":64139,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170717-DE-FB0"}}},"binds":[]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170717-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"configured":3},"lastSeen":1581108383531}
{"id":13,"type":"Router","ieeeAddr":"0x001fee0000001a7f","nwkAddr":25329,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S1 (5501)","epList":[1,2,200,232,242,3],"endpoints":{"1":{"profId":260,"epId":1,"devId":9,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S1 (5501)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20170522-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20170522-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1581108383689}
{"id":14,"type":"Router","ieeeAddr":"0x001fee000000181e","nwkAddr":42235,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170717-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170717-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1581180853669}
{"id":15,"type":"Router","ieeeAddr":"0x00178801041b455f","nwkAddr":62234,"manufId":4107,"manufName":"Philips","powerSource":"Mains (single phase)","modelId":"LCT012","epList":[11,242],"endpoints":{"11":{"profId":49246,"epId":11,"devId":528,"inClusterList":[0,3,4,5,6,8,768,4096,64513],"outClusterList":[25],"clusters":{"genBasic":{"attributes":{"modelId":"LCT012","manufacturerName":"Philips","powerSource":1,"zclVersion":1,"appVersion":2,"stackVersion":1,"hwVersion":1,"dateCode":"20170908","swBuildId":"1.29.0_r21169"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":768,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"242":{"profId":41440,"epId":242,"devId":97,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":2,"stackVersion":1,"hwVersion":1,"dateCode":"20170908","swBuildId":"1.29.0_r21169","zclVersion":1,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1581277404007}
{"id":16,"type":"Router","ieeeAddr":"0x00158d00038f2a98","nwkAddr":65402,"manufId":4454,"manufName":"innr","powerSource":"Mains (single phase)","modelId":"RB 248 T","epList":[1,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":268,"inClusterList":[0,3,4,5,6,8,768,4096],"outClusterList":[25],"clusters":{"genBasic":{"attributes":{"modelId":"RB 248 T","manufacturerName":"innr","powerSource":1,"zclVersion":2,"appVersion":16,"stackVersion":21,"hwVersion":1,"dateCode":"20180810-1","swBuildId":"2.0"}},"genOnOff":{"attributes":{"onOff":0}},"lightingColorCtrl":{"attributes":{"colorTemperature":454}},"genLevelCtrl":{"attributes":{"currentLevel":120}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":768,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"242":{"profId":41440,"epId":242,"devId":97,"inClusterList":[],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":16,"stackVersion":21,"hwVersion":1,"dateCode":"20180810-1","swBuildId":"2.0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1582825390574}
{"id":17,"type":"Router","ieeeAddr":"0x00158d00038f2a93","nwkAddr":55680,"manufId":4454,"manufName":"innr","powerSource":"Mains (single phase)","modelId":"RB 248 T","epList":[1,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":268,"inClusterList":[0,3,4,5,6,8,768,4096],"outClusterList":[25],"clusters":{"genBasic":{"attributes":{"modelId":"RB 248 T","manufacturerName":"innr","powerSource":1,"zclVersion":2,"appVersion":16,"stackVersion":21,"hwVersion":1,"dateCode":"20180810-1","swBuildId":"2.0"}},"genOnOff":{"attributes":{"onOff":0}},"lightingColorCtrl":{"attributes":{"colorTemperature":454}},"genLevelCtrl":{"attributes":{"currentLevel":222}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":768,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"242":{"profId":41440,"epId":242,"devId":97,"inClusterList":[],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":16,"stackVersion":21,"hwVersion":1,"dateCode":"20180810-1","swBuildId":"2.0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1582825746121}
{"id":18,"type":"Router","ieeeAddr":"0xd0cf5efffe72e953","nwkAddr":6178,"manufId":4476,"manufName":"IKEA of Sweden","powerSource":"Mains (single phase)","modelId":"TRADFRI bulb E14 W op/ch 400lm","epList":[1],"endpoints":{"1":{"profId":49246,"epId":1,"devId":256,"inClusterList":[0,3,4,5,6,8,2821,4096],"outClusterList":[5,25,32,4096],"clusters":{"genBasic":{"attributes":{"modelId":"TRADFRI bulb E14 W op/ch 400lm","manufacturerName":"IKEA of Sweden","powerSource":1,"zclVersion":1,"appVersion":17,"stackVersion":87,"hwVersion":1,"dateCode":"20170302","swBuildId":"1.2.214"}},"genOnOff":{"attributes":{"onOff":0}},"genLevelCtrl":{"attributes":{"currentLevel":136}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]}},"appVersion":17,"stackVersion":87,"hwVersion":1,"dateCode":"20170302","swBuildId":"1.2.214","zclVersion":1,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1582826694387}
{"id":19,"type":"Router","ieeeAddr":"0x000b57fffe99aa9f","nwkAddr":35503,"manufId":4476,"manufName":"IKEA of Sweden","powerSource":"Mains (single phase)","modelId":"TRADFRI bulb E14 W op/ch 400lm","epList":[1],"endpoints":{"1":{"profId":49246,"epId":1,"devId":256,"inClusterList":[0,3,4,5,6,8,2821,4096],"outClusterList":[5,25,32,4096],"clusters":{"genBasic":{"attributes":{"modelId":"TRADFRI bulb E14 W op/ch 400lm","manufacturerName":"IKEA of Sweden","powerSource":1,"zclVersion":1,"appVersion":17,"stackVersion":87,"hwVersion":1,"dateCode":"20170302","swBuildId":"1.2.214"}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]}},"appVersion":17,"stackVersion":87,"hwVersion":1,"dateCode":"20170302","swBuildId":"1.2.214","zclVersion":1,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1583084290547}
{"id":20,"type":"Router","ieeeAddr":"0x00158d0002d4a91c","nwkAddr":19668,"manufId":4454,"manufName":"innr","powerSource":"Mains (single phase)","modelId":"RB 285 C","epList":[1,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":269,"inClusterList":[0,3,4,5,6,8,768,4096],"outClusterList":[25],"clusters":{"genBasic":{"attributes":{"modelId":"RB 285 C","manufacturerName":"innr","powerSource":1,"zclVersion":2,"appVersion":16,"stackVersion":21,"hwVersion":1,"dateCode":"20180813-1","swBuildId":"2.0"}},"genLevelCtrl":{"attributes":{"currentLevel":149}},"genOnOff":{"attributes":{"onOff":0}},"lightingColorCtrl":{"attributes":{"currentY":26130,"currentX":27773,"colorTemperature":313}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":768,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"242":{"profId":41440,"epId":242,"devId":97,"inClusterList":[],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":16,"stackVersion":21,"hwVersion":1,"dateCode":"20180813-1","swBuildId":"2.0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1584866131448}
{"id":21,"type":"Router","ieeeAddr":"0x001fee000000176d","nwkAddr":1233,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1584865466072}
{"id":22,"type":"Router","ieeeAddr":"0x001fee0000002a44","nwkAddr":39529,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"C4 (5504)","epList":[1,2,3,4,5,6,200,232,242],"endpoints":{"1":{"profId":260,"epId":1,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{"genBasic":{"attributes":{"modelId":"C4 (5504)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":3,"dateCode":"20180921-DE-FB0"}}},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00158d0002d4a91c","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00158d0002d4a91c","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00158d0002d4a91c","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x001fee000000176d","endpointID":2},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x001fee000000176d","endpointID":2}]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x001fee000000176d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x001fee000000176d","endpointID":1}]},"5":{"profId":260,"epId":5,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"6":{"profId":260,"epId":6,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":97,"inClusterList":[],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":3,"dateCode":"20180921-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":1},"lastSeen":1584868568176}
{"id":23,"type":"Router","ieeeAddr":"0x001fee00000017c6","nwkAddr":33022,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170214-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170214-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1584866669233}
{"id":24,"type":"Router","ieeeAddr":"0x001fee0000003bc1","nwkAddr":4485,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"C4 (5504)","epList":[1,2,200,232,242,3,4,5,6],"endpoints":{"1":{"profId":260,"epId":1,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{"genBasic":{"attributes":{"modelId":"C4 (5504)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":3,"dateCode":"20190211-DE-FB0"}}},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":8,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"5":{"profId":260,"epId":5,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"6":{"profId":260,"epId":6,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[{"cluster":5,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1},{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":97,"inClusterList":[],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":3,"dateCode":"20190211-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":1},"lastSeen":1584884327108}
{"id":25,"type":"Router","ieeeAddr":"0x001fee0000002c7d","nwkAddr":28872,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"J1 (5502)","epList":[1,2,200,232,242,3],"endpoints":{"1":{"profId":260,"epId":1,"devId":514,"inClusterList":[0,3,4,5,258],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"J1 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20180920-DE-FB0"}},"closuresWindowCovering":{"attributes":{"currentPositionLiftPercentage":255,"currentPositionTiltPercentage":255}}},"binds":[{"cluster":258,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":515,"inClusterList":[0,3],"outClusterList":[5,258],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{},"binds":[]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":6,"dateCode":"20180920-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1},"lastSeen":1584884792906}
{"id":26,"type":"Router","ieeeAddr":"0x001fee00000017cd","nwkAddr":62438,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0"}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1584885001104}

One remark that might be helpful: Some S2 devices report only one channel whereas others report both:

Only one channel:

0x001fee000000176d
0x001fee00000017c6

Both channels reported:

0x001fee0000001815
0x001fee000000181e

Maybe a hint: Both devices showing the issue have been renamed, both devices that work as expected still have their default name (IEEE-Address).

Koenkk commented 4 years ago

Please try with the following definition in devices.js (see https://www.zigbee2mqtt.io/how_tos/how_to_support_new_devices.html)

{
    zigbeeModel: ['S2 (5502)', 'S2-R (5602)'],
    model: 'S2',
    vendor: 'Ubisys',
    description: 'Power switch S2',
    supports: 'on/off, power measurement',
    fromZigbee: [fz.on_off, fz.metering_power],
    toZigbee: [tz.on_off, tz.ubisys_device_setup],
    endpoint: (device) => {
        return {'l1': 1, 'l2': 2};
    },
    meta: {configureKey: 4, multiEndpoint: true},
    configure: async (device, coordinatorEndpoint) => {
        const endpoint1 = device.getEndpoint(1);
        await bind(endpoint1, coordinatorEndpoint, ['genOnOff']);
        await configureReporting.onOff(endpoint1);

        const endpoint2 = device.getEndpoint(2);
        await bind(endpoint2, coordinatorEndpoint, ['seMetering']);
        await readMeteringPowerConverterAttributes(endpoint2);
        await configureReporting.instantaneousDemand(endpoint2);

        const endpoint5 = device.getEndpoint(5);
        await bind(endpoint5, coordinatorEndpoint, ['seMetering']);
        await readMeteringPowerConverterAttributes(endpoint5);
        await configureReporting.instantaneousDemand(endpoint5);
    },
    ota: ota.ubisys,
},

theFork commented 4 years ago

I've just changed the devices.js file and restarted the container (I'm on hassio). The reporting issue is still there. I see some errors in the log, that make me wonder if I also have to make modifications to other files. Should I test this on the development branch? Currently I have 1.12.0 installed. Here is the tail of the log:

zigbee2mqtt:info  2020-03-27 18:38:16: MQTT publish: topic 'zigbee2mqtt/0x001fee0000002c65', payload '{"position":100,"linkquality":13,"tilt":null,"update_available":false}'
zigbee2mqtt:error 2020-03-27 18:38:17: Failed to execute LQI for '0x001fee0000005747'
zigbee2mqtt:error 2020-03-27 18:38:23: Failed to configure '0x001fee00000058bf', attempt 1 (Error: Read 0x001fee00000058bf/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 24681 - 2 - 11 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:38:23: Configuring '0x001fee000000182d'
zigbee2mqtt:info  2020-03-27 18:38:24: MQTT publish: topic 'zigbee2mqtt/0x001fee0000005747', payload '{"power":0,"linkquality":55,"state_l2":"OFF","state_l1":"OFF"}'
zigbee2mqtt:error 2020-03-27 18:38:29: Failed to execute LQI for '0x001fee000000210f'
zigbee2mqtt:info  2020-03-27 18:38:32: MQTT publish: topic 'zigbee2mqtt/0x001fee00000058bf', payload '{"power":0,"linkquality":13,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:32: Configuring '0x001fee00000058bf'
zigbee2mqtt:error 2020-03-27 18:38:33: Failed to configure '0x001fee000000182d', attempt 1 (Error: Bind 0x001fee000000182d/1 genOnOff from '0x00124b001938aa2d/1' failed (Error: AREQ - ZDO - bindRsp after 10000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:235:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:38:33: Configuring '0x001fee000000181e'
zigbee2mqtt:info  2020-03-27 18:38:36: MQTT publish: topic 'zigbee2mqtt/0x001fee000000182d', payload '{"state_l1":"OFF","linkquality":13,"state_l2":"OFF","power":0,"update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:36: Configuring '0x001fee000000182d'
zigbee2mqtt:info  2020-03-27 18:38:42: MQTT publish: topic 'zigbee2mqtt/0x001fee000000182d', payload '{"state_l1":"OFF","linkquality":13,"state_l2":"OFF","power":0,"update_available":false}'
zigbee2mqtt:error 2020-03-27 18:38:44: Failed to configure '0x001fee0000005747', attempt 1 (Error: Read 0x001fee0000005747/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 23127 - 2 - 13 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:38:46: MQTT publish: topic 'zigbee2mqtt/0x001fee00000058bf', payload '{"power":0,"linkquality":57,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:error 2020-03-27 18:38:46: Failed to execute LQI for 'eg_flur_c4'
zigbee2mqtt:info  2020-03-27 18:38:47: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":0,"linkquality":13,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:47: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":0,"linkquality":10,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:48: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":25,"linkquality":7,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:49: MQTT publish: topic 'zigbee2mqtt/0x001fee000000182d', payload '{"state_l1":"OFF","linkquality":57,"state_l2":"OFF","power":0,"update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:50: MQTT publish: topic 'zigbee2mqtt/0x001fee0000002cad', payload '{"position":null,"linkquality":57,"tilt":null,"update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:51: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":24,"linkquality":55,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:51: MQTT publish: topic 'zigbee2mqtt/0x001fee0000002cad', payload '{"position":null,"linkquality":57,"tilt":null,"update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:52: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":25,"linkquality":55,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:53: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":24,"linkquality":55,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:56: MQTT publish: topic 'zigbee2mqtt/og_kinderzimmer1_s2', payload '{"state_l1":"OFF","linkquality":5,"power":0,"state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:56: Configuring 'og_kinderzimmer1_s2'
zigbee2mqtt:info  2020-03-27 18:38:56: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":25,"linkquality":55,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:38:57: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":24,"linkquality":55,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:error 2020-03-27 18:38:57: Failed to execute LQI for '0x001fee0000001a7f'
zigbee2mqtt:info  2020-03-27 18:39:00: MQTT publish: topic 'zigbee2mqtt/eg_kueche_stimmungslicht', payload '{"state":"OFF","linkquality":55,"color_temp":454,"brightness":120}'
zigbee2mqtt:error 2020-03-27 18:39:03: Failed to configure '0x001fee00000058bf', attempt 2 (Error: Read 0x001fee00000058bf/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 24681 - 2 - 18 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:39:06: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"ON","linkquality":55,"power":0,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-27 18:39:06: Configuring 'eg_kueche_esszimmer_s2'
zigbee2mqtt:info  2020-03-27 18:39:09: MQTT publish: topic 'zigbee2mqtt/eg_bad_stimmungslicht', payload '{"state":"OFF","linkquality":57,"brightness":166,"color_temp":447,"color":{"x":0.502600996314909,"y":0.415266341542373}}'
zigbee2mqtt:info  2020-03-27 18:39:11: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001a7f', payload '{"state":"OFF","linkquality":57,"power":0,"update_available":false}'
zigbee2mqtt:error 2020-03-27 18:39:12: Failed to configure '0x001fee000000181e', attempt 2 (Error: Read 0x001fee000000181e/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 42235 - 2 - 19 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:39:12: Configuring 'eg_wohnzimmer_s2'
zigbee2mqtt:info  2020-03-27 18:39:13: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":24,"linkquality":55,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:39:13: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":24,"linkquality":52,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:39:13: Configuring '0x001fee000000181e'
zigbee2mqtt:info  2020-03-27 18:39:13: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":24,"linkquality":52,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:39:13: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":0,"linkquality":55,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:39:14: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":0,"linkquality":55,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-03-27 18:39:15: MQTT publish: topic 'zigbee2mqtt/og_kinderzimmer1_s2', payload '{"state_l1":"OFF","linkquality":55,"power":0,"state_l2":"OFF","update_available":false}'
zigbee2mqtt:error 2020-03-27 18:39:19: Failed to configure '0x001fee000000182d', attempt 2 (Error: Read 0x001fee000000182d/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 13089 - 2 - 23 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:39:23: MQTT publish: topic 'zigbee2mqtt/eg_wohnzimmer_s2', payload '{"state_l1":"OFF","linkquality":57,"power":0,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-27 18:39:23: MQTT publish: topic 'zigbee2mqtt/0x001fee000000181e', payload '{"power":0,"linkquality":55,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:error 2020-03-27 18:39:24: Failed to configure 'eg_kueche_esszimmer_s2', attempt 1 (Error: Bind 0x001fee00000017c6/1 genOnOff from '0x00124b001938aa2d/1' failed (Error: AREQ - ZDO - bindRsp after 10000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:235:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:39:25: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"ON","linkquality":55,"power":26,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-27 18:39:25: Configuring 'eg_kueche_esszimmer_s2'
zigbee2mqtt:info  2020-03-27 18:39:25: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"OFF","linkquality":55,"power":26,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-27 18:39:25: MQTT publish: topic 'zigbee2mqtt/eg_esszimmer_stimmungslicht', payload '{"brightness":139,"linkquality":55,"state":"OFF","color_temp":440,"color":{"x":0.5016,"y":0.4137}}'
zigbee2mqtt:info  2020-03-27 18:39:25: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"OFF","linkquality":57,"power":19,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-27 18:39:26: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"ON","linkquality":55,"power":19,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-27 18:39:26: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"ON","linkquality":55,"power":26,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-27 18:39:27: MQTT publish: topic 'zigbee2mqtt/eg_esszimmer_stimmungslicht', payload '{"brightness":139,"linkquality":55,"state":"OFF","color_temp":440,"color":{"x":0.5016,"y":0.4137}}'
zigbee2mqtt:info  2020-03-27 18:39:31: MQTT publish: topic 'zigbee2mqtt/eg_wohnzimmer_hinten_stimmungslicht', payload '{"state":"OFF","linkquality":55,"color_temp":454,"brightness":116}'
zigbee2mqtt:error 2020-03-27 18:39:32: Failed to execute LQI for 'eg_kueche_stimmungslicht'
zigbee2mqtt:info  2020-03-27 18:39:33: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"ON","linkquality":55,"power":26,"update_available":false,"state_l2":"ON"}'
zigbee2mqtt:error 2020-03-27 18:39:39: Failed to configure 'og_kinderzimmer1_s2', attempt 1 (Error: Read 0x001fee00000017cd/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 62438 - 2 - 27 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:39:39: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"ON","linkquality":7,"power":6,"update_available":false,"state_l2":"ON"}'
zigbee2mqtt:error 2020-03-27 18:39:50: Failed to configure 'eg_wohnzimmer_s2', attempt 1 (Error: Read 0x001fee000000176d/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 1233 - 2 - 29 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))
zigbee2mqtt:info  2020-03-27 18:39:50: Configuring 'og_kinderzimmer1_s2'
zigbee2mqtt:error 2020-03-27 18:39:54: Failed to configure '0x001fee000000181e', attempt 3 (Error: Read 0x001fee000000181e/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 42235 - 2 - 30 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))

Koenkk commented 4 years ago

That's probably not the issue, the device just doesn't respond. Can you try repairing it?

theFork commented 4 years ago

Ok, thanks for the quick reply. I will try this with 0x001fee000000181e tomorrow. I'm too afraid to tinker around with our lighting when my baby is already sleeping ;)

theFork commented 4 years ago

Ok, so here is what I tested:

Configure homeassistant: false and restart Z2M
Test reporting behavior of 0x001fee000000181e: This is still reporting both channels. My bad I have to check 0x001fee000000176d or 0x001fee00000017c6. Continuing with 0x001fee00000017c6 AKA eg_kueche_esszimmer_s2.
For some reason, the device does now report both channels via MQTT:
```
zigbee2mqtt:info  2020-03-29 17:08:45: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"OFF","linkquality":15,"power":0,"update_available":false,"state_l2":"OFF"}'
```
The issue is now, that the second channel is always reporting to be OFF. For instance, when toggling CH2, the device always sends reporting data, but only the power changes whereas the state remains OFF:

zigbee2mqtt:info  2020-03-29 17:22:45: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"OFF","linkquality":5,"power":8,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-29 17:22:46: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"OFF","linkquality":5,"power":0,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-29 17:22:48: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"OFF","linkquality":5,"power":17,"update_available":false,"state_l2":"OFF"}'
zigbee2mqtt:info  2020-03-29 17:22:49: MQTT publish: topic 'zigbee2mqtt/eg_kueche_esszimmer_s2', payload '{"state_l1":"OFF","linkquality":2,"power":0,"update_available":false,"state_l2":"OFF"}'

Remove device

zigbee2mqtt:info  2020-03-29 17:26:12: Removing 'eg_kueche_esszimmer_s2'
zigbee2mqtt:info  2020-03-29 17:26:13: Successfully removed eg_kueche_esszimmer_s2
zigbee2mqtt:info  2020-03-29 17:26:13: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_removed","message":"eg_kueche_esszimmer_s2"}'

Install patched devices.js and restart container.

Open for joining and reset device. It joins:

zigbee2mqtt:info  2020-03-29 17:33:43: Device '0x001fee00000017c6' joined
zigbee2mqtt:info  2020-03-29 17:33:43: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x001fee00000017c6"}}'
zigbee2mqtt:info  2020-03-29 17:33:43: Starting interview of '0x001fee00000017c6'
zigbee2mqtt:info  2020-03-29 17:33:43: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x001fee00000017c6"}}'
zigbee2mqtt:info  2020-03-29 17:33:44: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_announced","message":"announce","meta":{"friendly_name":"0x001fee00000017c6"}}'
zigbee2mqtt:info  2020-03-29 17:33:45: Successfully setup reporting for '0x001fee00000017c6' - 1 - genOnOff
zigbee2mqtt:info  2020-03-29 17:33:46: Successfully interviewed '0x001fee00000017c6', device has successfully been paired
zigbee2mqtt:info  2020-03-29 17:33:46: Device '0x001fee00000017c6' is supported, identified as: Ubisys Power switch S2 (S2)
zigbee2mqtt:info  2020-03-29 17:33:46: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_successful","meta":{"friendly_name":"0x001fee00000017c6","model":"S2","vendor":"Ubisys","description":"Power switch S2","supported":true}}'
zigbee2mqtt:info  2020-03-29 17:33:46: Configuring '0x001fee00000017c6'
zigbee2mqtt:info  2020-03-29 17:33:47: MQTT publish: topic 'zigbee2mqtt/0x001fee00000017c6', payload '{"state_l1":"OFF","linkquality":18}'

But unfortunetely, the issue remains. Now, the device does only report the state of CH1 (correctly) but does not send data regarding CH2 at all:

zigbee2mqtt:info  2020-03-29 17:36:27: MQTT publish: topic 'zigbee2mqtt/0x001fee00000017c6', payload '{"state_l1":"ON","linkquality":5,"update_available":false}'
zigbee2mqtt:info  2020-03-29 17:36:27: Configuring '0x001fee00000017c6'
zigbee2mqtt:info  2020-03-29 17:36:28: MQTT publish: topic 'zigbee2mqtt/0x001fee00000017c6', payload '{"state_l1":"OFF","linkquality":18,"update_available":false}'
zigbee2mqtt:error 2020-03-29 17:36:43: Failed to configure '0x001fee00000017c6', attempt 3 (Error: Read 0x001fee00000017c6/2 seMetering(["multiplier","divisor"], {"timeout":6000,"manufacturerCode":null,"disableDefaultResponse":true}) failed (Error: Timeout - 42913 - 2 - 52 - 1794 - 1 after 6000ms)
    at Endpoint.<anonymous> (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:173:23)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.12.0/node_modules/zigbee-herdsman/dist/controller/model/endpoint.js:6:65))

theFork commented 4 years ago

Just to be sure, that there are S2s that are reporting both values correctly, I went around the house switching lights and then anlyzend the log. I can confirm, that some devices report both values correctly, for instance 0x001fee00000030b7:

zigbee2mqtt:info  2020-03-29 18:08:58: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":4,"linkquality":63,"state_l1":"OFF","state_l2":"ON",       "update_available":false}'
zigbee2mqtt:info  2020-03-29 18:08:58: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":4,"linkquality":68,"state_l1":"OFF","state_l2":"OFF",      "update_available":false}'
zigbee2mqtt:info  2020-03-29 18:08:59: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":4,"linkquality":70,"state_l1":"ON","state_l2":"OFF",       "update_available":false}'
zigbee2mqtt:info  2020-03-29 18:08:59: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":4,"linkquality":65,"state_l1":"OFF","state_l2":"OFF",      "update_available":false}'

Koenkk commented 4 years ago

@felixstorm do you have any experience with the Ubisys S2 and maybe now why this is happening?

felixstorm commented 4 years ago

Unfortunately I do not own any ubisys S2, but looking at the code, the database dump and the ubisys S2 technical reference a few things come to my mind:

There are genOnOff server clusters on endpoints 1 & 2 for the respective outputs, but power and energy are only being reported as a sum of both outputs on endpoint 5. Therefore the existing code in devices.js should IMHO already be fine - no need for any changes here: Reporting on endpoints 1 & 2 should automatically be setup by deviceReport.js and power/energy on endpoint 5 will be setup to report using the existing configure function in devices.js.
Looking at the database dump it looks like setup of reporting failed for some devices with regards to EP 2. Looking at 0x001fee00000017c6 the binds attribute is empty whereas for 0x001fee00000030b7 it shows that also EP 2 has been correctly bound to the coordinator.

I would therefore try to either

stop zigbee2mqtt, change "reporting":1 to "reporting":0 in the appropriate line in database.db and then restart zigbee2mqtt with the logging level set to at least 'info'. Then the log should show lines like 'Successfully setup reporting for ...' and you should see that both endpoints are being configured.
If that does not help, then remove one of the malfunctioning devices (using MQTT or the Z2M assistant HA add-on) then join it again. That should also make sure that reporting will be setup again (same log entries as above). If neither does work, then please post your logs from the first start of Z2M after the changes here.

May I ask what kind of coordinator you are using? You seem to have quite a number of devices and that was about the time when I started experiencing problems with the CC2531 - and device setup problems were not uncommon. Then I switched to a CC2652R which solved all the issues.

I'll also take a look at the OTA code and will try to comment on this in another post here.

felixstorm commented 4 years ago

Looking further up this thread at the different logs you already provided, it seems like Z2M currently fails to even try to setup reporting for EP 2 for whatever reason.

@Koenkk Do you have any idea why this could happen? Looking at deviceReport.js I do not see anything obvious, but for some reason EP 2 does not seem to get considered when reporting is being setup even though it also does support cluster 6 (genOnOff, according to database.js) and both endpoints have been listed in devices.js?

felixstorm commented 4 years ago

Now about OTA: Is the error you received in the beginning a permanent error or did it happen one time only? There should actually be a firmware file available at ubisys for the S2 with hardware versions between 0 and 6, but sometimes downloading the HTML page from ubisys seems to fail for no reason...

Koenkk commented 4 years ago

@felixstorm I have no idea why this fails, the coordinator could indeed be the issue here.

theFork commented 4 years ago

Regarding the coordinator - I'm still running a CC2531. Maybe I should change this and look wheter the issues remain. before we continue the investigation. Regarding the OTA - IIRC the update does not always fail. Maybe it is really an problem with the ubisys server.

stale[bot] commented 4 years ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

theFork commented 4 years ago

Unfortunetely, the issue persists with a ZZH CC2652R coordinator and only two devices. I've just switched and started to re-pair my network:

zigbee2mqtt:info  2020-06-19 21:20:36: Zigbee: allowing new devices to join.
zigbee2mqtt:info  2020-06-19 21:20:36: MQTT publish: topic 'zigbee2mqtt/bridge/config', payload '{"version":"1.14.0","commit":"unknown","coordinator":{"type":"zStack3x0","meta":{"transportrev":2,"product":1,"majorrel":2,"minorrel":7,"maintrel":1,"revision":20200417}},"log_level":"info","permit_join":true}'
zigbee2mqtt:info  2020-06-19 21:21:18: Device '0x001fee00000030b7' joined
zigbee2mqtt:info  2020-06-19 21:21:18: Starting interview of '0x001fee00000030b7'
zigbee2mqtt:info  2020-06-19 21:21:18: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x001fee00000030b7"}}'
zigbee2mqtt:info  2020-06-19 21:21:18: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x001fee00000030b7"}}'
zigbee2mqtt:info  2020-06-19 21:21:18: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_announced","message":"announce","meta":{"friendly_name":"0x001fee00000030b7"}}'
zigbee2mqtt:info  2020-06-19 21:21:19: Successfully setup reporting for '0x001fee00000030b7' - 1 - genOnOff
zigbee2mqtt:info  2020-06-19 21:21:20: Successfully interviewed '0x001fee00000030b7', device has successfully been paired
zigbee2mqtt:info  2020-06-19 21:21:20: Device '0x001fee00000030b7' is supported, identified as: Ubisys Power switch S2 (S2)
zigbee2mqtt:info  2020-06-19 21:21:20: Configuring '0x001fee00000030b7'
zigbee2mqtt:info  2020-06-19 21:21:20: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_successful","meta":{"friendly_name":"0x001fee00000030b7","model":"S2","vendor":"Ubisys","description":"Power switch S2","supported":true}}'
zigbee2mqtt:info  2020-06-19 21:21:20: Successfully configured '0x001fee00000030b7'
zigbee2mqtt:info  2020-06-19 21:21:20: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":0,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:21:21: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":0,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:21:50: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":0,"linkquality":75,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:21:51: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":124,"linkquality":75,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:21:52: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":124,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:21:52: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":0,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:warn  2020-06-19 21:21:59: Firmware on '0x001fee00000030b7' is newer than latest firmware online.
zigbee2mqtt:info  2020-06-19 21:21:59: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":0,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:22:00: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":4,"linkquality":18,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:22:01: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":0,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:24:00: MQTT publish: topic 'zigbee2mqtt/bridge/config/devices', payload '[{"ieeeAddr":"0x00124b001e17f0bd","type":"Coordinator","networkAddress":0,"friendly_name":"Coordinator","softwareBuildID":"zStack3x0","dateCode":"20200417","lastSeen":1592594640012},{"ieeeAddr":"0x001fee00000030b7","type":"Router","networkAddress":58457,"model":"S2","vendor":"Ubisys","description":"Power switch S2","friendly_name":"0x001fee00000030b7","manufacturerID":4338,"manufacturerName":"ubisys","powerSource":"Mains (single phase)","modelID":"S2 (5502)","hardwareVersion":5,"dateCode":"20180517-DE-FB0","lastSeen":1592594521676}]'
zigbee2mqtt:info  2020-06-19 21:24:00: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"devices","message":[{"ieeeAddr":"0x00124b001e17f0bd","type":"Coordinator","networkAddress":0,"friendly_name":"Coordinator","softwareBuildID":"zStack3x0","dateCode":"20200417","lastSeen":1592594640018},{"ieeeAddr":"0x001fee00000030b7","type":"Router","networkAddress":58457,"model":"S2","vendor":"Ubisys","description":"Power switch S2","friendly_name":"0x001fee00000030b7","manufacturerID":4338,"manufacturerName":"ubisys","powerSource":"Mains (single phase)","modelID":"S2 (5502)","hardwareVersion":5,"dateCode":"20180517-DE-FB0","lastSeen":1592594521676}]}'
zigbee2mqtt:info  2020-06-19 21:26:22: Device '0x001fee0000001815' joined
zigbee2mqtt:info  2020-06-19 21:26:22: Starting interview of '0x001fee0000001815'
zigbee2mqtt:info  2020-06-19 21:26:22: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x001fee0000001815"}}'
zigbee2mqtt:info  2020-06-19 21:26:22: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x001fee0000001815"}}'
zigbee2mqtt:info  2020-06-19 21:26:23: Successfully setup reporting for '0x001fee0000001815' - 1 - genOnOff
zigbee2mqtt:info  2020-06-19 21:26:23: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_announced","message":"announce","meta":{"friendly_name":"0x001fee0000001815"}}'
zigbee2mqtt:info  2020-06-19 21:26:24: Successfully interviewed '0x001fee0000001815', device has successfully been paired
zigbee2mqtt:info  2020-06-19 21:26:24: Device '0x001fee0000001815' is supported, identified as: Ubisys Power switch S2 (S2)
zigbee2mqtt:info  2020-06-19 21:26:24: Configuring '0x001fee0000001815'
zigbee2mqtt:info  2020-06-19 21:26:24: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_successful","meta":{"friendly_name":"0x001fee0000001815","model":"S2","vendor":"Ubisys","description":"Power switch S2","supported":true}}'
zigbee2mqtt:info  2020-06-19 21:26:25: Successfully configured '0x001fee0000001815'
zigbee2mqtt:info  2020-06-19 21:26:25: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":15,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:26:26: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":12,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:26:58: MQTT publish: topic 'zigbee2mqtt/0x001fee00000030b7', payload '{"power":0,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:error 2020-06-19 21:27:01: Failed to call 'OTAUpdate' 'onZigbeeEvent' (AssertionError [ERR_ASSERTION]: No image available for imageType '0x7b03' with hardware version 5
    at getImageMeta (/zigbee2mqtt-1.14.0/node_modules/zigbee-herdsman-converters/ota/ubisys.js:22:5)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:94:5)
    at async isNewImageAvailable (/zigbee2mqtt-1.14.0/node_modules/zigbee-herdsman-converters/ota/ubisys.js:49:18)
    at async Object.isUpdateAvailable (/zigbee2mqtt-1.14.0/node_modules/zigbee-herdsman-converters/ota/common.js:168:23)
    at async OTAUpdate.onZigbeeEvent (/zigbee2mqtt-1.14.0/lib/extension/otaUpdate.js:37:31)
    at async Controller.callExtensionMethod (/zigbee2mqtt-1.14.0/lib/controller.js:352:21))
zigbee2mqtt:info  2020-06-19 21:28:59: Starting network scan (includeRoutes 'false')
zigbee2mqtt:info  2020-06-19 21:28:59: Network scan finished
zigbee2mqtt:info  2020-06-19 21:28:59: MQTT publish: topic 'zigbee2mqtt/bridge/networkmap/raw', payload '{"nodes":[{"ieeeAddr":"0x00124b001e17f0bd","friendlyName":"Coordinator","type":"Coordinator","networkAddress":0,"failed":[],"lastSeen":null},{"ieeeAddr":"0x001fee00000030b7","friendlyName":"0x001fee00000030b7","type":"Router","networkAddress":58457,"manufacturerName":"ubisys","modelID":"S2 (5502)","failed":[],"lastSeen":1592594818485},{"ieeeAddr":"0x001fee0000001815","friendlyName":"0x001fee0000001815","type":"Router","networkAddress":415,"manufacturerName":"ubisys","modelID":"S2 (5502)","failed":[],"lastSeen":1592594821245}],"links":[{"source":{"ieeeAddr":"0x001fee00000030b7","networkAddress":58457},"target":{"ieeeAddr":"0x00124b001e17f0bd","networkAddress":0},"linkquality":77,"depth":1,"routes":[],"sourceIeeeAddr":"0x001fee00000030b7","targetIeeeAddr":"0x00124b001e17f0bd","sourceNwkAddr":58457,"lqi":77,"relationship":1},{"source":{"ieeeAddr":"0x001fee0000001815","networkAddress":415},"target":{"ieeeAddr":"0x00124b001e17f0bd","networkAddress":0},"linkquality":13,"depth":1,"routes":[],"sourceIeeeAddr":"0x001fee0000001815","targetIeeeAddr":"0x00124b001e17f0bd","sourceNwkAddr":415,"lqi":13,"relationship":1},{"source":{"ieeeAddr":"0x00124b001e17f0bd","networkAddress":0},"target":{"ieeeAddr":"0x001fee00000030b7","networkAddress":58457},"linkquality":232,"depth":255,"routes":[],"sourceIeeeAddr":"0x00124b001e17f0bd","targetIeeeAddr":"0x001fee00000030b7","sourceNwkAddr":0,"lqi":232,"relationship":2},{"source":{"ieeeAddr":"0x001fee0000001815","networkAddress":415},"target":{"ieeeAddr":"0x001fee00000030b7","networkAddress":58457},"linkquality":135,"depth":255,"routes":[],"sourceIeeeAddr":"0x001fee0000001815","targetIeeeAddr":"0x001fee00000030b7","sourceNwkAddr":415,"lqi":135,"relationship":2},{"source":{"ieeeAddr":"0x00124b001e17f0bd","networkAddress":0},"target":{"ieeeAddr":"0x001fee0000001815","networkAddress":415},"linkquality":141,"depth":255,"routes":[],"sourceIeeeAddr":"0x00124b001e17f0bd","targetIeeeAddr":"0x001fee0000001815","sourceNwkAddr":0,"lqi":141,"relationship":2},{"source":{"ieeeAddr":"0x001fee00000030b7","networkAddress":58457},"target":{"ieeeAddr":"0x001fee0000001815","networkAddress":415},"linkquality":131,"depth":255,"routes":[],"sourceIeeeAddr":"0x001fee00000030b7","targetIeeeAddr":"0x001fee0000001815","sourceNwkAddr":58457,"lqi":131,"relationship":2}]}'
zigbee2mqtt:info  2020-06-19 21:29:25: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":21,"linkquality":0,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:29:27: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":0,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:29:37: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":15,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:29:38: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":21,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:29:45: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":78,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:06: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":15,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:06: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":79,"linkquality":15,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:07: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":73,"linkquality":18,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:08: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":73,"linkquality":21,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:08: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":18,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:22: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":21,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:22: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":73,"linkquality":18,"state_l1":"ON","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:31: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":73,"linkquality":24,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:30:31: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":21,"state_l1":"OFF","state_l2":"OFF","update_available":false}'

The following lines show, what happens when I switch the second channel off. There are new log entries since the power value changes, but the state_l2 field remains unchanged.

zigbee2mqtt:info  2020-06-19 21:30:38: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":21,"linkquality":12,"state_l1":"OFF","state_l2":"OFF","update_available":false}'
zigbee2mqtt:info  2020-06-19 21:31:07: MQTT publish: topic 'zigbee2mqtt/0x001fee0000001815', payload '{"power":0,"linkquality":9,"state_l1":"OFF","state_l2":"OFF","update_available":false}'

This is database.db:

{"id":1,"type":"Coordinator","ieeeAddr":"0x00124b001e17f0bd","nwkAddr":0,"manufId":0,"epList":[242,47,13,12,110,11,8,6,5,4,3,2,1],"endpoints":{"1":{"profId":260,"epId":1,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"2":{"profId":257,"epId":2,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"3":{"profId":261,"epId":3,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"4":{"profId":263,"epId":4,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"5":{"profId":264,"epId":5,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"6":{"profId":265,"epId":6,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"8":{"profId":260,"epId":8,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"11":{"profId":260,"epId":11,"devId":1024,"inClusterList":[],"outClusterList":[1280,1282],"clusters":{},"binds":[]},"12":{"profId":49246,"epId":12,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"13":{"profId":260,"epId":13,"devId":5,"inClusterList":[25],"outClusterList":[],"clusters":{},"binds":[]},"47":{"profId":260,"epId":47,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"110":{"profId":260,"epId":110,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":5,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]}},"interviewCompleted":true,"meta":{},"lastSeen":null}
{"id":2,"type":"Router","ieeeAddr":"0x001fee00000030b7","nwkAddr":58457,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20180517-DE-FB0","swBuildId":""}},"genOnOff":{"attributes":{"onOff":0}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001e17f0bd","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":266,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"swBuildId":""}}},"binds":[]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{"genBasic":{"attributes":{}}},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{"genBasic":{"attributes":{}}},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"genBasic":{"attributes":{}},"seMetering":{"attributes":{"multiplier":1,"divisor":1000,"instantaneousDemand":0}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001e17f0bd","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{"genBasic":{"attributes":{}}},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20180517-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1592594480440}
{"id":3,"type":"Router","ieeeAddr":"0x001fee0000001815","nwkAddr":415,"manufId":4338,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0"}}},"binds":[{"cluster":6,"type":"endpoint","deviceIeeeAddress":"0x00124b001e17f0bd","endpointID":1}]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{}}},"binds":[]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{"genBasic":{"attributes":{}}},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{"genBasic":{"attributes":{}}},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"genBasic":{"attributes":{}},"seMetering":{"attributes":{"multiplier":1,"divisor":1000}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001e17f0bd","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{"genBasic":{"attributes":{}}},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170718-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"reporting":1,"configured":3},"lastSeen":1592594785001}
{"id":12,"ieeeAddr":"0x001fee000000181e","nwkAddr":64139,"manufName":"ubisys","powerSource":"Mains (single phase)","modelId":"S2 (5502)","epList":[1,2,200,232,242,3,4,5],"endpoints":{"1":{"profId":260,"epId":1,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{"genBasic":{"attributes":{"modelId":"S2 (5502)","manufacturerName":"ubisys","powerSource":1,"zclVersion":2,"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170717-DE-FB0"}}},"binds":[]},"2":{"profId":260,"epId":2,"devId":2,"inClusterList":[0,3,4,5,6],"outClusterList":[],"clusters":{},"binds":[]},"3":{"profId":260,"epId":3,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"4":{"profId":260,"epId":4,"devId":1,"inClusterList":[0,3],"outClusterList":[5,6,8],"clusters":{},"binds":[]},"5":{"profId":260,"epId":5,"devId":1281,"inClusterList":[0,1794,2820],"outClusterList":[],"clusters":{"seMetering":{"attributes":{"multiplier":1,"divisor":1000}}},"binds":[{"cluster":1794,"type":"endpoint","deviceIeeeAddress":"0x00124b001938aa2d","endpointID":1}]},"200":{"profId":49155,"epId":200,"devId":0,"inClusterList":[],"outClusterList":[],"clusters":{},"binds":[]},"232":{"profId":260,"epId":232,"devId":1287,"inClusterList":[0,21,64512],"outClusterList":[3,25],"clusters":{},"binds":[]},"242":{"profId":41440,"epId":242,"devId":102,"inClusterList":[33],"outClusterList":[33],"clusters":{},"binds":[]}},"appVersion":1,"stackVersion":1,"hwVersion":5,"dateCode":"20170717-DE-FB0","zclVersion":2,"interviewCompleted":true,"meta":{"configured":3},"lastSeen":1581108383531}#

@Koenkk It appears as if I cannot reopen this issue. Can you do this? If it helps I could also keep it closed and open a new one.

felixstorm commented 4 years ago

@theFork Unfortunately there is nothing in the logs that would look unusual to me. Also, I took another look at the current reporting and OTA code again - and both look fine as well.

About the reporting problem: I am not sure if it is really only an ubisys S2 problem - it might even be a general problem with reporting setup for multi-endpoint devices. Unfortunately I do not own an ubisys S2 nor any other device where reporting would automatically bind more than one endpoint, so there is not much I can do at this point.

About the OTA problem: For ubisys, we need to use screen scraping from the ubisys website (https://www.ubisys.de/en/support/firmware/) to get firmware images. But the ubisys website does have an image available for the S2 for hardware versions 0 until 6 (10F2-7B03-0000-0006-010E0206-spo-fms2.ota.zigbee), so the error message should not come up.
@theFork Are you sure that your z2m instance has full network connectivity - no firewalls, proxies or anything similar in between?

theFork commented 4 years ago

I'm running Z2M as an add-on in home assistant, so it's running in docker. As far as I understand it, there is no proxy or firewall involved. Downloading the firmware using wget works fine:

➜  ~ wget https://www.ubisys.de/wp-content/uploads/10F2-7B03-0000-0006-010E0206-spo-fms2.ota.zigbee
--2020-06-21 16:16:59--  https://www.ubisys.de/wp-content/uploads/10F2-7B03-0000-0006-010E0206-spo-fms2.ota.zigbee
Resolving www.ubisys.de... 2a02:2350:5:102:800d:618e:77a5:d93f, 77.111.240.161
Connecting to www.ubisys.de|2a02:2350:5:102:800d:618e:77a5:d93f|:443... connected.
HTTP request sent, awaiting response... 200 OK
Length: 249626 (244K)
Saving to: '10F2-7B03-0000-0006-010E0206-spo-fms2.ota.zigbee'

10F2-7B03-0000-0006-010E02 100%[======================================>] 243.78K  --.-KB/s    in 0.1s    

2020-06-21 16:17:00 (2.47 MB/s) - '10F2-7B03-0000-0006-010E0206-spo-fms2.ota.zigbee' saved [249626/249626]

In order to separate (and re-open) the topics, I have opened two issues: OTA: https://github.com/Koenkk/zigbee2mqtt/issues/3789 Reporting: https://github.com/Koenkk/zigbee-herdsman-converters/issues/1334

@felixstorm Would it be an option if I send you an S2 device? If you can use it, you keep it, if you have no purpose, you send it back to me? If this is an option, please send me your address via eMail (simon(At)sineband.de).

felixstorm commented 4 years ago

@theFork Sounds good to me, only time on my side might be an issue. I could hopefully work on it before mid of July, but if problems come up it might become early August due to a planned vacation. I just sent you an email with further details.

Koenkk / zigbee-herdsman-converters

Ubisys S2: Reporting of only one channel / OTA fails #1081