zigbee2mqtt icon indicating copy to clipboard operation
zigbee2mqtt copied to clipboard
Published 3 months ago • verified publisher icon Koenkk

Paulman 371000001 light flashing

Open cantfindanam opened this issue 3 years ago • 14 comments

What happened?

Hi, I noticed some strange behaviour of the Paulmann 371000001. A few days ago it started to randomly turn on or off for just a second and it starts to happen more and more often.

The debug logs show that the device is requesting an OTA update every minute but otherwise nothing suspicious (it is definitely not flashing every time the request is send). I attached a snippet of the request that was send around the time the light was flashing. However the message alway the same. Any idea how to fix that?

What did you expect to happen?

No response

How to reproduce it (minimal and precise)

No response

Zigbee2MQTT version

1.28.0 commit: 03ba647

Adapter firmware version

20220219

Adapter

ZigStar stick V4

Debug log

2022-10-31 20:09:07 Received Zigbee message from 'Kitchen_main_light', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType": 4364," manufacturerCode":4624}' from endpoint 1 with groupID 0

Debug 2022-10-31 20:09:07Device 'Kitchen_main_light' requested OTA

cantfindanam avatar Oct 31 '22 19:10 cantfindanam

@cantfindanam I had the same problems. The mentioned message in the logs and the flashing are indeed different things. Somewhere on the interwebs (probably even here in some issues 🤔) I found the solution for the flashing: in the Z2M frontend, disable joining.

I manually disabled it after having joined all my devices. But after a restart, it was enabled again. Turns out, disabling in the frontend is just for runtime, but on (re)start, it gets the initial state from the config file. So I had to disable joining there as well. Which, of course, makes a lot of sense - it just didn't occur to me right away. 😉

With regards to the message: those will keep coming up at regular intervals. 😕

