OTA for Ledvance
#23534
Replies: 4 comments 1 reply
-
Could you provide the debug log of this? See this on how to enable debug logging. |
Beta Was this translation helpful? Give feedback.
0 replies
-
Hi
Here is the debug file. I don't know how to filter out exactly what you
need so I tried to hit update exactly on 2024-08-08 12:26:20.
I let z2m run for a bit after I hit update, and then stopped z2m.
The product we are looking for is:
'0xf0d1b8be2408f86f' (PLUG COMPACT EU T)
Hope this is what you need, please let me know if I can do more.
Best regards
Conny
Den ons 7 aug. 2024 kl 22:07 skrev Koen Kanters ***@***.***>:
Could you provide the debug log of this?
See this <https://www.zigbee2mqtt.io/guide/configuration/logging.html> on
how to enable debug logging.
—
Reply to this email directly, view it on GitHub
<#23534 (comment)>,
or unsubscribe
<https://github.com/notifications/unsubscribe-auth/A3OTPA7ZEPCEL7WNROYXZVTZQJ5AZAVCNFSM6AAAAABMCFUFGWVHI2DSMVQWIX3LMV43URDJONRXK43TNFXW4Q3PNVWWK3TUHMYTAMRWHAZTEOA>
.
You are receiving this because you authored the thread.Message ID:
***@***.***>
[2024-08-08 12:26:20] debug: z2m:mqtt: Received MQTT message on 'zigbee2mqtt/bridge/request/device/ota_update/check' with data '{"id":"0xf0d1b8be2408f86f","transaction":"gdelz-7"}'
[2024-08-08 12:26:20] info: z2m: Checking if update available for '0xf0d1b8be2408f86f'
[2024-08-08 12:26:20] debug: zhc:ota:common: Checking if an update is available for '0xf0d1b8be2408f86f' (PLUG COMPACT EU T)
[2024-08-08 12:26:20] debug: zhc:ota:common: Using endpoint '1'
[2024-08-08 12:26:20] debug: zh:controller:endpoint: CommandResponse 0xf0d1b8be2408f86f/1 genOta.imageNotify({"payloadType":0,"queryJitter":100}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false,"sendPolicy":"immediate"})
[2024-08-08 12:26:20] debug: zh:ember: ~~~> [ZCL to=15337 apsFrame={"profileId":260,"clusterId":25,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":1,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"manufacturerCode":null,"transactionSequenceNumber":4,"commandIdentifier":0}]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=41 Len=26]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=4](ackRx=1)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=4](frmRx=4) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=2)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=41 Len=7]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=229 messageTag=4 status=OK]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=5](frmRx=5) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=2)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=41 Len=22]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspIncomingRouteRecordHandler(): callback called with: [source=32346], [sourceEui=0xa4c138e4ab7b46e3], [lastHopLqi=128], [lastHopRssi=-68], [relayCount=2], [relayList=38560,53125]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=6](frmRx=6) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=2)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=41 Len=37]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspIncomingMessageHandler(): callback called with: [type=UNICAST], [apsFrame={"profileId":260,"clusterId":25,"sourceEndpoint":1,"destinationEndpoint":1,"options":320,"groupId":0,"sequence":112}], [packetInfo:{"senderShortId":32346,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":128,"lastHopRssi":-68,"lastHopTimestamp":0}], [messageContents=0196010015db030201300011]
[2024-08-08 12:26:20] debug: zh:controller: Received payload: clusterID=25, address=32346, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=128, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"manufacturerCode":null,"transactionSequenceNumber":150,"commandIdentifier":1},"payload":{"fieldControl":0,"manufacturerCode":56085,"imageType":515,"fileVersion":285224961},"command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}}
[2024-08-08 12:26:20] debug: z2m: Received Zigbee message from 'mi_th5', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":285224961,"imageType":515,"manufacturerCode":56085}' from endpoint 1 with groupID 0
[2024-08-08 12:26:20] debug: z2m: Device 'mi_th5' requested OTA
[2024-08-08 12:26:20] debug: zhc:ota:common: Checking if an update is available for '0xa4c138e4ab7b46e3' (LYWSD03MMC)
[2024-08-08 12:26:20] debug: zhc:ota:common: Is new image available for '0xa4c138e4ab7b46e3' (LYWSD03MMC), current '{"fieldControl":0,"manufacturerCode":56085,"imageType":515,"fileVersion":285224961}'
[2024-08-08 12:26:20] debug: zhc:ota: Getting image metadata for 'LYWSD03MMC'
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=7](frmRx=7) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=2)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=41 Len=22]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspMessageSentHandler(): callback called with: [status=OK], [type=DIRECT], [indexOrDestination=15337], [apsFrame={"profileId":260,"clusterId":25,"sourceEndpoint":1,"destinationEndpoint":1,"options":4096,"groupId":0,"sequence":229}], [messageTag=4]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=0](frmRx=0) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=2)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=41 Len=22]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspIncomingRouteRecordHandler(): callback called with: [source=15337], [sourceEui=0xf0d1b8be2408f86f], [lastHopLqi=128], [lastHopRssi=-68], [relayCount=2], [relayList=3652,53125]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=1](frmRx=1) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=2)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=41 Len=37]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspIncomingMessageHandler(): callback called with: [type=UNICAST], [apsFrame={"profileId":260,"clusterId":25,"sourceEndpoint":1,"destinationEndpoint":1,"options":320,"groupId":0,"sequence":34}], [packetInfo:{"senderShortId":15337,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":128,"lastHopRssi":-68,"lastHopTimestamp":0}], [messageContents=01b101008911d60072362003]
[2024-08-08 12:26:20] debug: zh:controller: Received payload: clusterID=25, address=15337, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=128, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"manufacturerCode":null,"transactionSequenceNumber":177,"commandIdentifier":1},"payload":{"fieldControl":0,"manufacturerCode":4489,"imageType":214,"fileVersion":52442738},"command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}}
[2024-08-08 12:26:20] debug: z2m: Received Zigbee message from '0xf0d1b8be2408f86f', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":52442738,"imageType":214,"manufacturerCode":4489}' from endpoint 1 with groupID 0
[2024-08-08 12:26:20] debug: zhc:ota:common: Got request '{"fieldControl":0,"manufacturerCode":4489,"imageType":214,"fileVersion":52442738}'
[2024-08-08 12:26:20] debug: zhc:ota:common: Is new image available for '0xf0d1b8be2408f86f' (PLUG COMPACT EU T), current '{"fieldControl":0,"manufacturerCode":4489,"imageType":214,"fileVersion":52442738}'
[2024-08-08 12:26:20] debug: zhc:ota:ledvance: Call getImageMeta for PLUG COMPACT EU T
[2024-08-08 12:26:20] debug: zhc:ota: Downloaded main index
[2024-08-08 12:26:20] debug: zhc:ota:common: Is new image available for '0xa4c138e4ab7b46e3' (LYWSD03MMC), latest meta '{"fileVersion":285224961,"fileSize":131362,"url":"https://github.com/Koenkk/zigbee-OTA/raw/master/images/DIY/db15-0203-11003001-z03mmc.zigbee","sha512":"2a7a17f348f6631e10217d689456ddeceaf768e06267a76a93335a8fb0ee57dfda88e84830536eb478977e423d3add9ff0590839b8736e465e216d0d81f474e9"}'
[2024-08-08 12:26:20] debug: zhc:ota:common: Update available for '0xa4c138e4ab7b46e3' (LYWSD03MMC): NO
[2024-08-08 12:26:20] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/mi_th5', payload '{"battery":35.5,"comfort_humidity_max":60,"comfort_humidity_min":30,"comfort_temperature_max":26,"comfort_temperature_min":20,"enable_display":"OFF","humidity":61.55,"humidity_calibration":0,"linkquality":128,"show_smiley":"HIDE","temperature":24.8,"temperature_calibration":null,"temperature_display_mode":"celsius","update":{"installed_version":285224961,"latest_version":285224961,"state":"idle"},"update_available":null,"voltage":3000}'
[2024-08-08 12:26:20] debug: zh:controller:endpoint: CommandResponse 0xa4c138e4ab7b46e3/1 genOta.queryNextImageResponse({"status":152}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
[2024-08-08 12:26:20] debug: zh:ember: ~~~> [ZCL to=32346 apsFrame={"profileId":260,"clusterId":25,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":1,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"manufacturerCode":null,"transactionSequenceNumber":150,"commandIdentifier":2}]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=42 Len=25]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=2](ackRx=2)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=42 Len=7]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=230 messageTag=5 status=OK]
[2024-08-08 12:26:20] debug: z2m: Responded to OTA request of 'mi_th5' with 'NO_IMAGE_AVAILABLE'
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=3](frmRx=3) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=3)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=42 Len=22]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspMessageSentHandler(): callback called with: [status=OK], [type=DIRECT], [indexOrDestination=32346], [apsFrame={"profileId":260,"clusterId":25,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":230}], [messageTag=5]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=4](frmRx=4) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=3)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=42 Len=20]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspIncomingRouteRecordHandler(): callback called with: [source=24699], [sourceEui=0x5cc7c1fffe83437e], [lastHopLqi=200], [lastHopRssi=-50], [relayCount=1], [relayList=63926]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=5](frmRx=5) Added to rxQueue
[2024-08-08 12:26:20] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=3)
[2024-08-08 12:26:20] debug: zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=42 Len=32]
[2024-08-08 12:26:20] debug: zh:ember:ezsp: ezspIncomingMessageHandler(): callback called with: [type=UNICAST], [apsFrame={"profileId":260,"clusterId":1030,"sourceEndpoint":2,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":27}], [packetInfo:{"senderShortId":24699,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":200,"lastHopRssi":-50,"lastHopTimestamp":0}], [messageContents=184b0a00001800]
[2024-08-08 12:26:20] debug: zh:controller: Received payload: clusterID=1030, address=24699, groupID=0, endpoint=2, destinationEndpoint=1, wasBroadcast=false, linkQuality=200, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"manufacturerCode":null,"transactionSequenceNumber":75,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":24,"attrData":0}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-08-08 12:26:20] debug: z2m: Received Zigbee message from 'ikea_ms2', type 'attributeReport', cluster 'msOccupancySensing', data '{"occupancy":0}' from endpoint 2 with groupID 0
[2024-08-08 12:26:20] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/ikea_ms2', payload '{"battery":81,"illuminance":0,"illuminance_lux":1,"linkquality":200,"occupancy":false,"update":{"installed_version":16777316,"latest_version":16777316,"state":"idle"},"update_available":null}'
[2024-08-08 12:26:20] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/response/device/ota_update/check', payload '{"data":{"id":"0xf0d1b8be2408f86f"},"error":"Failed to check if update available for '0xf0d1b8be2408f86f' (Cannot read properties of null (reading '1'))","status":"error","transaction":"gdelz-7"}'
[2024-08-08 12:26:20] error: z2m: Failed to check if update available for '0xf0d1b8be2408f86f' (Cannot read properties of null (reading '1'))
[2024-08-08 12:26:20] debug: z2m: TypeError: Cannot read properties of null (reading '1')
at getImageMeta (/app/node_modules/zigbee-herdsman-converters/src/lib/ota/ledvance.ts:28:55)
at processTicksAndRejections (node:internal/process/task_queues:95:5)
at isNewImageAvailable (/app/node_modules/zigbee-herdsman-converters/src/lib/ota/common.ts:524:18)
at Object.isUpdateAvailable (/app/node_modules/zigbee-herdsman-converters/src/lib/ota/common.ts:505:29)
at OTAUpdate.onMQTTMessage (/app/lib/extension/otaUpdate.ts:210:45)
at EventEmitter.wrappedCallback (/app/lib/eventBus.ts:174:17)
[2024-08-08 12:26:30] debug: z2m: Saving state to file /config/zigbee2mqtt/state.json
[2024-08-08 12:26:30] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload '{"state":"offline"}'
[2024-08-08 12:26:30] info: z2m: Disconnecting from MQTT server
[2024-08-08 12:26:30] info: z2m: Stopping zigbee-herdsman...
[2024-08-08 12:26:30] debug: zh:controller: Disable joining
[2024-08-08 12:26:30] debug: zh:ember: ~~~> [ZCL BROADCAST apsFrame={"profileId":41440,"clusterId":33,"sourceEndpoint":242,"destinationEndpoint":242,"options":4416,"groupId":65533,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":1,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"manufacturerCode":null,"transactionSequenceNumber":5,"commandIdentifier":2}]
[2024-08-08 12:26:30] debug: zh:ember:ezsp: ===> [FRAME: ID=54:"SEND_BROADCAST" Seq=43 Len=27]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=6](ackRx=3)
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=6](frmRx=6) Added to rxQueue
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=4)
[2024-08-08 12:26:30] debug: zh:ember:ezsp: <=== [FRAME: ID=54:"SEND_BROADCAST" Seq=43 Len=7]
[2024-08-08 12:26:30] debug: zh:ember:ezsp: ~~~> [SENT type=BROADCAST apsSequence=231 messageTag=6 status=OK]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=7](frmRx=7) Added to rxQueue
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=4)
[2024-08-08 12:26:30] debug: zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=43 Len=30]
[2024-08-08 12:26:30] debug: zh:ember:ezsp: ezspIncomingMessageHandler(): callback called with: [type=BROADCAST_LOOPBACK], [apsFrame={"profileId":41440,"clusterId":33,"sourceEndpoint":242,"destinationEndpoint":242,"options":256,"groupId":0,"sequence":231}], [packetInfo:{"senderShortId":0,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":255,"lastHopRssi":0,"lastHopTimestamp":0}], [messageContents=1905020a0000]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=0](frmRx=0) Added to rxQueue
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=4)
[2024-08-08 12:26:30] debug: zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=43 Len=20]
[2024-08-08 12:26:30] debug: zh:ember:ezsp: ezspIncomingRouteRecordHandler(): callback called with: [source=49797], [sourceEui=0x8c6fb9fffe260ee3], [lastHopLqi=255], [lastHopRssi=-33], [relayCount=1], [relayList=64158]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=1](frmRx=1) Added to rxQueue
[2024-08-08 12:26:30] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=4)
[2024-08-08 12:26:30] debug: zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=43 Len=32]
[2024-08-08 12:26:30] debug: zh:ember:ezsp: ezspIncomingMessageHandler(): callback called with: [type=UNICAST], [apsFrame={"profileId":260,"clusterId":1030,"sourceEndpoint":2,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":171}], [packetInfo:{"senderShortId":49797,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":255,"lastHopRssi":-33,"lastHopTimestamp":0}], [messageContents=18320a00001800]
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=107:"CLEAR_TRANSIENT_LINK_KEYS" Seq=44 Len=5]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=4 frmRx=2](ackRx=4)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=4 frmTx=5)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=2](frmRx=2) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=5)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=107:"CLEAR_TRANSIENT_LINK_KEYS" Seq=44 Len=5]
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=85:"SET_POLICY" Seq=45 Len=7]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=5 frmRx=3](ackRx=5)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=5 frmTx=6)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=3](frmRx=3) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=6)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=85:"SET_POLICY" Seq=45 Len=6]
[2024-08-08 12:26:31] debug: zh:ember: [EzspPolicyId] SET 'TRUST_CENTER_POLICY' TO '2' with status=OK.
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=34:"PERMIT_JOINING" Seq=46 Len=6]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=6 frmRx=4](ackRx=6)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=6 frmTx=7)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=4](frmRx=4) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=7)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=34:"PERMIT_JOINING" Seq=46 Len=6]
[2024-08-08 12:26:31] debug: zh:ember: Permit joining for 0 sec. status=0
[2024-08-08 12:26:31] debug: zh:ember: ~~~> [ZDO PERMIT_JOINING_REQUEST BROADCAST to=65532 messageTag=2 messageContents=020001]
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=54:"SEND_BROADCAST" Seq=47 Len=24]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=7 frmRx=5](ackRx=7)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=7 frmTx=0)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=5](frmRx=5) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=0)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=54:"SEND_BROADCAST" Seq=47 Len=7]
[2024-08-08 12:26:31] debug: zh:ember: ~~~> [SENT ZDO type=BROADCAST apsSequence=232 messageTag=2 status=OK
[2024-08-08 12:26:31] debug: zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db'
[2024-08-08 12:26:31] debug: zh:controller: Creating coordinator backup
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=40:"GET_NETWORK_PARAMETERS" Seq=48 Len=5]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=0 frmRx=6](ackRx=0)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=0 frmTx=1)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=6](frmRx=6) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=1)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=40:"GET_NETWORK_PARAMETERS" Seq=48 Len=27]
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=38:"GET_EUI64" Seq=49 Len=5]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=7](ackRx=1)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=7](frmRx=7) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=2)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=38:"GET_EUI64" Seq=49 Len=13]
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=278:"GET_NETWORK_KEY_INFO" Seq=50 Len=5]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=0](ackRx=2)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=0](frmRx=0) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=3)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=278:"GET_NETWORK_KEY_INFO" Seq=50 Len=17]
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=276:"EXPORT_KEY" Seq=51 Len=23]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=1](ackRx=3)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=1](frmRx=1) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=4)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=276:"EXPORT_KEY" Seq=51 Len=25]
[2024-08-08 12:26:31] debug: zh:ember:ezsp: ===> [FRAME: ID=276:"EXPORT_KEY" Seq=52 Len=23]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=4 frmRx=2](ackRx=4)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA]
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=4 frmTx=5)
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=2](frmRx=2) Added to rxQueue
[2024-08-08 12:26:31] debug: zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=5)
[2024-08-08 12:26:31] debug: zh:ember:ezsp: <=== [FRAME: ID=276:"EXPORT_KEY" Seq=52 Len=25]
[2024-08-08 12:26:31] info: zh:controller: Wrote coordinator backup to '/config/zigbee2mqtt/coordinator_backup.json'
[2024-08-08 12:26:31] info: zh:ember:uart:ash: ASH COUNTERS since last clear:
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Total frames: RX=76, TX=130
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Cancelled : RX=0, TX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: DATA frames : RX=75, TX=53
[2024-08-08 12:26:31] info: zh:ember:uart:ash: DATA bytes : RX=1036, TX=644
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Retry frames: RX=0, TX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: ACK frames : RX=0, TX=76
[2024-08-08 12:26:31] info: zh:ember:uart:ash: NAK frames : RX=0, TX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: nRdy frames : RX=0, TX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: CRC errors : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Comm errors : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Length < minimum: RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Length > maximum: RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Bad controls : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Bad lengths : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Bad ACK numbers : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Out of buffers : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Retry dupes : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Out of sequence : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: ACK timeouts : RX=0
[2024-08-08 12:26:31] info: zh:ember:uart:ash: Port closed.
[2024-08-08 12:26:31] info: zh:ember:uart:ash: ======== ASH stopped ========
[2024-08-08 12:26:31] info: zh:ember:ezsp: ======== EZSP stopped ========
[2024-08-08 12:26:31] info: zh:ember: ======== Ember Adapter Stopped ========
[2024-08-08 12:26:31] info: z2m: Stopped zigbee-herdsman
[2024-08-08 12:26:31] info: z2m: Stopped Zigbee2MQTT
|
Beta Was this translation helpful? Give feedback.
0 replies
-
Fixed the issue, fix will be included in the 1 September release. |
Beta Was this translation helpful? Give feedback.
0 replies
-
Progress, but not fully success |
Beta Was this translation helpful? Give feedback.
1 reply
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment
-
When I try to search for updates for a Ledvance outdoor plug I get an error message.
It seems like there is a repository from Ledvance with firmware for most of their products (https://update.ledvance.com/firmware-overview). Is there a way to download a file and push it to the device?
Beta Was this translation helpful? Give feedback.
All reactions