Zigbee2MQTT:debug 2022-11-09 17:15:05: Received Zigbee message from 'LED-Strahler 1', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-09 17:15:05: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 1', payload '{"brightness":76,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-09T17:15:05+01:00","linkquality":29,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-09 17:15:05: Device 'LED-Strahler 1' requested OTA
Zigbee2MQTT:debug 2022-11-09 17:16:06: Received Zigbee message from 'LED-Strahler 1', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-09 17:16:06: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 1', payload '{"brightness":76,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-09T17:16:06+01:00","linkquality":10,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-09 17:16:06: Device 'LED-Strahler 1' requested OTA
Zigbee2MQTT:debug 2022-11-09 17:17:07: Received Zigbee message from 'LED-Strahler 1', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-09 17:17:07: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 1', payload '{"brightness":76,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-09T17:17:07+01:00","linkquality":18,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-09 17:17:07: Device 'LED-Strahler 1' requested OTA

@Koenkk Is there something that can be done about that? Or should we just ignore it? According to my logs, the Paulmann lights (for me it's Paulmann 929.63, Zigbee Model 500.46) are sending that message about every minute...

mh166 avatar Nov 09 '22 16:11 mh166

@mh166 can you try with z2m 1.28.2? (something has been changed in the OTA handling)

Koenkk avatar Nov 09 '22 17:11 Koenkk

@Koenkk I already updated to "1.28.2 commit: 360a777". Same behaviour.

Debug 2022-11-09 18:25:52Received Zigbee message from 'Kitchen_main_light', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Debug 2022-11-09 18:25:52Device 'Kitchen_main_light' requested OTA
Debug 2022-11-09 18:26:53Received Zigbee message from 'Kitchen_main_light', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Debug 2022-11-09 18:26:53Device 'Kitchen_main_light' requested OTA

cantfindanam avatar Nov 09 '22 17:11 cantfindanam

Can you provide the herdsman debug log of this?

See https://www.zigbee2mqtt.io/guide/usage/debug.html on how to enable the herdsman debug logging. Note that this is only logged to STDOUT and not to log files.

Koenkk avatar Nov 09 '22 17:11 Koenkk

@mh166 Hmm that's weird. Why should only Paulmann devices show this quirk? Thanks for the input! I just deactivated permit join and observe what happens.

cantfindanam avatar Nov 09 '22 17:11 cantfindanam

I suddenly also have this issue with my Paulmann lights since recently updating my home assistant and zigbee2mqtt. Joining for new devices is disabled.

I see no messages in my logs except the requested OTA

Could be duplicate off: https://github.com/Koenkk/zigbee2mqtt/issues/5104

The flashing happens both when lights are off and on (flashes to full brightness). It does seem to happen the moment of commandQueryNextImageRequest but not every time.

Also when it happens it happens to both of my Paulmann lights.

In my case it's the Clever Connect which get's detected as as spot light and has same manufacturerCode and imageType as above.

timdj avatar Nov 17 '22 11:11 timdj

@mh166 can you try with z2m 1.28.2? (something has been changed in the OTA handling)

@Koenkk I think I started to see the problem after updating to 1.28.2 (not sure what I was running before). What changed? I'll try downgrading soon and see if that solves the problem. Any suggestion what version I should go back to?

timdj avatar Nov 18 '22 09:11 timdj

I have issues with the Paulmann 929.66 flashing at will or switching on at will. However the Paulmann 500.44 has no issues. As all my other lights from other vendors. I'd like to verify that the issue is linked to Z2MQTT by connecting these lights via ZHA, but unfortunately ZHA does not recognize my Conbee II stick.

TMV-NL avatar Nov 18 '22 18:11 TMV-NL

@timdj 1.28.0

Koenkk avatar Nov 20 '22 08:11 Koenkk

I'm experiencing the same issue with two Paulmann 371000002 with many OTA update requests at least one in every minute. It happened after an update from 1.18.3 to 1.25.0.

DenisLug avatar Nov 20 '22 08:11 DenisLug

@mh166 can you try with z2m 1.28.2? (something has been changed in the OTA handling)

@Koenkk Sorry for the late reply. Took me while to get to it. Here are the logs with v1.28.2:

Logs, grouped by request 
2022-11-27T20:56:19.858Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,239,224,1,13,0,193,0,18,158,225,0,0,12,1,28,1,0,16,18,12,17,1,0,0,0,239,224,29,79]
2022-11-27T20:56:19.859Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,239,224,1,13,0,193,0,18,158,225,0,0,12,1,28,1,0,16,18,12,17,1,0,0,0,239,224,29,79]
2022-11-27T20:56:19.859Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,239,224,1,13,0,193,0,18,158,225,0,0,12,1,28,1,0,16,18,12,17,1,0,0,0,239,224,29] - 79
2022-11-27T20:56:19.860Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57583,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":193,"securityuse":0,"timestamp":14786066,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,28,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:56:19.865Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":28,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57583,"endpoint":1,"linkquality":193,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:56:19: Received Zigbee message from 'LED-Strahler 3', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:56:19: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 3', payload '{"brightness":28,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:56:19+01:00","linkquality":193,"state":"OFF"}'
Zigbee2MQTT:debug 2022-11-27 21:56:19: Device 'LED-Strahler 3' requested OTA
2022-11-27T20:56:19.892Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d00082038dd/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:56:19.893Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d00082038dd:57583/1 (0,0,1)
2022-11-27T20:56:19.894Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":57583,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":45,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,90,2,152]}}
2022-11-27T20:56:19.895Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,239,224,1,1,25,0,45,0,30,4,25,90,2,152,211]
2022-11-27T20:56:19.897Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:56:19.910Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:56:19.911Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:56:19.911Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:56:19.911Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:56:19.912Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:56:19.914Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,45,235]
2022-11-27T20:56:19.915Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,45,235]
2022-11-27T20:56:19.916Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,45] - 235
2022-11-27T20:56:19.917Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":45}
2022-11-27T20:56:19.918Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:56:42.337Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,145,152,1,13,0,40,0,9,14,7,0,0,12,1,54,1,0,16,18,12,17,1,0,0,0,145,152,29,225]
2022-11-27T20:56:42.339Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,145,152,1,13,0,40,0,9,14,7,0,0,12,1,54,1,0,16,18,12,17,1,0,0,0,145,152,29,225]
2022-11-27T20:56:42.339Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,145,152,1,13,0,40,0,9,14,7,0,0,12,1,54,1,0,16,18,12,17,1,0,0,0,145,152,29] - 225
2022-11-27T20:56:42.340Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":39057,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":40,"securityuse":0,"timestamp":462345,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,54,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:56:42.345Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":54,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":39057,"endpoint":1,"linkquality":40,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:56:42: Received Zigbee message from 'LED-Strahler 2', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:56:42: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 2', payload '{"brightness":84,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:56:42+01:00","linkquality":40,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-27 21:56:42: Device 'LED-Strahler 2' requested OTA
2022-11-27T20:56:42.374Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d0007d41b5a/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:56:42.374Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d0007d41b5a:39057/1 (0,0,1)
2022-11-27T20:56:42.375Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":39057,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":48,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,91,2,152]}}
2022-11-27T20:56:42.376Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,145,152,1,1,25,0,48,0,30,4,25,91,2,152,201]
2022-11-27T20:56:42.388Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:56:42.400Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:56:42.400Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:56:42.401Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:56:42.401Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:56:42.401Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:56:42.403Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,48,246]
2022-11-27T20:56:42.404Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,48,246]
2022-11-27T20:56:42.404Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,48] - 246
2022-11-27T20:56:42.404Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":48}
2022-11-27T20:56:42.405Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:57:02.415Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,5,69,196,198,225,1,145,152,171]
2022-11-27T20:57:02.416Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,5,69,196,198,225,1,145,152,171]
2022-11-27T20:57:02.416Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 5 - 2 - 5 - 196 - [198,225,1,145,152] - 171
2022-11-27T20:57:02.417Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":57798,"relaycount":1,"relaylist":[39057]}
2022-11-27T20:57:02.417Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:02.433Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,198,225,1,13,0,43,0,10,56,26,0,0,12,1,79,1,0,16,18,12,17,1,0,0,0,145,152,28,156]
2022-11-27T20:57:02.433Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,198,225,1,13,0,43,0,10,56,26,0,0,12,1,79,1,0,16,18,12,17,1,0,0,0,145,152,28,156]
Zigbee2MQTT:debug 2022-11-27 21:57:02: Received Zigbee message from 'LED-Strahler 1', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:57:02: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 1', payload '{"brightness":76,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:57:02+01:00","linkquality":43,"state":"ON"}'
2022-11-27T20:57:02.434Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,198,225,1,13,0,43,0,10,56,26,0,0,12,1,79,1,0,16,18,12,17,1,0,0,0,145,152,28] - 156
2022-11-27T20:57:02.435Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57798,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":43,"securityuse":0,"timestamp":1718282,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,79,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:57:02.440Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":79,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57798,"endpoint":1,"linkquality":43,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:57:02: Device 'LED-Strahler 1' requested OTA
2022-11-27T20:57:02.473Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d00082036cd/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:57:02.473Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d00082036cd:57798/1 (0,0,1)
2022-11-27T20:57:02.474Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":57798,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":49,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,92,2,152]}}
2022-11-27T20:57:02.475Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,198,225,1,1,25,0,49,0,30,4,25,92,2,152,225]
2022-11-27T20:57:02.479Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:02.492Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:57:02.492Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:57:02.492Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:57:02.493Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:57:02.493Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:02.497Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,49,247]
2022-11-27T20:57:02.498Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,49,247]
2022-11-27T20:57:02.499Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,49] - 247
2022-11-27T20:57:02.500Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":49}
2022-11-27T20:57:02.502Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:57:20.863Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,239,224,0,141,254,32,68,129,0,0,25,0,239,224,1,13,0,196,0,81,203,43,0,0,12,1,29,1,0,16,18,12,17,1,0,0,0,239,224,29,151]
2022-11-27T20:57:20.864Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,239,224,0,141,254,32,68,129,0,0,25,0,239,224,1,13,0,196,0,81,203,43,0,0,12,1,29,1,0,16,18,12,17,1,0,0,0,239,224,29,151]
2022-11-27T20:57:20.864Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [239,224,0] - 141
2022-11-27T20:57:20.874Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":57583,"relaycount":0,"relaylist":[]}
2022-11-27T20:57:20.875Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,239,224,1,13,0,196,0,81,203,43,0,0,12,1,29,1,0,16,18,12,17,1,0,0,0,239,224,29,151]
2022-11-27T20:57:20.876Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,239,224,1,13,0,196,0,81,203,43,0,0,12,1,29,1,0,16,18,12,17,1,0,0,0,239,224,29] - 151
2022-11-27T20:57:20.876Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57583,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":196,"securityuse":0,"timestamp":2870097,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,29,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:57:20.882Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":29,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57583,"endpoint":1,"linkquality":196,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:57:20: Received Zigbee message from 'LED-Strahler 3', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:57:20: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 3', payload '{"brightness":28,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:57:20+01:00","linkquality":196,"state":"OFF"}'
Zigbee2MQTT:debug 2022-11-27 21:57:20: Device 'LED-Strahler 3' requested OTA
2022-11-27T20:57:20.921Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d00082038dd/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:57:20.922Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d00082038dd:57583/1 (0,0,1)
2022-11-27T20:57:20.925Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":57583,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":52,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,94,2,152]}}
2022-11-27T20:57:20.926Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,239,224,1,1,25,0,52,0,30,4,25,94,2,152,206]
2022-11-27T20:57:20.929Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:20.942Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:57:20.942Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:57:20.943Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:57:20.944Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:57:20.945Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:20.947Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,52,242]
2022-11-27T20:57:20.948Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,52,242]
2022-11-27T20:57:20.948Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,52] - 242
2022-11-27T20:57:20.951Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":52}
2022-11-27T20:57:20.952Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:57:43.331Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,145,152,0,139]
2022-11-27T20:57:43.332Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,145,152,0,139]
2022-11-27T20:57:43.333Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [145,152,0] - 139
2022-11-27T20:57:43.335Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":39057,"relaycount":0,"relaylist":[]}
2022-11-27T20:57:43.337Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:43.350Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,145,152,1,13,0,36,0,172,61,65,0,0,12,1,55,1,0,16,18,12,17,1,0,0,0,145,152,29,60]
2022-11-27T20:57:43.351Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,145,152,1,13,0,36,0,172,61,65,0,0,12,1,55,1,0,16,18,12,17,1,0,0,0,145,152,29,60]
2022-11-27T20:57:43.352Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,145,152,1,13,0,36,0,172,61,65,0,0,12,1,55,1,0,16,18,12,17,1,0,0,0,145,152,29] - 60
2022-11-27T20:57:43.353Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":39057,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":36,"securityuse":0,"timestamp":4275628,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,55,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:57:43.360Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":55,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":39057,"endpoint":1,"linkquality":36,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:57:43: Received Zigbee message from 'LED-Strahler 2', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:57:43: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 2', payload '{"brightness":84,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:57:43+01:00","linkquality":36,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-27 21:57:43: Device 'LED-Strahler 2' requested OTA
2022-11-27T20:57:43.393Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d0007d41b5a/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:57:43.394Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d0007d41b5a:39057/1 (0,0,1)
2022-11-27T20:57:43.396Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":39057,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":53,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,95,2,152]}}
2022-11-27T20:57:43.397Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,145,152,1,1,25,0,53,0,30,4,25,95,2,152,200]
2022-11-27T20:57:43.401Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:43.417Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:57:43.418Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:57:43.418Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:57:43.419Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:57:43.419Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:57:43.420Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,53,243]
2022-11-27T20:57:43.420Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,53,243]
2022-11-27T20:57:43.421Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,53] - 243
2022-11-27T20:57:43.421Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":53}
2022-11-27T20:57:43.421Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:58:03.414Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,198,225,1,13,0,36,0,255,95,84,0,0,12,1,80,1,0,16,18,12,17,1,0,0,0,145,152,28,80]
2022-11-27T20:58:03.420Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,198,225,1,13,0,36,0,255,95,84,0,0,12,1,80,1,0,16,18,12,17,1,0,0,0,145,152,28,80]
2022-11-27T20:58:03.420Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,198,225,1,13,0,36,0,255,95,84,0,0,12,1,80,1,0,16,18,12,17,1,0,0,0,145,152,28] - 80
2022-11-27T20:58:03.421Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57798,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":36,"securityuse":0,"timestamp":5529599,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,80,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:58:03.427Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":80,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57798,"endpoint":1,"linkquality":36,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:58:03: Received Zigbee message from 'LED-Strahler 1', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:58:03: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 1', payload '{"brightness":76,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:58:03+01:00","linkquality":36,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-27 21:58:03: Device 'LED-Strahler 1' requested OTA
2022-11-27T20:58:03.458Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d00082036cd/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:58:03.458Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d00082036cd:57798/1 (0,0,1)
2022-11-27T20:58:03.459Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":57798,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":55,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,96,2,152]}}
2022-11-27T20:58:03.460Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,198,225,1,1,25,0,55,0,30,4,25,96,2,152,219]
2022-11-27T20:58:03.463Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:58:03.475Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:58:03.475Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:58:03.476Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:58:03.476Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:58:03.477Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:58:03.483Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,55,241]
2022-11-27T20:58:03.483Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,55,241]
2022-11-27T20:58:03.484Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,55] - 241
2022-11-27T20:58:03.484Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":55}
2022-11-27T20:58:03.485Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:58:21.867Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,239,224,1,13,0,193,0,20,249,101,0,0,12,1,30,1,0,16,18,12,17,1,0,0,0,239,224,29,168]
2022-11-27T20:58:21.867Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,239,224,1,13,0,193,0,20,249,101,0,0,12,1,30,1,0,16,18,12,17,1,0,0,0,239,224,29,168]
2022-11-27T20:58:21.869Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,239,224,1,13,0,193,0,20,249,101,0,0,12,1,30,1,0,16,18,12,17,1,0,0,0,239,224,29] - 168
2022-11-27T20:58:21.871Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57583,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":193,"securityuse":0,"timestamp":6682900,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,30,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:58:21.878Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":30,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57583,"endpoint":1,"linkquality":193,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:58:21: Received Zigbee message from 'LED-Strahler 3', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:58:21: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 3', payload '{"brightness":28,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:58:21+01:00","linkquality":193,"state":"OFF"}'
Zigbee2MQTT:debug 2022-11-27 21:58:21: Device 'LED-Strahler 3' requested OTA
2022-11-27T20:58:21.902Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d00082038dd/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:58:21.904Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d00082038dd:57583/1 (0,0,1)
2022-11-27T20:58:21.905Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":57583,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":56,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,97,2,152]}}
2022-11-27T20:58:21.906Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,239,224,1,1,25,0,56,0,30,4,25,97,2,152,253]
2022-11-27T20:58:21.909Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:58:21.920Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:58:21.921Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:58:21.921Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:58:21.921Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:58:21.922Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:58:21.925Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,56,254]
2022-11-27T20:58:21.926Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,56,254]
2022-11-27T20:58:21.926Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,56] - 254
2022-11-27T20:58:21.927Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":56}
2022-11-27T20:58:21.928Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:58:44.333Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,145,152,0,139]
2022-11-27T20:58:44.334Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,145,152,0,139]
2022-11-27T20:58:44.334Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [145,152,0] - 139
2022-11-27T20:58:44.335Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":39057,"relaycount":0,"relaylist":[]}
2022-11-27T20:58:44.335Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:58:44.345Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,145,152,1,13,0,43,0,207,104,123,0,0,12,1,56,1,0,16,18,12,17,1,0,0,0,145,152,29,48]
2022-11-27T20:58:44.345Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,145,152,1,13,0,43,0,207,104,123,0,0,12,1,56,1,0,16,18,12,17,1,0,0,0,145,152,29,48]
2022-11-27T20:58:44.346Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,145,152,1,13,0,43,0,207,104,123,0,0,12,1,56,1,0,16,18,12,17,1,0,0,0,145,152,29] - 48
2022-11-27T20:58:44.346Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":39057,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":43,"securityuse":0,"timestamp":8087759,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,56,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:58:44.352Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":56,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":39057,"endpoint":1,"linkquality":43,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:58:44: Received Zigbee message from 'LED-Strahler 2', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:58:44: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 2', payload '{"brightness":84,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:58:44+01:00","linkquality":43,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-27 21:58:44: Device 'LED-Strahler 2' requested OTA
2022-11-27T20:58:44.384Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d0007d41b5a/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:58:44.385Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d0007d41b5a:39057/1 (0,0,1)
2022-11-27T20:58:44.386Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":39057,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":57,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,98,2,152]}}
2022-11-27T20:58:44.387Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,145,152,1,1,25,0,57,0,30,4,25,98,2,152,249]
2022-11-27T20:58:44.389Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:58:44.401Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:58:44.402Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:58:44.402Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:58:44.403Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:58:44.404Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:58:44.408Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,57,255]
2022-11-27T20:58:44.409Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,57,255]
2022-11-27T20:58:44.409Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,57] - 255
2022-11-27T20:58:44.410Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":57}
2022-11-27T20:58:44.411Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-11-27T20:59:04.414Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,5,69,196,198,225,1,145,152,171]
2022-11-27T20:59:04.414Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,5,69,196,198,225,1,145,152,171]
2022-11-27T20:59:04.415Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 5 - 2 - 5 - 196 - [198,225,1,145,152] - 171
2022-11-27T20:59:04.415Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":57798,"relaycount":1,"relaylist":[39057]}
2022-11-27T20:59:04.416Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:59:04.439Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,198,225,1,13,0,36,0,144,146,142,0,0,12,1,81,1,0,16,18,12,17,1,0,0,0,145,152,28,41]
2022-11-27T20:59:04.440Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,198,225,1,13,0,36,0,144,146,142,0,0,12,1,81,1,0,16,18,12,17,1,0,0,0,145,152,28,41]
2022-11-27T20:59:04.440Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,198,225,1,13,0,36,0,144,146,142,0,0,12,1,81,1,0,16,18,12,17,1,0,0,0,145,152,28] - 41
2022-11-27T20:59:04.441Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57798,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":36,"securityuse":0,"timestamp":9343632,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,81,1,0,16,18,12,17,1,0,0,0]}}
2022-11-27T20:59:04.447Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":81,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57798,"endpoint":1,"linkquality":36,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-11-27 21:59:04: Received Zigbee message from 'LED-Strahler 1', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-11-27 21:59:04: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 1', payload '{"brightness":76,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-11-27T21:59:04+01:00","linkquality":36,"state":"ON"}'
Zigbee2MQTT:debug 2022-11-27 21:59:04: Device 'LED-Strahler 1' requested OTA
2022-11-27T20:59:04.478Z zigbee-herdsman:controller:endpoint CommandResponse 0x00158d00082036cd/1 genOta.queryNextImageResponse({"status":152}, {"sendWhen":"immediate","timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
2022-11-27T20:59:04.479Z zigbee-herdsman:adapter:zStack:adapter sendZclFrameToEndpointInternal 0x00158d00082036cd:57798/1 (0,0,1)
2022-11-27T20:59:04.480Z zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":57798,"destendpoint":1,"srcendpoint":1,"clusterid":25,"transid":58,"options":0,"radius":30,"len":4,"data":{"type":"Buffer","data":[25,99,2,152]}}
2022-11-27T20:59:04.481Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,14,36,1,198,225,1,1,25,0,58,0,30,4,25,99,2,152,213]
2022-11-27T20:59:04.484Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:59:04.495Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100]
2022-11-27T20:59:04.496Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100]
2022-11-27T20:59:04.496Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100
2022-11-27T20:59:04.497Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0}
2022-11-27T20:59:04.498Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2022-11-27T20:59:04.500Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,58,252]
2022-11-27T20:59:04.500Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,58,252]
2022-11-27T20:59:04.501Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,58] - 252
2022-11-27T20:59:04.501Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":58}
2022-11-27T20:59:04.502Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

I tried to get only the parts relevant to each LED light's communication. I grouped them with an empty line between each request. Should I've missed something or you need any more information, please let me know. :)

mh166 avatar Nov 27 '22 21:11 mh166

@mh166 we can try disabling the OTA response completely, how are you running z2m? then I can provide instructions

Koenkk avatar Nov 28 '22 17:11 Koenkk

I'm running it using docker:

docker run \
        --name zigbee2mqtt \
        --device=/dev/serial/by-id/usb-ITead_Sonoff_Zigbee_3.0_USB_Dongle_Plus_50f7ab909345ec11ae0994fd6f14af06-if00-port0:/dev/ttyACM0 \
        -e DEBUG=zigbee-herdsman* \
        -p 8080:8080 \
        -v $(pwd)/data:/app/data \
        -v /run/udev:/run/udev:ro \
        -e TZ=Europe/Berlin \
        --restart=unless-stopped \
        koenkk/zigbee2mqtt

mh166 avatar Nov 28 '22 21:11 mh166

My attempt to downgrade zigbee2mqtt within Home assistant failed. Reinstalled 1.28.2 while maintaining my configuration. Now the problem is gone.

timdj avatar Nov 29 '22 08:11 timdj

@mh166

  • run `docker exec -it zigbee2mqtt /bin/bash
  • vi dist/extension/otaUpdate.js
  • Remove await endpoint.commandResponse('genOta', 'queryNextImageResponse', { status: 0x98 }); (somewhere around line 136)
  • Save
  • exit
  • docker restart zigbee2mqtt

Koenkk avatar Nov 29 '22 18:11 Koenkk

@Koenkk

I tried it, but it does make no difference. Even with this line commented out, I still get these OTA requests from the lights:

[Logfiles again] 
2022-12-01T21:09:20.472Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,239,224,1,13,0,189,0,165,65,79,0,0,12,1,80,1,0,16,18,12,17,1,0,0,0,239,224,29,185]
2022-12-01T21:09:20.473Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,239,224,1,13,0,189,0,165,65,79,0,0,12,1,80,1,0,16,18,12,17,1,0,0,0,239,224,29,185]
2022-12-01T21:09:20.474Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,239,224,1,13,0,189,0,165,65,79,0,0,12,1,80,1,0,16,18,12,17,1,0,0,0,239,224,29] - 185
2022-12-01T21:09:20.476Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57583,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":189,"securityuse":0,"timestamp":5194149,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,80,1,0,16,18,12,17,1,0,0,0]}}
2022-12-01T21:09:20.483Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":80,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57583,"endpoint":1,"linkquality":189,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-12-01 22:09:20: Received Zigbee message from 'LED-Strahler 3', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-12-01 22:09:20: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 3', payload '{"brightness":28,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-12-01T22:09:20+01:00","linkquality":189,"state":"OFF"}'
Zigbee2MQTT:debug 2022-12-01 22:09:20: Device 'LED-Strahler 3' requested OTA
2022-12-01T21:09:20.528Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-12-01T21:09:25.010Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,145,152,1,13,0,40,0,71,149,83,0,0,12,1,111,1,0,16,18,12,17,1,0,0,0,145,152,29,57]
2022-12-01T21:09:25.011Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,145,152,1,13,0,40,0,71,149,83,0,0,12,1,111,1,0,16,18,12,17,1,0,0,0,145,152,29,57]
2022-12-01T21:09:25.011Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,145,152,1,13,0,40,0,71,149,83,0,0,12,1,111,1,0,16,18,12,17,1,0,0,0,145,152,29] - 57
2022-12-01T21:09:25.013Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":39057,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":40,"securityuse":0,"timestamp":5477703,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,111,1,0,16,18,12,17,1,0,0,0]}}
2022-12-01T21:09:25.021Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":111,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":39057,"endpoint":1,"linkquality":40,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-12-01 22:09:25: Received Zigbee message from 'LED-Strahler 2', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-12-01 22:09:25: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 2', payload '{"brightness":51,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-12-01T22:09:25+01:00","linkquality":40,"state":"OFF"}'
Zigbee2MQTT:debug 2022-12-01 22:09:25: Device 'LED-Strahler 2' requested OTA
2022-12-01T21:09:25.057Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

2022-12-01T21:09:28.046Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,32,68,129,0,0,25,0,198,225,1,13,0,43,0,210,122,86,0,0,12,1,198,1,0,16,18,12,17,1,0,0,0,145,152,28,195]
2022-12-01T21:09:28.048Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,32,68,129,0,0,25,0,198,225,1,13,0,43,0,210,122,86,0,0,12,1,198,1,0,16,18,12,17,1,0,0,0,145,152,28,195]
2022-12-01T21:09:28.049Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 32 - 2 - 4 - 129 - [0,0,25,0,198,225,1,13,0,43,0,210,122,86,0,0,12,1,198,1,0,16,18,12,17,1,0,0,0,145,152,28] - 195
2022-12-01T21:09:28.051Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":25,"srcaddr":57798,"srcendpoint":1,"dstendpoint":13,"wasbroadcast":0,"linkquality":43,"securityuse":0,"timestamp":5667538,"transseqnumber":0,"len":12,"data":{"type":"Buffer","data":[1,198,1,0,16,18,12,17,1,0,0,0]}}
2022-12-01T21:09:28.059Z zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":198,"manufacturerCode":null,"commandIdentifier":1},"Payload":{"fieldControl":0,"manufacturerCode":4624,"imageType":4364,"fileVersion":1},"Command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}},"address":57798,"endpoint":1,"linkquality":43,"groupID":0,"wasBroadcast":false,"destinationEndpoint":13}'
Zigbee2MQTT:debug 2022-12-01 22:09:28: Received Zigbee message from 'LED-Strahler 1', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1,"imageType":4364,"manufacturerCode":4624}' from endpoint 1 with groupID 0
Zigbee2MQTT:info  2022-12-01 22:09:28: MQTT publish: topic 'zigbee2mqtt/LED-Strahler 1', payload '{"brightness":13,"color_mode":"color_temp","color_temp":370,"last_seen":"2022-12-01T22:09:28+01:00","linkquality":43,"state":"ON"}'
Zigbee2MQTT:debug 2022-12-01 22:09:28: Device 'LED-Strahler 1' requested OTA
2022-12-01T21:09:28.103Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

Also, I think the OTA messages in the logs and the constant flashing are probably two separate problems: since debugging for zigbee-herdsman was active at the moment, I re-enabled the permission to join in z2m (Settings > Main > [x] Permit join).

At first, it takes some minutes for the flashing to begin. But once it started, it flashes quite reliably every 400sec (or 6min 40sec). Also, it doesn't matter whether the light is turned on or off; all three lights that I own flash in this interval no matter their power state.

Whenever the flashing occurs, no messages are sent over the Zigbee protocol. At least I don't see any in the herdsman debug log. So I don't think the flashing is related to the OTA messages. Rather, I would assume, that this might be a "safety feature" of the lights, perhaps? As if they wanted to tell you "Hey, be careful. You're still allowing any device to join your network!" This would go along with the fact that there is an initial delay before it starts flashing regularly.

mh166 avatar Dec 01 '22 21:12 mh166

Whenever the flashing occurs, no messages are sent over the Zigbee protocol. At least I don't see any in the herdsman debug log.

This does not have to be the case. Not all messages are received by the coordinator and not all messages send to the coordinator are forwarded to zigbee-herdsman. To continue debugging this:

  • Sniff the traffic of the moment it starts flashing (https://www.zigbee2mqtt.io/advanced/zigbee/04_sniff_zigbee_traffic.html)
  • If we find nothing in this sniff, sniff the traffic when these bulbs are used with the original gateway (assuming bulbs do not flash when paired with the original gateway)

Koenkk avatar Dec 01 '22 21:12 Koenkk

I'm afraid I won't be able to help out here: neither have I got a CC2531 nor an original gateway. I'm only using this Sonoff Zigbee 3.0 USB dongle (ID 10c4:ea60 according to lsusb).

I do however have an old QIVICON ZigBee Stick lying around. lsusb shows it with the ID 10c4:89fb. I don't know if this stick might be of any help. It came together with a smart home solution of my former ISP. Never have used it actually...

mh166 avatar Dec 04 '22 18:12 mh166

I have 12x Paulmann 92962, and they flash randomly, wether on or off. If they are off but under power, one lamp will turn on by itself. As I have them in groups of three, if one light turns on, HA Circadian Lightning recognizes that, sets the color temp of the group and all turn on shortly after the first.

I also have the Sonoff Zigbee Coordinator, but I also tested with the raspbee ii. Same behaviour, but I think it was less often. Startup is by systemd service:

[Service]
ExecStart=/usr/bin/npm start

Installation was done somewhat september to december '21 (z2mqtt 1.22.0)

I have just updated to the latest master branch, I will have check if this will still occur

Crumar avatar Dec 10 '22 13:12 Crumar

After not having this problem for 2 weeks mine started flashing again after a new device joined the network Osram plug.

timdj avatar Dec 12 '22 09:12 timdj

Gibts hier schon Neuigkeiten?

Ricox1975 avatar Jan 07 '23 17:01 Ricox1975

Still happening, nothing changed

Crumar avatar Jan 07 '23 17:01 Crumar

Hello everyone,

I do not know what causes the flashing technically, but I can confirm that after setting permit_join on "false" and reboot Openhab system, the flashing disappears. Setting back on "true" and reboot, flashing behaviour reappears.

ballwax avatar Jan 26 '23 07:01 ballwax

I have the same issue with paulmann 50064 in my case the device is reported as EcoDim after an update. This seems to be introduced in: https://github.com/Koenkk/zigbee2mqtt/issues/12412

Those device id's are matching. I'm not sure this is causing the issue.

jaapio avatar Feb 12 '23 19:02 jaapio

I do definitely have this particular problem. As far as I can tell at the moment Paulmann devices show this behaviour. (371000001, 371000002)

As I am using zigbee2mqtt since yesterday I am not familiar with the debugging process. How can I get into it quickly?

I switched off "Permit join" at 3:00 AM and will observe if the problem reaccurs. (But I fear it does as I have watched a LEDVANCE 4058075208339 behaving just like that 5 minutes ago. At exactly the same moment I saw the zigbee2mqtt GUI showing a message "Starting network scan". This may be a coincidence but also maybe not.)

What I know for sure is that I have not seen this random flashing behaviour when my devices were still connected to Phoscon/deCONZ over a ConBeeII.

elronzo avatar Feb 22 '23 07:02 elronzo

FYI. I turned off "permit join" after mh66 suggested it in November and haven't had this issue since then.

cantfindanam avatar Feb 22 '23 08:02 cantfindanam

Hi i got the same spots (Paulmann 92962) and the same problem as @Crumar above: 1.) SOLVED: randomly flashing lights --> solved by turning off Permit Join -> ok so far, can live with that 2.) NOT SOLVED: all of my 5 spots are randomly turning on at low brightness, without apparently being triggered by Home Assistant.

perhaps an interesting observation: before the spot turns randomly on i find a "device_announce" in the log. Some seconds after that it turns on. What is a device_announce, is it needed and if not can it be switched off somehow?

info 2023-03-05 02:51:31: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"Flur Spot 5","ieee_address":"0x00158d0006c5e6f4"},"type":"device_announce"}' info 2023-03-05 02:51:31: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"announce","meta":{"friendly_name":"Flur Spot 5"},"type":"device_announced"}' info 2023-03-05 02:51:34: MQTT publish: topic 'zigbee2mqtt/Flur Spot 5', payload '{"brightness":254,"color_mode":"color_temp","color_temp":370,"color_temp_startup":65535,"linkquality":211,"power_on_behavior":null,"state":"ON"}'

I got the Sonoff Coordinator Stick stick as well. Would be thankful for any idea.

Already thinking about switching to a different hub for these spots (maybe Hue or sth else, but actually I would like to avoid that) :-(

@cantfindanam: is it possible to reopen this issue?

GrohmS2977 avatar Mar 05 '23 10:03 GrohmS2977

I am having exactly the same problem with Osram bulbs.

Last weekend i migrated from ZHA to Z2M, main reason that i could not get an Aqara smartplug to show usageinformation. That part works beautifully with Z2M.

Only now my Osram bulbs are switching on automagically when in off state. When they are on, they blink every so often. The 'Permit Join' parameter was already switched off

I am using the latest Home Assistant with also the latest Addon. i am using a sonoff coordinator.

hhuitema avatar Apr 13 '23 09:04 hhuitema

I also see this issue with two Paulmann controllers of type 500.46. When off, the connected light flashes at full brightness every now and then (seemingly no pattern). When on, the connected light would go off, then full brightness and then back go the previous setting.

Also, I observe that when the lights are switched off (=0% brightness) and I turn them on via Aqara Zigbee switch, they blink at full brightness and then return to the original state.

This started occuring in the last three weeks. I always try to be on the latest Z2M version. Controller is CC2352, its firmware may not be the latest.

I will review the permit join, but know for certain that it’s disabled in the UI.

dubtec avatar Apr 14 '23 17:04 dubtec