Koenkk / zigbee2mqtt

Zigbee 🐝 to MQTT bridge 🌉, get rid of your proprietary Zigbee bridges 🔨
https://www.zigbee2mqtt.io
GNU General Public License v3.0
12.15k stars 1.68k forks source link

Enocean PTM 215Z Greenpower not working after upgrade 1.36.1=> 1.37 or later #23814

Closed coendaar closed 2 months ago

coendaar commented 2 months ago

What happened?

After upgrading from 1.31.2 to 1.40.0 the Enocean PTM215Z no longer works. All other devices still work. After downgrading back to 1.31.2 it works again.

I've tried

What did you expect to happen?

This is logging from version 1.31.2:

debug 2024-09-02 14:39:32: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{},"commandID":19,"frameCounter":9637,"options":0,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948

debug 2024-09-02 14:39:32: Device '0x0000000001718f82' reconnected
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82/availability', payload 'online'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"press_3","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","manufacturerID":null,"model":"PTM 215Z","networkAddress":24219522,"type":"GreenPower"},"last_seen":"2024-09-02T12:39:32.322Z","linkquality":127}'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","manufacturerID":null,"model":"PTM 215Z","networkAddress":24219522,"type":"GreenPower"},"last_seen":"2024-09-02T12:39:32.322Z","linkquality":127}'
info  2024-09-02 14:39:32: MQTT publish: topic 'homeassistant/device_automation/0x0000000001718f82/action_press_3/config', payload '{"automation_type":"trigger","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82"},"payload":"press_3","subtype":"press_3","topic":"zigbee2mqtt/0x0000000001718f82/action","type":"action"}'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82/action', payload 'press_3'
debug 2024-09-02 14:39:32: Received MQTT message on 'homeassistant/device_automation/0x0000000001718f82/action_press_3/config' with data '{"automation_type":"trigger","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82"},"payload":"press_3","subtype":"press_3","topic":"zigbee2mqtt/0x0000000001718f82/action","type":"action"}'
debug 2024-09-02 14:39:32: Received Zigbee message from 'Stokstaart', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[51,22,255],"type":"Buffer"}},"commandID":19,"frameCounter":9637,"gppGddLink":255,"gppNwkAddr":5683,"options":21640,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID null
debug 2024-09-02 14:39:32: No converter available for 'HG06337' with cluster 'greenPower' and type 'commandNotification' and data '{"commandFrame":{"raw":{"data":[51,22,255],"type":"Buffer"}},"commandID":19,"frameCounter":9637,"gppGddLink":255,"gppNwkAddr":5683,"options":21640,"payloadSize":0,"srcID":24219522}'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/Stokstaart', payload '{"device":{"applicationVersion":66,"dateCode":"","friendlyName":"Stokstaart","hardwareVersion":1,"ieeeAddr":"0xb4e3f9fffea30e8b","manufacturerID":4098,"manufacturerName":"_TZ3000_kdi2o9m6","model":"HG06337","networkAddress":5683,"powerSource":"Mains (single phase)","stackVersion":0,"type":"Router","zclVersion":3},"indicator_mode":null,"last_seen":"2024-09-02T12:39:32.369Z","linkquality":199,"power_on_behavior":null,"state":"OFF"}'
debug 2024-09-02 14:39:32: Received Zigbee message from 'Bollie', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[244,62,255],"type":"Buffer"}},"commandID":19,"frameCounter":9637,"gppGddLink":255,"gppNwkAddr":16116,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID null
debug 2024-09-02 14:39:32: No converter available for 'LED1624G9' with cluster 'greenPower' and type 'commandNotification' and data '{"commandFrame":{"raw":{"data":[244,62,255],"type":"Buffer"}},"commandID":19,"frameCounter":9637,"gppGddLink":255,"gppNwkAddr":16116,"options":21672,"payloadSize":0,"srcID":24219522}'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/Bollie', payload '{"brightness":176,"color":{"x":0.249,"y":0.632},"color_mode":"xy","color_temp":312,"device":{"applicationVersion":17,"dateCode":"20220816","friendlyName":"Bollie","hardwareVersion":1,"ieeeAddr":"0x842e14fffea67a91","manufacturerID":4476,"manufacturerName":"IKEA of Sweden","model":"LED1624G9","networkAddress":16116,"powerSource":"Mains (single phase)","softwareBuildID":"2.3.093","stackVersion":87,"type":"Router","zclVersion":3},"last_seen":"2024-09-02T12:39:32.393Z","linkquality":247,"power_on_behavior":"on","state":"OFF","update":{"installed_version":587806257,"latest_version":587806257,"state":"idle"},"update_available":false}'`

debug 2024-09-02 14:39:32: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{},"commandID":23,"frameCounter":9638,"options":0,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948

info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"release_3","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","manufacturerID":null,"model":"PTM 215Z","networkAddress":24219522,"type":"GreenPower"},"last_seen":"2024-09-02T12:39:32.493Z","linkquality":127}'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","manufacturerID":null,"model":"PTM 215Z","networkAddress":24219522,"type":"GreenPower"},"last_seen":"2024-09-02T12:39:32.493Z","linkquality":127}'
info  2024-09-02 14:39:32: MQTT publish: topic 'homeassistant/device_automation/0x0000000001718f82/action_release_3/config', payload '{"automation_type":"trigger","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82"},"payload":"release_3","subtype":"release_3","topic":"zigbee2mqtt/0x0000000001718f82/action","type":"action"}'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82/action', payload 'release_3'
debug 2024-09-02 14:39:32: Received MQTT message on 'homeassistant/device_automation/0x0000000001718f82/action_release_3/config' with data '{"automation_type":"trigger","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82"},"payload":"release_3","subtype":"release_3","topic":"zigbee2mqtt/0x0000000001718f82/action","type":"action"}'
debug 2024-09-02 14:39:32: Received Zigbee message from 'Bollie', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[244,62,255],"type":"Buffer"}},"commandID":23,"frameCounter":9638,"gppGddLink":255,"gppNwkAddr":16116,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID null
debug 2024-09-02 14:39:32: No converter available for 'LED1624G9' with cluster 'greenPower' and type 'commandNotification' and data '{"commandFrame":{"raw":{"data":[244,62,255],"type":"Buffer"}},"commandID":23,"frameCounter":9638,"gppGddLink":255,"gppNwkAddr":16116,"options":21672,"payloadSize":0,"srcID":24219522}'
info  2024-09-02 14:39:32: MQTT publish: topic 'zigbee2mqtt/Bollie', payload '{"brightness":176,"color":{"x":0.249,"y":0.632},"color_mode":"xy","color_temp":312,"device":{"applicationVersion":17,"dateCode":"20220816","friendlyName":"Bollie","hardwareVersion":1,"ieeeAddr":"0x842e14fffea67a91","manufacturerID":4476,"manufacturerName":"IKEA of Sweden","model":"LED1624G9","networkAddress":16116,"powerSource":"Mains (single phase)","softwareBuildID":"2.3.093","stackVersion":87,"type":"Router","zclVersion":3},"last_seen":"2024-09-02T12:39:32.545Z","linkquality":255,"power_on_behavior":"on","state":"OFF","update":{"installed_version":587806257,"latest_version":587806257,"state":"idle"},"update_available":false}'
debug 2024-09-02 14:39:32: Received Zigbee message from 'Stokstaart', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[51,22,255],"type":"Buffer"}},"commandID":23,"frameCounter":9638,"gppGddLink":255,"gppNwkAddr":5683,"options":21640,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID null
debug 2024-09-02 14:39:32: No converter available for 'HG06337' with cluster 'greenPower' and type 'commandNotification' and data '{"commandFrame":{"raw":{"data":[51,22,255],"type":"Buffer"}},"commandID":23,"frameCounter":9638,"gppGddLink":255,"gppNwkAddr":5683,"options":21640,"payloadSize":0,"srcID":24219522}'

Where it's clear that the PTM215Z with address 1718f82 sends a few button presses and they are directly read

How to reproduce it (minimal and precise)

No response

Zigbee2MQTT version

1.37.0 and later

Adapter firmware version

0x26680700

Adapter

conbee II

Setup

docker on synology ds216+II

Debug log

log_v140_truncated.log

posixx commented 2 months ago

I have a PTM 215Z on my network and it works without issues running 1.39.1-1. So i think i will wait upgrading to 1.40 for now..

chris-1243 commented 2 months ago

It might be related to deconz driver. I am using Z2M 1.40 on zstack and all my PTM215Z are working as they should

@coendaar How did you pair your PTM215Z ?

coendaar commented 2 months ago

@chris-1243 I paired as follows=>

I don’t reallly get how the log in v1.31 can be full of no converter available for xxx with cluster greenpower and yet if still works. However in v1.40 i get the same errors and it doesn’t work.

chris-1243 commented 2 months ago

As I told you and from Z2M 1.31 to the latest 1.40, some changes might have occured in deconz driver...

Would you try to pair one of your PTM215Z as suggested in this link

https://www.zigbee2mqtt.io/advanced/zigbee/01_zigbee_network.html#green-power-devices

posixx commented 2 months ago

It has to be the deconz driver as i upgraded to 1.40 and no problems here using Tubezb controller.

coendaar commented 2 months ago

As I told you and from Z2M 1.31 to the latest 1.40, some changes might have occured in deconz driver...

Would you try to pair one of your PTM215Z as suggested in this link

https://www.zigbee2mqtt.io/advanced/zigbee/01_zigbee_network.html#green-power-devices

As requested: Using v 1.36.1:

Next steps:

The switch doesn't work. Log in v 1.40:


[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: GP data notification
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x10 frameCounter: 0x25e8
[2024-09-03 13:45:08] debug:    zh:controller:greenpower: Received unhandled command '0x10' from '24219522'
[2024-09-03 13:45:08] debug:    zh:controller: Data is from unknown device with address '24219522', skipping...
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: GP data notification
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-03 13:45:08] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:45:08] debug:    zh:deconz:driver: query aps data indication
[2024-09-03 13:45:08] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 61
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 61 srcAddr: 0x8f82 destAddr: 0xdd09 profile id: 0xa1e0 cluster id: 0x21 lqi: 95
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: response payload: 17,45,0,160,84,130,143,113,1,232,37,0,0,16,0,143,44,255
[2024-09-03 13:45:08] debug:    zh:controller:greenpower: Received unhandled command '0x10' from '36738'
[2024-09-03 13:45:08] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:45:08] debug:    zh:controller: Data is from unknown device with address '36738', skipping...
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-03 13:45:08] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:45:08] debug:    zh:deconz:driver: query aps data indication
[2024-09-03 13:45:08] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 62
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 62 srcAddr: 0x3ef4 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: response payload: 17,24,0,168,84,130,143,113,1,232,37,0,0,16,0,244,62,255
[2024-09-03 13:45:08] debug:    zh:controller:greenpower: Received unhandled command '0x10' from '16116'
[2024-09-03 13:45:08] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:45:08] debug:    zh:controller: Data is from unknown device with address '16116', skipping...
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-03 13:45:08] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-03 13:45:08] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:45:08] debug:    zh:deconz:driver: query aps data indication
[2024-09-03 13:45:08] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 63
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 63 srcAddr: 0x9169 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-03 13:45:08] debug:    zh:deconz:frameparser: response payload: 17,60,0,168,84,130,143,113,1,232,37,0,0,16,0,105,145,255

Then i tried:

Then i tried

Then I tried

After digging in the logs I find this Assertionerror during the join process:

error 2024-09-03 14:02:10zh:controller:greenpower: AssertionError [ERR_ASSERTION]: Failed to find green power proxy device at GreenPower.sendPairingCommand (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:95:19) at GreenPower.onZclGreenPowerData (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:181:25) at Controller.onZclPayload (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:688:13)

This one from joining the Ikea light:

[2024-09-03 13:54:21] debug:    zh:deconz:frameparser: GP commissioning notification
[2024-09-03 13:54:21] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-03 13:54:21] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:54:21] debug:    zh:deconz:driver: query aps data indication
[2024-09-03 13:54:21] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-03 13:54:21] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 210
[2024-09-03 13:54:21] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 211 paramId: 7
[2024-09-03 13:54:21] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 210 srcAddr: 0xaef2 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 247
[2024-09-03 13:54:21] debug:    zh:deconz:frameparser: response payload: 17,106,4,0,8,130,143,113,1,237,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,237,37,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,242,174,216
[2024-09-03 13:54:21] info:     zh:controller:greenpower: Received commissioning from '44786'
[2024-09-03 13:54:21] debug:    zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
[2024-09-03 13:54:21] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 13:54:21] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-03 13:54:22] error:    zh:controller:greenpower: AssertionError [ERR_ASSERTION]: Failed to find green power proxy device
    at GreenPower.sendPairingCommand (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:95:19)
    at GreenPower.onZclGreenPowerData (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:181:25)
    at Controller.onZclPayload (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:688:13)
[2024-09-03 13:54:22] debug:    zh:controller: Data is from unknown device with address '24219522', skipping...
[2024-09-03 13:54:22] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 212 paramId: 1
[2024-09-03 13:54:22] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-03 13:54:22] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 213 paramId: 7
[2024-09-03 13:54:22] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-03 13:54:22] debug:    zh:deconz: no response expected (24)
[2024-09-03 13:54:22] debug:    zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
[2024-09-03 13:54:22] debug:    zh:controller: Deleted green power device '0x0000000001718f82' joined, undeleting
[2024-09-03 13:54:22] debug:    zh:controller:database: Writing database to '/app/data/database.db'
[2024-09-03 13:54:23] info:     z2m: Device '0x0000000001718f82' joined
[2024-09-03 13:54:24] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":{"friendly_name":"0x0000000001718f82"},"type":"device_connected"}'
[2024-09-03 13:54:24] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/action/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":true,"entity_category":"diagnostic","icon":"mdi:gesture-double-tap","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Action","object_id":"0x0000000001718f82_action","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_action_zigbee2mqtt","value_template":"{{ value_json.action }}"}'
[2024-09-03 13:54:24] info:     z2m: Successfully interviewed '0x0000000001718f82', device has successfully been paired
[2024-09-03 13:54:24] info:     z2m: Device '0x0000000001718f82' is supported, identified as: EnOcean Pushbutton transmitter module (PTM 215Z)
[2024-09-03 13:54:24] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"interview_successful","meta":{"description":"Pushbutton transmitter module","friendly_name":"0x0000000001718f82","model":"PTM 215Z","supported":true,"vendor":"EnOcean"},"type":"pairing"}'
[2024-09-03 13:54:24] debug:    zh:controller: Data is from unknown device with address '44786', skipping...
[2024-09-03 13:54:24] debug:    zh:deconz:driver: DATA_REQUEST - destAddr: 0xaef2 EP:242 SeqNr. 214 request id: 35
[2024-09-03 13:54:24] debug:    zh:deconz:frameparser: GP data notification
[2024-09-03 13:54:24] debug:    zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x68 frameCounter: 0x25ee
[2024-09-03 13:54:24] debug:    zh:controller:greenpower: Received unhandled command '0x68' from '24219522'

This from joining the adurosmart device

[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: GP commissioning notification
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0xe0 frameCounter: 0x25f6
[2024-09-03 14:02:09] info:     zh:controller:greenpower: Received commissioning from '24219522'
[2024-09-03 14:02:09] debug:    zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
[2024-09-03 14:02:09] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 127 paramId: 1
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: GP commissioning notification
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-03 14:02:09] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 128 paramId: 7
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-03 14:02:09] error:    zh:controller:greenpower: AssertionError [ERR_ASSERTION]: Failed to find green power proxy device
    at GreenPower.sendPairingCommand (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:95:19)
    at GreenPower.onZclGreenPowerData (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:181:25)
    at Controller.onZclPayload (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:688:13)
[2024-09-03 14:02:09] debug:    zh:controller: Data is from unknown device with address '24219522', skipping...
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-03 14:02:09] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 14:02:09] debug:    zh:deconz:driver: query aps data indication
[2024-09-03 14:02:09] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 129
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 129 srcAddr: 0x709 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: response payload: 17,251,4,0,8,130,143,113,1,246,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,246,37,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,9,7,11
[2024-09-03 14:02:09] info:     zh:controller:greenpower: Received commissioning from '1801'
[2024-09-03 14:02:09] debug:    zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
[2024-09-03 14:02:09] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-03 14:02:09] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 130 paramId: 1
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-03 14:02:09] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 131 paramId: 7
[2024-09-03 14:02:09] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-03 14:02:09] debug:    zh:deconz: no response expected (35)
[2024-09-03 14:02:09] debug:    zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
[2024-09-03 14:02:09] debug:    zh:controller: Deleted green power device '0x0000000001718f82' joined, undeleting
[2024-09-03 14:02:09] debug:    zh:controller:database: Writing database to '/app/data/database.db'

And searching the log for 'green' shows (top left button press+release, wait 10 seconds, top right button press+release):

Line 5089: [2024-09-03 14:21:34] debug:     zh:controller:greenpower: Received unhandled command '0x10' from '24219522'
    Line 5098: [2024-09-03 14:21:34] debug:     zh:controller:greenpower: Received unhandled command '0x10' from '36738'
    Line 5107: [2024-09-03 14:21:34] debug:     zh:controller:greenpower: Received unhandled command '0x10' from '16116'
    Line 5116: [2024-09-03 14:21:34] debug:     zh:controller:greenpower: Received unhandled command '0x10' from '44786'
    Line 5123: [2024-09-03 14:21:34] debug:     zh:controller:greenpower: Received unhandled command '0x10' from '37225'
    Line 5130: [2024-09-03 14:21:34] debug:     zh:controller:greenpower: Received unhandled command '0x10' from '5683'
    Line 5139: [2024-09-03 14:21:34] debug:     zh:controller:greenpower: Received unhandled command '0x10' from '1801'
    Line 5144: [2024-09-03 14:21:35] debug:     zh:controller:greenpower: Received unhandled command '0x14' from '24219522'
    Line 5155: [2024-09-03 14:21:35] debug:     zh:controller:greenpower: Received unhandled command '0x14' from '36738'
    Line 5164: [2024-09-03 14:21:35] debug:     zh:controller:greenpower: Received unhandled command '0x14' from '16116'
    Line 5171: [2024-09-03 14:21:35] debug:     zh:controller:greenpower: Received unhandled command '0x14' from '37225'
    Line 5180: [2024-09-03 14:21:35] debug:     zh:controller:greenpower: Received unhandled command '0x14' from '44786'
    Line 5189: [2024-09-03 14:21:35] debug:     zh:controller:greenpower: Received unhandled command '0x14' from '5683'
    Line 5198: [2024-09-03 14:21:35] debug:     zh:controller:greenpower: Received unhandled command '0x14' from '1801'
    Line 5227: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x13' from '24219522'
    Line 5237: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x13' from '36738'
    Line 5248: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x13' from '16116'
    Line 5257: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x13' from '44786'
    Line 5264: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x13' from '5683'
    Line 5271: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x13' from '37225'
    Line 5276: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x17' from '24219522'
    Line 5286: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x17' from '36738'
    Line 5302: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x17' from '16116'
    Line 5309: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x17' from '5683'
    Line 5320: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x17' from '37225'
    Line 5327: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x13' from '1801'
    Line 5336: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x17' from '44786'
    Line 5345: [2024-09-03 14:21:41] debug:     zh:controller:greenpower: Received unhandled command '0x17' from '1801'

So conclusions/assumptions:

chris-1243 commented 2 months ago

I had a look in the thread you referenced and it seems related to deconz only.

I guess something is not working/coded correctly in deconz. My main network is using zstack without issue and I checked ember and everything works as it should be now with this driver as well.

The best way to know if your PTM215Z are paired as unicast is to sniff trafic. A recent issue #22897 found some problem with HUE devices and latest firmware. The list provided is not exhaustive and has to be completed for sure. If no ZigbeeGreen clusters, it should not work as a proxy.

coendaar commented 2 months ago

Assuming the deconz driver is in zigbee-herdsman,

The changelogs show significant refactoring of deconz and Greenpower between these versions. Most of it from @Koenkk

But i would have expected other users to have also noticed this. Only this user seems to have same issue, and he is also stuck on 1.36.1: https://github.com/Koenkk/zigbee2mqtt/issues/22578#issuecomment-2307636321

Nerivec commented 2 months ago

@Koenkk I suggest starting by re-writing https://github.com/Koenkk/zigbee-herdsman/blob/19757aefe1229bd677cda0011fd3691ca8da6abd/src/adapter/deconz/adapter/deconzAdapter.ts#L1231-L1268 to something like this:

    private checkReceivedGreenPowerIndication(ind: gpDataInd): void {
        const gpdHeader = Buffer.alloc(15); // applicationId === IEEE_ADDRESS ? 20 : 15
        gpdHeader.writeUInt8(0b00000001, 0); // frameControl: FrameType.SPECIFIC + Direction.CLIENT_TO_SERVER + disableDefaultResponse=false
        gpdHeader.writeUInt8(ind.seqNr!, 1);
        gpdHeader.writeUInt8(ind.id!, 2); // commandIdentifier
        gpdHeader.writeUInt16LE(0, 3); // options, only srcID present
        gpdHeader.writeUInt32LE(ind.srcId!, 5);
        // omitted: gpdIEEEAddr (ieeeAddr)
        // omitted: gpdEndpoint (uint8)
        gpdHeader.writeUInt32LE(ind.frameCounter!, 9);
        gpdHeader.writeUInt8(ind.commandId!, 13);
        gpdHeader.writeUInt8(ind.commandFrameSize!, 14);

        // TODO: change `commandFrame` parsing type to Buffer to avoid unnecessary re-conversion
        const payBuf = Buffer.concat([gpdHeader, Buffer.from(ind.commandFrame!)]);
        const payload: Events.ZclPayload = {
            header: Zcl.Header.fromBuffer(payBuf),
            data: payBuf,
            clusterID: Zcl.Clusters.greenPower.ID,
            address: ind.srcId! & 0xffff,
            endpoint: ZSpec.GP_ENDPOINT,
            linkquality: 127,// bogus
            groupID: ZSpec.GP_GROUP_ID,
            wasBroadcast: true,// TODO: take the codepath that doesn't require `gppNwkAddr` (true) or not (false)?
            destinationEndpoint: ZSpec.GP_ENDPOINT,
        };

        this.waitress.resolve(payload);
        this.emit('zclPayload', payload);
    }

It should clean things up and align behavior with ember.

Koenkk commented 2 months ago

Thanks @Nerivec !

I was able to reproduce the issue (Failed to find green power proxy device) with my Deconz adapter

@coendaar can you see if this fixes the issue:

docker exec -it CONTAINER_NAME sh
apk add nano
echo > /app/node_modules/zigbee-herdsman/dist/adapter/deconz/adapter/deconzAdapter.js
nano /app/node_modules/zigbee-herdsman/dist/adapter/deconz/adapter/deconzAdapter.js
# Update with https://gist.github.com/Koenkk/3f8a69b8aefeefa27a3a1ed5d13801a6
# save and exit nano
exit
docker restart CONTAINER_NAME
coendaar commented 2 months ago

@Koenkk thanx for looking in to this.

Do i need to re-pair the switch to test this? Or try if works instantly?

Koenkk commented 2 months ago

@coendaar try to send the pairing command again (so holding button of the corresponding channel + energy bar for 7 secs)

coendaar commented 2 months ago

Ok. I’ll test it in about an hour..

coendaar commented 2 months ago

Ok. Without re-pairing.. a button press still results in an error:

zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x13 frameCounter: 0x261c
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 102
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 102 srcAddr: 0x8f82 destAddr: 0xdd09 profile id: 0xa1e0 cluster id: 0x21 lqi: 239
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: response payload: 17,14,0,168,84,130,143,113,1,28,38,0,0,19,0,105,145,255
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 103
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 103 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 175
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:frameparser: response payload: 17,70,0,136,84,130,143,113,1,28,38,0,0,19,0,51,22,255
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '5683'
zigbee2mqtt                 | [2024-09-05 13:42:31] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
coendaar commented 2 months ago

and the pairing (permit join: all) gives an error:

zigbee2mqtt                 | [2024-09-05 13:44:51] info:    zh:controller:greenpower: Received commissioning from '36738'
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:driver: send read parameter request from queue. seqNr: 165 paramId: 1
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:driver: send read parameter request from queue. seqNr: 166 paramId: 7
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 13:44:51] error:   zh:controller:greenpower: TypeError: value.slice is not a function
zigbee2mqtt                 |     at BuffaloZcl.writeIeeeAddr (/app/node_modules/zigbee-herdsman/src/buffalo/buffalo.ts:183:41)
zigbee2mqtt                 |     at BuffaloZcl.write (/app/node_modules/zigbee-herdsman/src/zspec/zcl/buffaloZcl.ts:855:29)
zigbee2mqtt                 |     at ZclFrame.writePayloadCluster (/app/node_modules/zigbee-herdsman/src/zspec/zcl/zclFrame.ts:136:21)
zigbee2mqtt                 |     at ZclFrame.toBuffer (/app/node_modules/zigbee-herdsman/src/zspec/zcl/zclFrame.ts:77:18)
zigbee2mqtt                 |     at DeconzAdapter.sendZclFrameToEndpoint (/app/node_modules/zigbee-herdsman/src/adapter/deconz/adapter/deconzAdapter.ts:892:29)
zigbee2mqtt                 |     at GreenPower.sendPairingCommand (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:96:33)
zigbee2mqtt                 |     at GreenPower.onZclGreenPowerData (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:181:25)
zigbee2mqtt                 |     at Controller.onZclPayload (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:688:13)
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 167
zigbee2mqtt                 | [2024-09-05 13:44:51] debug:   zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 29 confirm status: 0
coendaar commented 2 months ago

after deleting the switch and permit join to an ikea https://www.zigbee2mqtt.io/devices/LED1733G7.html#ikea-led1733g7

The switch still doesn't work (but i'm not sure if the 1733G7 is a suitable ZGP translator

the log is as follow:

zigbee2mqtt                 | [2024-09-05 13:51:02] info:       zh:controller:greenpower: Received commissioning from '44786'
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 17 paramId: 1
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 18 paramId: 7
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz: no response expected (34)
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:controller: Data is from unknown device with address '44786', skipping...
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0xaef2 EP:242 SeqNr. 19 request id: 46
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 46 status: 0
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 13:51:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
Koenkk commented 2 months ago

Updated https://gist.github.com/Koenkk/3f8a69b8aefeefa27a3a1ed5d13801a6, can you try again?

coendaar commented 2 months ago

Sure. Here’s the second attempt:

join-all without prior removal: Switch doesn't work.

zigbee2mqtt                 | [2024-09-05 14:30:19] info:       z2m: Zigbee: allowing new devices to join.
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffc EP:0 SeqNr. 35 request id: 7
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 7 status: 0
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 36
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffc request id: 7 confirm status: 0
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: send write parameter request from queue. seqNr: 37 paramId: 33 param: 254
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:frameparser: write parameter response - parameter id: 33 - status: 0
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz: PERMIT_JOIN - 254 seconds
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz: zclFrame to all - zclFrame.payload:
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz: [object Object]
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz: sendZclFrameToAll - message send
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 38 request id: 8
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 8 status: 0
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 39
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 8 confirm status: 0
zigbee2mqtt                 | [2024-09-05 14:30:19] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:19] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/response/permit_join', payload '{"data":{"time":254,"value":true},"status":"ok","transaction":"o70z8-4"}'
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x13 frameCounter: 0x262d
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '24219522'
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:controller: Data is from unknown device with address '24219522', skipping...
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 40
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 40 srcAddr: 0x8f82 destAddr: 0xdd09 profile id: 0xa1e0 cluster id: 0x21 lqi: 247
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: response payload: 17,12,0,160,84,130,143,113,1,45,38,0,0,19,0,143,44,255
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 41
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 41 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 148
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: response payload: 17,91,4,32,11,130,143,113,1,45,38,0,0,19,0,84,73,144,0,82,213,218
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 42
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 42 srcAddr: 0x3ef4 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 191
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:frameparser: response payload: 17,121,0,168,84,130,143,113,1,45,38,0,0,19,0,244,62,255
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '16116'
zigbee2mqtt                 | [2024-09-05 14:30:24] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1

Join-all after force-remove error@14:37:35:

zigbee2mqtt                 | [2024-09-05 14:37:33] info:       zh:controller:greenpower: Received commissioning from '36738'
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 188 paramId: 1
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 189 paramId: 1
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 191
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 190 paramId: 7
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 31 confirm status: 0
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:controller: Deleted green power device '0x0000000001718f82' joined, undeleting
zigbee2mqtt                 | [2024-09-05 14:37:33] debug:      zh:controller:database: Writing database to '/app/data/database.db'
zigbee2mqtt                 | [2024-09-05 14:37:34] info:       z2m: Device '0x0000000001718f82' joined
zigbee2mqtt                 | [2024-09-05 14:37:34] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":{"friendly_name":"0x0000000001718f82"},"type":"device_connected"}'
zigbee2mqtt                 | [2024-09-05 14:37:34] info:       z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/action/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":true,"entity_category":"diagnostic","icon":"mdi:gesture-double-tap","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Action","object_id":"0x0000000001718f82_action","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_action_zigbee2mqtt","value_template":"{{ value_json.action }}"}'
zigbee2mqtt                 | [2024-09-05 14:37:34] info:       z2m: Successfully interviewed '0x0000000001718f82', device has successfully been paired
zigbee2mqtt                 | [2024-09-05 14:37:34] info:       z2m: Device '0x0000000001718f82' is supported, identified as: EnOcean Pushbutton transmitter module (PTM 215Z)
zigbee2mqtt                 | [2024-09-05 14:37:34] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"interview_successful","meta":{"description":"Pushbutton transmitter module","friendly_name":"0x0000000001718f82","model":"PTM 215Z","supported":true,"vendor":"EnOcean"},"type":"pairing"}'
zigbee2mqtt                 | [2024-09-05 14:37:34] debug:      zh:controller: Data is from unknown device with address '24219522', skipping...
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:37:35] error:      zh:controller:greenpower: TypeError: value.slice is not a function
zigbee2mqtt                 |     at BuffaloZcl.writeIeeeAddr (/app/node_modules/zigbee-herdsman/src/buffalo/buffalo.ts:183:41)
zigbee2mqtt                 |     at BuffaloZcl.write (/app/node_modules/zigbee-herdsman/src/zspec/zcl/buffaloZcl.ts:855:29)
zigbee2mqtt                 |     at ZclFrame.writePayloadCluster (/app/node_modules/zigbee-herdsman/src/zspec/zcl/zclFrame.ts:136:21)
zigbee2mqtt                 |     at ZclFrame.toBuffer (/app/node_modules/zigbee-herdsman/src/zspec/zcl/zclFrame.ts:77:18)
zigbee2mqtt                 |     at DeconzAdapter.sendZclFrameToEndpoint (/app/node_modules/zigbee-herdsman/src/adapter/deconz/adapter/deconzAdapter.ts:859:9)
zigbee2mqtt                 |     at GreenPower.sendPairingCommand (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:96:33)
zigbee2mqtt                 |     at GreenPower.onZclGreenPowerData (/app/node_modules/zigbee-herdsman/src/controller/greenPower.ts:181:25)
zigbee2mqtt                 |     at Controller.onZclPayload (/app/node_modules/zigbee-herdsman/src/controller/controller.ts:688:13)
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 192 paramId: 7
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:deconz: no response expected (18)
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 14:37:35] debug:      zh:controller: Data is from unknown device with address '36738', skipping...

Join Ikea bulb after force remove:

zigbee2mqtt                 | [2024-09-05 14:41:04] info:       zh:controller:greenpower: Received commissioning from '36738'
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:controller:greenpower: Payload.Options: 58696 wasBroadcast: true
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz: zclFrame to all - zclFrame.payload:
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz: [object Object]
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz: sendZclFrameToAll - message send
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 70 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:frameparser: response payload: 17,98,0,160,84,130,143,113,1,56,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,56,38,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,84,73,145
zigbee2mqtt                 | [2024-09-05 14:41:04] info:       zh:controller:greenpower: Received commissioning from '36738'
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:controller:greenpower: Payload.Options: 58696 wasBroadcast: true
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz: zclFrame to all - zclFrame.payload:
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz: [object Object]
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz: sendZclFrameToAll - message send
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 71 request id: 63
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 73
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 63 status: 0
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 63 status: 0
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 72 request id: 64
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 74
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 62 confirm status: 0
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 64 status: 0
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 64 status: 0
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:controller: Deleted green power device '0x0000000001718f82' joined, undeleting
zigbee2mqtt                 | [2024-09-05 14:41:04] debug:      zh:controller:database: Writing database to '/app/data/database.db'
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m: Device '0x0000000001718f82' joined
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":{"friendly_name":"0x0000000001718f82"},"type":"device_connected"}'
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/action/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":true,"entity_category":"diagnostic","icon":"mdi:gesture-double-tap","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Action","object_id":"0x0000000001718f82_action","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_action_zigbee2mqtt","value_template":"{{ value_json.action }}"}'
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m: Successfully interviewed '0x0000000001718f82', device has successfully been paired
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m: Device '0x0000000001718f82' is supported, identified as: EnOcean Pushbutton transmitter module (PTM 215Z)
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"interview_successful","meta":{"description":"Pushbutton transmitter module","friendly_name":"0x0000000001718f82","model":"PTM 215Z","supported":true,"vendor":"EnOcean"},"type":"pairing"}'
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Data is from unknown device with address '24219522', skipping...
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101110
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101110
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101110
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101110
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 75
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 76
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 63 confirm status: 0
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 76 srcAddr: 0xaef2 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 239
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: response payload: 17,62,4,0,8,130,143,113,1,56,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,56,38,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,242,174,210
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       zh:controller:greenpower: Received commissioning from '44786'
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 76 srcAddr: 0xaef2 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 239
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: response payload: 17,62,4,0,8,130,143,113,1,56,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,56,38,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,242,174,210
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       zh:controller:greenpower: Received commissioning from '44786'
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/linkquality/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":false,"entity_category":"diagnostic","icon":"mdi:signal","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Linkquality","object_id":"0x0000000001718f82_linkquality","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_class":"measurement","state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_linkquality_zigbee2mqtt","unit_of_measurement":"lqi","value_template":"{{ value_json.linkquality }}"}'
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 77 paramId: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 79
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x0000000001718f82","ieee_address":"0x0000000001718f82"},"type":"device_joined"}'
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"definition":{"description":"Pushbutton transmitter module","exposes":[{"access":1,"category":"diagnostic","description":"Triggered action (e.g. a button click)","label":"Action","name":"action","property":"action","type":"enum","values":["press_1","release_1","press_2","release_2","press_3","release_3","press_4","release_4","press_1_and_3","release_1_and_3","press_2_and_4","release_2_and_4","press_energy_bar"]},{"access":1,"category":"diagnostic","description":"Link quality (signal strength)","label":"Linkquality","name":"linkquality","property":"linkquality","type":"numeric","unit":"lqi","value_max":255,"value_min":0}],"model":"PTM 215Z","options":[],"supports_ota":false,"vendor":"EnOcean"},"friendly_name":"0x0000000001718f82","ieee_address":"0x0000000001718f82","status":"successful","supported":true},"type":"device_interview"}'
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 64 confirm status: 0
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 78 paramId: 1
zigbee2mqtt                 | [2024-09-05 14:41:05] info:       z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/last_seen/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"device_class":"timestamp","enabled_by_default":false,"entity_category":"diagnostic","icon":"mdi:clock","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Last seen","object_id":"0x0000000001718f82_last_seen","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_last_seen_zigbee2mqtt","value_template":"{{ value_json.last_seen }}"}'
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 80 paramId: 7
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz: no response expected (39)
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Data is from unknown device with address '44786', skipping...
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 81 paramId: 7
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0xaef2 EP:242 SeqNr. 82 request id: 65
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:deconz: no response expected (40)
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
zigbee2mqtt                 | [2024-09-05 14:41:05] debug:      zh:controller: Data is from unknown device with address '44786', skipping...

A button press is still not registered unfortunately

Koenkk commented 2 months ago

@coendaar to be sure, can you update your conbee to the latest versions?

coendaar commented 2 months ago

@Koenkk sure. I’ll try later tonight when I’m back home.

Just to be sure: https://www.zigbee2mqtt.io/guide/adapters/deconz.html still mentions an issue:

Warning: Conbee 2 firmware versions newer than 0x26580700 will result in an unstable network with devices dropping randomly, see Issue 9554

Is it safe to update to https://deconz.dresden-elektronik.de/deconz-firmware/deCONZ_ConBeeII_0x26780700.bin.GCF ?

Koenkk commented 2 months ago

It seems you are already on a new firmware than the problematic one so I guess it should be fine

coendaar commented 2 months ago

- Looking at the first lines from the above log, it looks promising. There is a source ID, and a command in the frame-parser.
- However the controller does nothing with it.  Note that src id: 0x1718f82 == decimal 24219522'

zigbee2mqtt | [2024-09-05 21:31:30] debug: zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x13 frameCounter: 0x2662 zigbee2mqtt | [2024-09-05 21:31:30] debug: zh:controller:greenpower: Received unhandled command '0x13' from '24219522'

Nerivec commented 2 months ago

@Koenkk Since your last gist revision reverts the srcID/nwkAddr masking in ZclPayload, it shows the received data seems to come in sometimes as plain GP (shows srcID instead since no longer masked in ZclPayload):

zh:controller:greenpower: Received unhandled command '0x13' from '24219522'
...
zh:controller: Data is from unknown device with address '24219522', skipping...

and sometimes as proxied GP (shows proper nwkAddr):

zh:controller:greenpower: Received unhandled command '0x13' from '36738'
...
zh:controller: Data is from unknown device with address '36738', skipping...

It's receiving the commissioning in duplicate too, once as plain, and once as proxied GP. So wasBroadcast could be either true/false depending, and the codepath would change accordingly.

Either way, it's acting like the device never joined?? so it never processes regular cluster commands.

Koenkk commented 2 months ago

@coendaar could you provide the debug logging again of pairing + a command with:

coendaar commented 2 months ago

ok. here we go:

pairing (join all) after a force remove:

zigbee2mqtt                 | [2024-09-05 22:51:00] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/response/permit_join', payload '{"data":{"time":254,"value":true},"status":"ok","transaction":"h2dd4-12"}'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x13 frameCounter: 0x2666
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 142
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 142 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: response payload: 17,35,0,160,84,130,143,113,1,102,38,0,0,19,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 142 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: response payload: 17,35,0,160,84,130,143,113,1,102,38,0,0,19,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 143
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 144
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 143 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: response payload: 17,117,4,32,11,130,143,113,1,102,38,0,0,19,0,84,73,138,54,84,36,118
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 143 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: response payload: 17,117,4,32,11,130,143,113,1,102,38,0,0,19,0,84,73,138,54,84,36,118
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 144 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: response payload: 17,107,0,168,84,130,143,113,1,102,38,0,0,19,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '5683'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 144 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:frameparser: response payload: 17,107,0,168,84,130,143,113,1,102,38,0,0,19,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '5683'
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:03] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:05] debug:      zh:deconz:driver: send read device state from queue. seqNr: 145
zigbee2mqtt                 | [2024-09-05 22:51:05] debug:      zh:deconz:frameparser: device state: 10100010
zigbee2mqtt                 | [2024-09-05 22:51:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:05] debug:      zh:deconz:frameparser: device state: 10100010
zigbee2mqtt                 | [2024-09-05 22:51:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: GP commissioning notification
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0xe0 frameCounter: 0x2667
zigbee2mqtt                 | [2024-09-05 22:51:12] info:       zh:controller:greenpower: Received commissioning from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:controller:greenpower: Payload.Options: 58696 wasBroadcast: true
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz: zclFrame to all - zclFrame.payload:
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz: [object Object]
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz: sendZclFrameToAll - message send
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: GP commissioning notification
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: GP commissioning notification
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: GP commissioning notification
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 146 request id: 34
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 147
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 34 status: 0
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 34 status: 0
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 147 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: response payload: 17,36,4,0,8,130,143,113,1,103,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,103,38,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,109,74,210
zigbee2mqtt                 | [2024-09-05 22:51:12] info:       zh:controller:greenpower: Received commissioning from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 147 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: response payload: 17,36,4,0,8,130,143,113,1,103,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,103,38,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,109,74,210
zigbee2mqtt                 | [2024-09-05 22:51:12] info:       zh:controller:greenpower: Received commissioning from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 148 paramId: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 150
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 149 paramId: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 34 confirm status: 0
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:deconz:frameparser: MAC: 0x00212effff07ad37
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined 24219522
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:controller: Deleted green power device '0x0000000001718f82' joined, undeleting
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:controller:database: Writing database to '/app/data/database.db'
zigbee2mqtt                 | [2024-09-05 22:51:12] debug:      zh:controller: GP device joined: 0x0000000001718f82 - 36738
zigbee2mqtt                 | [2024-09-05 22:51:12] info:       z2m: Device '0x0000000001718f82' joined
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":{"friendly_name":"0x0000000001718f82"},"type":"device_connected"}'
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/action/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":true,"entity_category":"diagnostic","icon":"mdi:gesture-double-tap","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Action","object_id":"0x0000000001718f82_action","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_action_zigbee2mqtt","value_template":"{{ value_json.action }}"}'
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m: Successfully interviewed '0x0000000001718f82', device has successfully been paired
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m: Device '0x0000000001718f82' is supported, identified as: EnOcean Pushbutton transmitter module (PTM 215Z)
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"interview_successful","meta":{"description":"Pushbutton transmitter module","friendly_name":"0x0000000001718f82","model":"PTM 215Z","supported":true,"vendor":"EnOcean"},"type":"pairing"}'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 151 paramId: 7
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: no response expected (9)
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined 24219522
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Device already joined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: GP device joined: 0x0000000001718f82 - 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: send read parameter request from queue. seqNr: 152 paramId: 7
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0x4a6d EP:242 SeqNr. 153 request id: 35
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: NWKADDR: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: no response expected (10)
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined 24219522
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Device already joined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: GP device joined: 0x0000000001718f82 - 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_REQUEST - destAddr: 0x4a6d EP:242 SeqNr. 154 request id: 36
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/linkquality/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":false,"entity_category":"diagnostic","icon":"mdi:signal","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Linkquality","object_id":"0x0000000001718f82_linkquality","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_class":"measurement","state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_linkquality_zigbee2mqtt","unit_of_measurement":"lqi","value_template":"{{ value_json.linkquality }}"}'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 35 status: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 35 status: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 36 status: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 36 status: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 155
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10100110
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 156
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x4a6d request id: 35 confirm status: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 9
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: false, true, false, 10000
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: resolve request (9)
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 157
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data confirm
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x0000000001718f82","ieee_address":"0x0000000001718f82"},"type":"device_joined"}'
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"definition":{"description":"Pushbutton transmitter module","exposes":[{"access":1,"category":"diagnostic","description":"Triggered action (e.g. a button click)","label":"Action","name":"action","property":"action","type":"enum","values":["press_1","release_1","press_2","release_2","press_3","release_3","press_4","release_4","press_1_and_3","release_1_and_3","press_2_and_4","release_2_and_4","press_energy_bar"]},{"access":1,"category":"diagnostic","description":"Link quality (signal strength)","label":"Linkquality","name":"linkquality","property":"linkquality","type":"numeric","unit":"lqi","value_max":255,"value_min":0}],"model":"PTM 215Z","options":[],"supports_ota":false,"vendor":"EnOcean"},"friendly_name":"0x0000000001718f82","ieee_address":"0x0000000001718f82","status":"successful","supported":true},"type":"device_interview"}'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x68 frameCounter: 0x2668
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller:greenpower: Received unhandled command '0x68' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101110
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101110
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x4a6d request id: 36 confirm status: 0
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 10
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: false, true, false, 10000
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz: resolve request (10)
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 158
zigbee2mqtt                 | [2024-09-05 22:51:13] info:       z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/last_seen/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"device_class":"timestamp","enabled_by_default":false,"entity_category":"diagnostic","icon":"mdi:clock","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Last seen","object_id":"0x0000000001718f82_last_seen","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_last_seen_zigbee2mqtt","value_template":"{{ value_json.last_seen }}"}'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 159
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 160
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 159 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 196
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: response payload: 17,37,0,168,84,130,143,113,1,104,38,0,0,104,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller:greenpower: Received unhandled command '0x68' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 159 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 196
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: response payload: 17,37,0,168,84,130,143,113,1,104,38,0,0,104,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller:greenpower: Received unhandled command '0x68' from '36738'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 161
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 160 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: response payload: 17,109,0,168,84,130,143,113,1,104,38,0,0,104,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller:greenpower: Received unhandled command '0x68' from '5683'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 160 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: response payload: 17,109,0,168,84,130,143,113,1,104,38,0,0,104,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller:greenpower: Received unhandled command '0x68' from '5683'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 162
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 161 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: response payload: 17,37,0,168,84,130,143,113,1,104,38,0,0,104,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller:greenpower: Received unhandled command '0x68' from '19053'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 161 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:frameparser: response payload: 17,37,0,168,84,130,143,113,1,104,38,0,0,104,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller:greenpower: Received unhandled command '0x68' from '19053'
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '19053', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:51:13] debug:      zh:controller: Data is from unknown device with address '19053', skipping...
zigbee2mqtt                 | [2024-09-05 22:51:15] debug:      zh:deconz:driver: send read device state from queue. seqNr: 163
zigbee2mqtt                 | [2024-09-05 22:51:15] debug:      zh:deconz:frameparser: device state: 10100010
zigbee2mqtt                 | [2024-09-05 22:51:15] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:15] debug:      zh:deconz:frameparser: device state: 10100010
zigbee2mqtt                 | [2024-09-05 22:51:15] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:25] debug:      zh:deconz:driver: send read device state from queue. seqNr: 164
zigbee2mqtt                 | [2024-09-05 22:51:25] debug:      zh:deconz:frameparser: device state: 10100010
zigbee2mqtt                 | [2024-09-05 22:51:25] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:51:25] debug:      zh:deconz:frameparser: device state: 10100010
zigbee2mqtt                 | [2024-09-05 22:51:25] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1

and a button press:


zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x13 frameCounter: 0x2669
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 207
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 207 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,12,0,160,84,130,143,113,1,105,38,0,0,19,0,143,44,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 207 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,12,0,160,84,130,143,113,1,105,38,0,0,19,0,143,44,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '36738'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 208
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 208 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,38,0,168,84,130,143,113,1,105,38,0,0,19,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '19053'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 208 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,38,0,168,84,130,143,113,1,105,38,0,0,19,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '19053'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '19053', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '19053', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 209
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 209 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,111,0,168,84,130,143,113,1,105,38,0,0,19,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '5683'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 209 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,111,0,168,84,130,143,113,1,105,38,0,0,19,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x13' from '5683'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x17 frameCounter: 0x266a
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x17' from '36738'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 210
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 210 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 100
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,39,0,168,84,130,143,113,1,106,38,0,0,23,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x17' from '36738'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 210 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 100
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,39,0,168,84,130,143,113,1,106,38,0,0,23,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x17' from '36738'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '36738', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 211
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 211 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,112,0,168,84,130,143,113,1,106,38,0,0,23,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x17' from '5683'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 211 srcAddr: 0x1633 destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,112,0,168,84,130,143,113,1,106,38,0,0,23,0,51,22,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x17' from '5683'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 5683
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '5683', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 212
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 212 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,39,0,168,84,130,143,113,1,106,38,0,0,23,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x17' from '19053'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 212 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:frameparser: response payload: 17,39,0,168,84,130,143,113,1,106,38,0,0,23,0,109,74,255
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller:greenpower: Received unhandled command '0x17' from '19053'
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '19053', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Receiced ZCL GP resolved: undefined
zigbee2mqtt                 | [2024-09-05 22:55:02] debug:      zh:controller: Data is from unknown device with address '19053', skipping...
zigbee2mqtt                 | [2024-09-05 22:55:05] debug:      zh:deconz:driver: send read device state from queue. seqNr: 213
zigbee2mqtt                 | [2024-09-05 22:55:05] debug:      zh:deconz:frameparser: device state: 10100010
zigbee2mqtt                 | [2024-09-05 22:55:05] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
Koenkk commented 2 months ago

I have the impression that there is a bug in the undelete code:

zh:controller: GP device joined: 0x0000000001718f82 - 36738
# GP device joins with network address 36738

 z2m: Device '0x0000000001718f82' joined
 z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":{"friendly_name":"0x0000000001718f82"},"type":"device_connected"}'
 z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/action/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":true,"entity_category":"diagnostic","icon":"mdi:gesture-double-tap","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Action","object_id":"0x0000000001718f82_action","origin":{"name":"Zigbee2MQTT","sw":"1.40.0","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_action_zigbee2mqtt","value_template":"{{ value_json.action }}"}'
 z2m: Successfully interviewed '0x0000000001718f82', device has successfully been paired
 z2m: Device '0x0000000001718f82' is supported, identified as: EnOcean Pushbutton transmitter module (PTM 215Z)
 z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"interview_successful","meta":{"description":"Pushbutton transmitter module","friendly_name":"0x0000000001718f82","model":"PTM 215Z","supported":true,"vendor":"EnOcean"},"type":"pairing"}'

# Now it tries to resolve the device with address 36738, which doesn't return any result.
zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zh:controller: Receiced ZCL GP resolved: undefined
zh:controller: Data is from unknown device with address '36738', skipping...

To prevent the undelete from happening, can you: force remove the device from the frontend, stop z2m, start z2m, try again and provide the log of this?

coendaar commented 2 months ago

ok.

zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x10 frameCounter: 0x2677
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '36738'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=36738, groupID=2948, endpoint=242, destinationEndpoint=242, wasBroadcast=true, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":247,"commandIdentifier":0},"payload":{"options":0,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{}},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{},"commandID":16,"frameCounter":9847,"options":0,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"press_1","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.617Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.617Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82/action', payload 'press_1'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: GP data notification
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 247
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 247 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,120,0,160,84,130,143,113,1,119,38,0,0,16,0,105,145,255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '36738'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 247 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,120,0,160,84,130,143,113,1,119,38,0,0,16,0,105,145,255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '36738'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=36738, groupID=2948, endpoint=242, destinationEndpoint=0, wasBroadcast=true, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":120,"commandIdentifier":0},"payload":{"options":21664,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[105,145,255]}},"gppNwkAddr":37225,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=36738, groupID=2948, endpoint=242, destinationEndpoint=0, wasBroadcast=true, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":120,"commandIdentifier":0},"payload":{"options":21664,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[105,145,255]}},"gppNwkAddr":37225,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[105,145,255],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":255,"gppNwkAddr":37225,"options":21664,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[105,145,255],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":255,"gppNwkAddr":37225,"options":21664,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.670Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.670Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 248
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 248 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,133,4,32,11,130,143,113,1,119,38,0,0,16,0,84,73,143,112,51,133,135
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '36738'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 248 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,133,4,32,11,130,143,113,1,119,38,0,0,16,0,84,73,143,112,51,133,135
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '36738'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=36738, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":133,"commandIdentifier":4},"payload":{"options":2848,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[84,73,143,112,51,133,135]}},"gppNwkAddr":18772,"gppGddLink":143},"command":{"ID":4,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]}],"name":"commissioningNotification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=36738, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":133,"commandIdentifier":4},"payload":{"options":2848,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[84,73,143,112,51,133,135]}},"gppNwkAddr":18772,"gppGddLink":143},"command":{"ID":4,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]}],"name":"commissioningNotification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandCommissioningNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[84,73,143,112,51,133,135],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":143,"gppNwkAddr":18772,"options":2848,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 0
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandCommissioningNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[84,73,143,112,51,133,135],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":143,"gppNwkAddr":18772,"options":2848,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 0
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.699Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.699Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 249
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DEVICE_STATE changed: 10101010
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 250
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 249 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,52,0,168,84,130,143,113,1,119,38,0,0,16,0,109,74,255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '19053'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 249 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,52,0,168,84,130,143,113,1,119,38,0,0,16,0,109,74,255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '19053'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=19053, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":52,"commandIdentifier":0},"payload":{"options":21672,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[109,74,255]}},"gppNwkAddr":19053,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 19053
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=19053, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":52,"commandIdentifier":0},"payload":{"options":21672,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[109,74,255]}},"gppNwkAddr":19053,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[109,74,255],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":255,"gppNwkAddr":19053,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 0
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[109,74,255],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":255,"gppNwkAddr":19053,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 0
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.730Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.730Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: query aps data indication
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 251
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 250 srcAddr: 0x2c8f destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,36,0,168,84,130,143,113,1,119,38,0,0,16,0,143,44,255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '11407'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 250 srcAddr: 0x2c8f destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:frameparser: response payload: 17,36,0,168,84,130,143,113,1,119,38,0,0,16,0,143,44,255
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller:greenpower: Received unhandled command '0x10' from '11407'
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 11407
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=11407, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":36,"commandIdentifier":0},"payload":{"options":21672,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[143,44,255]}},"gppNwkAddr":11407,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP: 24219522 - 36738, got it from router 11407
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Receiced ZCL GP resolved: 36738
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      zh:controller: Received payload: clusterID=33, address=11407, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":36,"commandIdentifier":0},"payload":{"options":21672,"srcID":24219522,"frameCounter":9847,"commandID":16,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[143,44,255]}},"gppNwkAddr":11407,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[143,44,255],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":255,"gppNwkAddr":11407,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 0
zigbee2mqtt                 | [2024-09-05 23:16:30] debug:      z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[143,44,255],"type":"Buffer"}},"commandID":16,"frameCounter":9847,"gppGddLink":255,"gppNwkAddr":11407,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 0
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.757Z","linkquality":255}'
zigbee2mqtt                 | [2024-09-05 23:16:30] info:       z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-05T21:16:30.757Z","linkquality":255}'
Koenkk commented 2 months ago

but it looks like it gets a lót of duplicate messages.. So that is maybe the unicast/broadcast joining...

Yes but I would say this is a feature and not a bug. It means that every GPP proxy will now forward messages of this GP device, which allows you to move the device around freely. Since if only 1 GPP proxy would forward messages and the GP device gets out of range, you will not get any messages at all.

Note that although you get multiple messages for the same click, only 1 action is published ({"action":"press_1"}) since the frameCounter is the same. The reason why you see many z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82' is because you have enabled the last seen functionality, this will update the last seen whenever a Zigbee message is received from the device.

I've merged https://github.com/Koenkk/zigbee-herdsman/pull/1175 so the GPP part is fixed now. That still leaves the undelete bug which I cannot reproduce.

Can you:

coendaar commented 2 months ago

@Koenkk Nice! As requested:

Koenkk commented 2 months ago

Looks good, seems that the added logging changes the behaviour. @Nerivec just did a nice improvement in this area (https://github.com/Koenkk/zigbee-herdsman/pull/1176). Can you check again with the latest-dev if all works? (without any changed, also check the force remove -> repair case)

coendaar commented 2 months ago

testing: 1.40.0-dev commit: [0d46354](https://github.com/Koenkk/zigbee2mqtt/commit/0d46354) 20.11.0 0.57.3

force remove:

[2024-09-07 00:15:09] debug:    z2m:mqtt: Received MQTT message on 'zigbee2mqtt/bridge/request/device/remove' with data '{"block":false,"force":true,"id":"0x0000000001718f82","transaction":"xlhha-2"}'
[2024-09-07 00:15:09] info:     z2m: Removing device '0x0000000001718f82' (block: false, force: true)
[2024-09-07 00:15:09] debug:    zh:controller:database: Writing database to '/app/data/database.db'
[2024-09-07 00:15:09] debug:    z2m: Clearing Home Assistant discovery for '0x0000000001718f82'
[2024-09-07 00:15:09] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/action/config', payload 'null'
[2024-09-07 00:15:10] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload ''
[2024-09-07 00:15:10] info:     z2m: Successfully removed device '0x0000000001718f82' (block: false, force: true)
[2024-09-07 00:15:10] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/linkquality/config', payload 'null'
[2024-09-07 00:15:10] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/response/device/remove', payload '{"data":{"block":false,"force":true,"id":"0x0000000001718f82"},"status":"ok","transaction":"xlhha-2"}'
[2024-09-07 00:15:10] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/last_seen/config', payload 'null'

re-pair join all:

[2024-09-07 00:18:12] info:     z2m: Zigbee: allowing new devices to join.
[2024-09-07 00:18:12] debug:    zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffc EP:0 SeqNr. 156 request id: 100
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 100 status: 0
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 100 status: 0
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:driver: query aps data confirm
[2024-09-07 00:18:12] debug:    zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 157
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffc request id: 100 confirm status: 0
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:driver: send write parameter request from queue. seqNr: 158 paramId: 33 param: 254
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: write parameter response - parameter id: 33 - status: 0
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: write parameter response - parameter id: 33 - status: 0
[2024-09-07 00:18:12] debug:    zh:deconz: PERMIT_JOIN - 254 seconds
[2024-09-07 00:18:12] debug:    zh:deconz: zclFrame to all - zclFrame.payload:
[2024-09-07 00:18:12] debug:    zh:deconz: [object Object]
[2024-09-07 00:18:12] debug:    zh:deconz: sendZclFrameToAll - message send
[2024-09-07 00:18:12] debug:    zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 159 request id: 101
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 101 status: 0
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 101 status: 0
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] debug:    zh:deconz:driver: query aps data confirm
[2024-09-07 00:18:12] debug:    zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 160
[2024-09-07 00:18:12] debug:    zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 101 confirm status: 0
[2024-09-07 00:18:12] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:12] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/response/permit_join', payload '{"data":{"time":254,"value":true},"status":"ok","transaction":"xlhha-6"}'
[2024-09-07 00:18:14] debug:    zh:deconz:driver: send read device state from queue. seqNr: 161
[2024-09-07 00:18:14] debug:    zh:deconz:frameparser: device state: 10100010
[2024-09-07 00:18:14] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:14] debug:    zh:deconz:frameparser: device state: 10100010
[2024-09-07 00:18:14] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:19] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Wasmachine', payload '{"child_lock":"UNLOCK","countdown":null,"current":0,"device":{"applicationVersion":192,"dateCode":"","friendlyName":"Wasmachine","hardwareVersion":1,"ieeeAddr":"0xa4c138c6b601fc85","manufacturerID":4417,"manufacturerName":"_TZ3000_typdpbpg","model":"TS011F_plug_1","networkAddress":35777,"powerSource":"Mains (single phase)","stackVersion":0,"type":"Router","zclVersion":3},"energy":130.78,"indicator_mode":"off/on","last_seen":"2024-09-06T22:18:08.684Z","linkquality":255,"power":0,"power_outage_memory":"on","state":"ON","update":{"installed_version":192,"latest_version":192,"state":"idle"},"update_available":false,"voltage":229}'
[2024-09-07 00:18:19] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Wasmachine', payload '{"child_lock":"UNLOCK","countdown":null,"current":0,"device":{"applicationVersion":192,"dateCode":"","friendlyName":"Wasmachine","hardwareVersion":1,"ieeeAddr":"0xa4c138c6b601fc85","manufacturerID":4417,"manufacturerName":"_TZ3000_typdpbpg","model":"TS011F_plug_1","networkAddress":35777,"powerSource":"Mains (single phase)","stackVersion":0,"type":"Router","zclVersion":3},"energy":130.78,"indicator_mode":"off/on","last_seen":"2024-09-06T22:18:08.684Z","linkquality":255,"power":0,"power_outage_memory":"on","state":"ON","update":{"installed_version":192,"latest_version":192,"state":"idle"},"update_available":false,"voltage":229}'
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x13 frameCounter: 0x26a2
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '36738', skipping...
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:22] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 162
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 162 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,33,0,168,84,130,143,113,1,162,38,0,0,19,0,51,22,255
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 162 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,33,0,168,84,130,143,113,1,162,38,0,0,19,0,51,22,255
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '36738', skipping...
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '36738', skipping...
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:22] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:22] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 163
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 163 srcAddr: 0x2c8f destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,42,0,168,84,130,143,113,1,162,38,0,0,19,0,143,44,255
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '11407'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 163 srcAddr: 0x2c8f destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,42,0,168,84,130,143,113,1,162,38,0,0,19,0,143,44,255
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '11407'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '11407', skipping...
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '11407', skipping...
[2024-09-07 00:18:22] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:22] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 164
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 164 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,164,4,32,11,130,143,113,1,162,38,0,0,19,0,84,73,140,192,115,127,247
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 164 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,164,4,32,11,130,143,113,1,162,38,0,0,19,0,84,73,140,192,115,127,247
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '36738', skipping...
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '36738', skipping...
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:22] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 165
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 165 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,108,0,168,84,130,143,113,1,162,38,0,0,19,0,109,74,255
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '19053'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 165 srcAddr: 0x4a6d destAddr: 0x0 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:22] debug:    zh:deconz:frameparser: response payload: 17,108,0,168,84,130,143,113,1,162,38,0,0,19,0,109,74,255
[2024-09-07 00:18:22] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '19053'
[2024-09-07 00:18:22] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '19053', skipping...
[2024-09-07 00:18:22] debug:    zh:controller: Data is from unknown device with address '19053', skipping...
[2024-09-07 00:18:24] debug:    zh:deconz:driver: send read device state from queue. seqNr: 166
[2024-09-07 00:18:24] debug:    zh:deconz:frameparser: device state: 10100010
[2024-09-07 00:18:24] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:24] debug:    zh:deconz:frameparser: device state: 10100010
[2024-09-07 00:18:24] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 167
[2024-09-07 00:18:27] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 167 srcAddr: 0x5625 destAddr: 0x0 profile id: 0x104 cluster id: 0x402 lqi: 244
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: response payload: 24,56,10,0,0,41,214,7
[2024-09-07 00:18:27] debug:    zh:controller: Received payload: clusterID=1026, address=22053, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=244, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":56,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":41,"attrData":2006}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 167 srcAddr: 0x5625 destAddr: 0x0 profile id: 0x104 cluster id: 0x402 lqi: 244
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: response payload: 24,56,10,0,0,41,214,7
[2024-09-07 00:18:27] debug:    zh:controller: Received payload: clusterID=1026, address=22053, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=244, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":56,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":41,"attrData":2006}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    z2m: Received Zigbee message from 'aqa6_tuin', type 'attributeReport', cluster 'msTemperatureMeasurement', data '{"measuredValue":2006}' from endpoint 1 with groupID 0
[2024-09-07 00:18:27] debug:    z2m: Received Zigbee message from 'aqa6_tuin', type 'attributeReport', cluster 'msTemperatureMeasurement', data '{"measuredValue":2006}' from endpoint 1 with groupID 0
[2024-09-07 00:18:27] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/aqa6_tuin', payload '{"battery":100,"device":{"applicationVersion":5,"dateCode":"20191205","friendlyName":"aqa6_tuin","hardwareVersion":30,"ieeeAddr":"0x00158d0007f02aa7","manufacturerID":4151,"manufacturerName":"LUMI","model":"WSDCGQ11LM","networkAddress":22053,"powerSource":"Battery","softwareBuildID":"3000-0001","stackVersion":2,"type":"EndDevice","zclVersion":1},"humidity":74,"last_seen":"2024-09-06T22:18:27.630Z","linkquality":244,"power_outage_count":4231,"pressure":1011.9,"temperature":20.1,"voltage":3015}'
[2024-09-07 00:18:27] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/aqa6_tuin', payload '{"battery":100,"device":{"applicationVersion":5,"dateCode":"20191205","friendlyName":"aqa6_tuin","hardwareVersion":30,"ieeeAddr":"0x00158d0007f02aa7","manufacturerID":4151,"manufacturerName":"LUMI","model":"WSDCGQ11LM","networkAddress":22053,"powerSource":"Battery","softwareBuildID":"3000-0001","stackVersion":2,"type":"EndDevice","zclVersion":1},"humidity":74,"last_seen":"2024-09-06T22:18:27.630Z","linkquality":244,"power_outage_count":4231,"pressure":1011.9,"temperature":20.1,"voltage":3015}'
[2024-09-07 00:18:27] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 168
[2024-09-07 00:18:27] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:27] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 169
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 168 srcAddr: 0x5625 destAddr: 0x0 profile id: 0x104 cluster id: 0x405 lqi: 255
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: response payload: 24,57,10,0,0,33,57,29
[2024-09-07 00:18:27] debug:    zh:controller: Received payload: clusterID=1029, address=22053, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":57,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":33,"attrData":7481}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 168 srcAddr: 0x5625 destAddr: 0x0 profile id: 0x104 cluster id: 0x405 lqi: 255
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: response payload: 24,57,10,0,0,33,57,29
[2024-09-07 00:18:27] debug:    zh:controller: Received payload: clusterID=1029, address=22053, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":57,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":33,"attrData":7481}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    z2m: Received Zigbee message from 'aqa6_tuin', type 'attributeReport', cluster 'msRelativeHumidity', data '{"measuredValue":7481}' from endpoint 1 with groupID 0
[2024-09-07 00:18:27] debug:    z2m: Received Zigbee message from 'aqa6_tuin', type 'attributeReport', cluster 'msRelativeHumidity', data '{"measuredValue":7481}' from endpoint 1 with groupID 0
[2024-09-07 00:18:27] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/aqa6_tuin', payload '{"battery":100,"device":{"applicationVersion":5,"dateCode":"20191205","friendlyName":"aqa6_tuin","hardwareVersion":30,"ieeeAddr":"0x00158d0007f02aa7","manufacturerID":4151,"manufacturerName":"LUMI","model":"WSDCGQ11LM","networkAddress":22053,"powerSource":"Battery","softwareBuildID":"3000-0001","stackVersion":2,"type":"EndDevice","zclVersion":1},"humidity":75,"last_seen":"2024-09-06T22:18:27.668Z","linkquality":255,"power_outage_count":4231,"pressure":1011.9,"temperature":20.1,"voltage":3015}'
[2024-09-07 00:18:27] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/aqa6_tuin', payload '{"battery":100,"device":{"applicationVersion":5,"dateCode":"20191205","friendlyName":"aqa6_tuin","hardwareVersion":30,"ieeeAddr":"0x00158d0007f02aa7","manufacturerID":4151,"manufacturerName":"LUMI","model":"WSDCGQ11LM","networkAddress":22053,"powerSource":"Battery","softwareBuildID":"3000-0001","stackVersion":2,"type":"EndDevice","zclVersion":1},"humidity":75,"last_seen":"2024-09-06T22:18:27.668Z","linkquality":255,"power_outage_count":4231,"pressure":1011.9,"temperature":20.1,"voltage":3015}'
[2024-09-07 00:18:27] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:27] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 170
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 169 srcAddr: 0x5625 destAddr: 0x0 profile id: 0x104 cluster id: 0x403 lqi: 208
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: response payload: 24,58,10,0,0,41,243,3,20,0,40,255,16,0,41,134,39
[2024-09-07 00:18:27] debug:    zh:controller: Received payload: clusterID=1027, address=22053, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=208, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":58,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":41,"attrData":1011},{"attrId":20,"dataType":40,"attrData":-1},{"attrId":16,"dataType":41,"attrData":10118}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 169 srcAddr: 0x5625 destAddr: 0x0 profile id: 0x104 cluster id: 0x403 lqi: 208
[2024-09-07 00:18:27] debug:    zh:deconz:frameparser: response payload: 24,58,10,0,0,41,243,3,20,0,40,255,16,0,41,134,39
[2024-09-07 00:18:27] debug:    zh:controller: Received payload: clusterID=1027, address=22053, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=208, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":58,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":41,"attrData":1011},{"attrId":20,"dataType":40,"attrData":-1},{"attrId":16,"dataType":41,"attrData":10118}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-09-07 00:18:27] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:27] debug:    z2m: Received Zigbee message from 'aqa6_tuin', type 'attributeReport', cluster 'msPressureMeasurement', data '{"measuredValue":1011,"scale":-1,"scaledValue":10118}' from endpoint 1 with groupID 0
[2024-09-07 00:18:27] debug:    z2m: Received Zigbee message from 'aqa6_tuin', type 'attributeReport', cluster 'msPressureMeasurement', data '{"measuredValue":1011,"scale":-1,"scaledValue":10118}' from endpoint 1 with groupID 0
[2024-09-07 00:18:27] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/aqa6_tuin', payload '{"battery":100,"device":{"applicationVersion":5,"dateCode":"20191205","friendlyName":"aqa6_tuin","hardwareVersion":30,"ieeeAddr":"0x00158d0007f02aa7","manufacturerID":4151,"manufacturerName":"LUMI","model":"WSDCGQ11LM","networkAddress":22053,"powerSource":"Battery","softwareBuildID":"3000-0001","stackVersion":2,"type":"EndDevice","zclVersion":1},"humidity":75,"last_seen":"2024-09-06T22:18:27.824Z","linkquality":208,"power_outage_count":4231,"pressure":1011.8,"temperature":20.1,"voltage":3015}'
[2024-09-07 00:18:27] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/aqa6_tuin', payload '{"battery":100,"device":{"applicationVersion":5,"dateCode":"20191205","friendlyName":"aqa6_tuin","hardwareVersion":30,"ieeeAddr":"0x00158d0007f02aa7","manufacturerID":4151,"manufacturerName":"LUMI","model":"WSDCGQ11LM","networkAddress":22053,"powerSource":"Battery","softwareBuildID":"3000-0001","stackVersion":2,"type":"EndDevice","zclVersion":1},"humidity":75,"last_seen":"2024-09-06T22:18:27.824Z","linkquality":208,"power_outage_count":4231,"pressure":1011.8,"temperature":20.1,"voltage":3015}'
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: GP commissioning notification
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0xe0 frameCounter: 0x26a3
[2024-09-07 00:18:30] info:     zh:controller:greenpower: Received commissioning from '36738'
[2024-09-07 00:18:30] debug:    zh:controller:greenpower: Payload.Options: 58696 wasBroadcast: true
[2024-09-07 00:18:30] debug:    zh:deconz: zclFrame to all - zclFrame.payload:
[2024-09-07 00:18:30] debug:    zh:deconz: [object Object]
[2024-09-07 00:18:30] debug:    zh:deconz: sendZclFrameToAll - message send
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: GP commissioning notification
[2024-09-07 00:18:30] debug:    zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 171 request id: 102
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: GP commissioning notification
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: GP commissioning notification
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:30] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:18:30] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:30] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 102 status: 0
[2024-09-07 00:18:30] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 102 status: 0
[2024-09-07 00:18:30] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:30] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 172
[2024-09-07 00:18:30] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:18:30] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 173
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 172 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: response payload: 17,109,4,0,8,130,143,113,1,163,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,163,38,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,109,74,211
[2024-09-07 00:18:30] info:     zh:controller:greenpower: Received commissioning from '36738'
[2024-09-07 00:18:30] debug:    zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
[2024-09-07 00:18:30] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 172 srcAddr: 0x8f82 destAddr: 0xfffd profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: response payload: 17,109,4,0,8,130,143,113,1,163,0,0,0,224,46,2,197,242,171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8,140,42,19,58,163,38,0,0,4,17,16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104,109,74,211
[2024-09-07 00:18:30] info:     zh:controller:greenpower: Received commissioning from '36738'
[2024-09-07 00:18:30] debug:    zh:controller:greenpower: Payload.Options: 58728 wasBroadcast: false
[2024-09-07 00:18:30] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:30] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 174 paramId: 1
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-07 00:18:30] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 175 paramId: 1
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: MAC: 0x00212effff07ad37
[2024-09-07 00:18:30] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 176 paramId: 7
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-07 00:18:30] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-07 00:18:30] debug:    zh:deconz: no response expected (43)
[2024-09-07 00:18:30] debug:    zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
[2024-09-07 00:18:30] debug:    zh:controller: Deleted green power device '0x0000000001718f82' joined, undeleting
[2024-09-07 00:18:30] debug:    zh:controller:database: Writing database to '/app/data/database.db'
[2024-09-07 00:18:32] info:     z2m: Device '0x0000000001718f82' joined
[2024-09-07 00:18:32] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":{"friendly_name":"0x0000000001718f82"},"type":"device_connected"}'
[2024-09-07 00:18:32] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/action/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":true,"entity_category":"diagnostic","icon":"mdi:gesture-double-tap","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Action","object_id":"0x0000000001718f82_action","origin":{"name":"Zigbee2MQTT","sw":"1.40.0-dev","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_action_zigbee2mqtt","value_template":"{{ value_json.action }}"}'
[2024-09-07 00:18:32] info:     z2m: Successfully interviewed '0x0000000001718f82', device has successfully been paired
[2024-09-07 00:18:32] info:     z2m: Device '0x0000000001718f82' is supported, identified as: EnOcean Pushbutton transmitter module (PTM 215Z)
[2024-09-07 00:18:32] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"interview_successful","meta":{"description":"Pushbutton transmitter module","friendly_name":"0x0000000001718f82","model":"PTM 215Z","supported":true,"vendor":"EnOcean"},"type":"pairing"}'
[2024-09-07 00:18:32] debug:    zh:controller: Received payload: clusterID=33, address=36738, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":109,"commandIdentifier":4},"payload":{"options":2048,"srcID":24219522,"frameCounter":163,"commandID":224,"payloadSize":46,"commandFrame":{"deviceID":2,"options":197,"extendedOptions":242,"securityKey":{"type":"Buffer","data":[171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8]},"keyMic":974334604,"outgoingCounter":9891,"applicationInfo":4,"manufacturerID":0,"modelID":0,"numGdpCommands":17,"gpdCommandIdList":{"type":"Buffer","data":[16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104]},"numServerClusters":0,"numClientClusters":0,"gpdServerClusters":{"type":"Buffer","data":[]},"gpdClientClusters":{"type":"Buffer","data":[]}},"gppNwkAddr":19053,"gppGddLink":211},"command":{"ID":4,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]}],"name":"commissioningNotification"}}
[2024-09-07 00:18:32] debug:    z2m: Received Zigbee message from '0x0000000001718f82', type 'commandCommissioningNotification', cluster 'greenPower', data '{"commandFrame":{"applicationInfo":4,"deviceID":2,"extendedOptions":242,"gpdClientClusters":{"data":[],"type":"Buffer"},"gpdCommandIdList":{"data":[16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104],"type":"Buffer"},"gpdServerClusters":{"data":[],"type":"Buffer"},"keyMic":974334604,"manufacturerID":0,"modelID":0,"numClientClusters":0,"numGdpCommands":17,"numServerClusters":0,"options":197,"outgoingCounter":9891,"securityKey":{"data":[171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8],"type":"Buffer"}},"commandID":224,"frameCounter":163,"gppGddLink":211,"gppNwkAddr":19053,"options":2048,"payloadSize":46,"srcID":24219522}' from endpoint 242 with groupID 0
[2024-09-07 00:18:32] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-06T22:18:32.757Z","linkquality":255}'
[2024-09-07 00:18:32] debug:    zh:deconz:driver: DATA_REQUEST - destAddr: 0x4a6d EP:242 SeqNr. 178 request id: 103
[2024-09-07 00:18:32] debug:    zh:deconz:driver: send read parameter request from queue. seqNr: 177 paramId: 7
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz: no response expected (44)
[2024-09-07 00:18:32] debug:    zh:controller: Green power device '{"sourceID":24219522,"deviceID":2,"networkAddress":36738}' joined
[2024-09-07 00:18:32] debug:    zh:controller: Received payload: clusterID=33, address=36738, groupID=0, endpoint=242, destinationEndpoint=242, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":109,"commandIdentifier":4},"payload":{"options":2048,"srcID":24219522,"frameCounter":163,"commandID":224,"payloadSize":46,"commandFrame":{"deviceID":2,"options":197,"extendedOptions":242,"securityKey":{"type":"Buffer","data":[171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8]},"keyMic":974334604,"outgoingCounter":9891,"applicationInfo":4,"manufacturerID":0,"modelID":0,"numGdpCommands":17,"gpdCommandIdList":{"type":"Buffer","data":[16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104]},"numServerClusters":0,"numClientClusters":0,"gpdServerClusters":{"type":"Buffer","data":[]},"gpdClientClusters":{"type":"Buffer","data":[]}},"gppNwkAddr":19053,"gppGddLink":211},"command":{"ID":4,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]}],"name":"commissioningNotification"}}
[2024-09-07 00:18:32] debug:    z2m: Received Zigbee message from '0x0000000001718f82', type 'commandCommissioningNotification', cluster 'greenPower', data '{"commandFrame":{"applicationInfo":4,"deviceID":2,"extendedOptions":242,"gpdClientClusters":{"data":[],"type":"Buffer"},"gpdCommandIdList":{"data":[16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104],"type":"Buffer"},"gpdServerClusters":{"data":[],"type":"Buffer"},"keyMic":974334604,"manufacturerID":0,"modelID":0,"numClientClusters":0,"numGdpCommands":17,"numServerClusters":0,"options":197,"outgoingCounter":9891,"securityKey":{"data":[171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8],"type":"Buffer"}},"commandID":224,"frameCounter":163,"gppGddLink":211,"gppNwkAddr":19053,"options":2048,"payloadSize":46,"srcID":24219522}' from endpoint 242 with groupID 0
[2024-09-07 00:18:32] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-06T22:18:32.902Z","linkquality":255}'
[2024-09-07 00:18:32] debug:    zh:deconz:driver: DATA_REQUEST - destAddr: 0x4a6d EP:242 SeqNr. 179 request id: 104
[2024-09-07 00:18:32] debug:    zh:deconz:driver: query aps data confirm
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 103 status: 0
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 103 status: 0
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 180
[2024-09-07 00:18:32] debug:    zh:deconz:driver: query aps data confirm
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: NWKADDR: 0
[2024-09-07 00:18:32] debug:    zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 181
[2024-09-07 00:18:32] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/linkquality/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"enabled_by_default":false,"entity_category":"diagnostic","icon":"mdi:signal","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Linkquality","object_id":"0x0000000001718f82_linkquality","origin":{"name":"Zigbee2MQTT","sw":"1.40.0-dev","url":"https://www.zigbee2mqtt.io"},"state_class":"measurement","state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_linkquality_zigbee2mqtt","unit_of_measurement":"lqi","value_template":"{{ value_json.linkquality }}"}'
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 104 status: 0
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 104 status: 0
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 102 confirm status: 0
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x4a6d request id: 103 confirm status: 0
[2024-09-07 00:18:32] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:32] debug:    zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 43
[2024-09-07 00:18:32] debug:    zh:deconz: false, true, false, 10000
[2024-09-07 00:18:32] debug:    zh:deconz: resolve request (43)
[2024-09-07 00:18:32] debug:    zh:controller: Received payload: clusterID=33, address=36738, groupID=2948, endpoint=242, destinationEndpoint=242, wasBroadcast=true, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":171,"commandIdentifier":4},"payload":{"options":0,"srcID":24219522,"frameCounter":9891,"commandID":224,"payloadSize":46,"commandFrame":{"deviceID":2,"options":197,"extendedOptions":242,"securityKey":{"type":"Buffer","data":[171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8]},"keyMic":974334604,"outgoingCounter":9891,"applicationInfo":4,"manufacturerID":0,"modelID":0,"numGdpCommands":17,"gpdCommandIdList":{"type":"Buffer","data":[16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104]},"numServerClusters":0,"numClientClusters":0,"gpdServerClusters":{"type":"Buffer","data":[]},"gpdClientClusters":{"type":"Buffer","data":[]}}},"command":{"ID":4,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":2048}]}],"name":"commissioningNotification"}}
[2024-09-07 00:18:32] debug:    z2m: Received Zigbee message from '0x0000000001718f82', type 'commandCommissioningNotification', cluster 'greenPower', data '{"commandFrame":{"applicationInfo":4,"deviceID":2,"extendedOptions":242,"gpdClientClusters":{"data":[],"type":"Buffer"},"gpdCommandIdList":{"data":[16,17,18,19,20,21,22,23,34,96,98,99,100,101,102,103,104],"type":"Buffer"},"gpdServerClusters":{"data":[],"type":"Buffer"},"keyMic":974334604,"manufacturerID":0,"modelID":0,"numClientClusters":0,"numGdpCommands":17,"numServerClusters":0,"options":197,"outgoingCounter":9891,"securityKey":{"data":[171,172,118,236,48,135,145,201,184,133,221,153,52,25,129,8],"type":"Buffer"}},"commandID":224,"frameCounter":9891,"options":0,"payloadSize":46,"srcID":24219522}' from endpoint 242 with groupID 2948
[2024-09-07 00:18:32] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-06T22:18:32.995Z","linkquality":255}'
[2024-09-07 00:18:32] debug:    zh:deconz:driver: query aps data confirm
[2024-09-07 00:18:33] debug:    zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 182
[2024-09-07 00:18:33] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x0000000001718f82","ieee_address":"0x0000000001718f82"},"type":"device_joined"}'
[2024-09-07 00:18:33] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"definition":{"description":"Pushbutton transmitter module","exposes":[{"access":1,"category":"diagnostic","description":"Triggered action (e.g. a button click)","label":"Action","name":"action","property":"action","type":"enum","values":["press_1","release_1","press_2","release_2","press_3","release_3","press_4","release_4","press_1_and_3","release_1_and_3","press_2_and_4","release_2_and_4","press_energy_bar"]},{"access":1,"category":"diagnostic","description":"Link quality (signal strength)","label":"Linkquality","name":"linkquality","property":"linkquality","type":"numeric","unit":"lqi","value_max":255,"value_min":0}],"model":"PTM 215Z","options":[],"supports_ota":false,"vendor":"EnOcean"},"friendly_name":"0x0000000001718f82","ieee_address":"0x0000000001718f82","status":"successful","supported":true},"type":"device_interview"}'
[2024-09-07 00:18:33] debug:    zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x4a6d request id: 104 confirm status: 0
[2024-09-07 00:18:33] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:18:33] debug:    zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 44
[2024-09-07 00:18:33] debug:    zh:deconz: false, true, false, 10000
[2024-09-07 00:18:33] debug:    zh:deconz: resolve request (44)
[2024-09-07 00:18:33] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x0000000001718f82/last_seen/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state"},{"topic":"zigbee2mqtt/0x0000000001718f82/availability"}],"availability_mode":"all","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"device_class":"timestamp","enabled_by_default":false,"entity_category":"diagnostic","icon":"mdi:clock","json_attributes_topic":"zigbee2mqtt/0x0000000001718f82","name":"Last seen","object_id":"0x0000000001718f82_last_seen","origin":{"name":"Zigbee2MQTT","sw":"1.40.0-dev","url":"https://www.zigbee2mqtt.io"},"state_topic":"zigbee2mqtt/0x0000000001718f82","unique_id":"0x0000000001718f82_last_seen_zigbee2mqtt","value_template":"{{ value_json.last_seen }}"}'

and a button push. pay attention to line 3. Still an unhandled command? Button works fine though:

[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: GP_DATA_INDICATION - src id: 0x1718f82 cmd id: 0x13 frameCounter: 0x26a4
[2024-09-07 00:19:45] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:19:45] debug:    zh:controller: Received payload: clusterID=33, address=36738, groupID=2948, endpoint=242, destinationEndpoint=242, wasBroadcast=true, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":202,"commandIdentifier":0},"payload":{"options":0,"srcID":24219522,"frameCounter":9892,"commandID":19,"payloadSize":0,"commandFrame":{}},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
[2024-09-07 00:19:45] debug:    z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{},"commandID":19,"frameCounter":9892,"options":0,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948
[2024-09-07 00:19:45] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"press_3","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-06T22:19:45.491Z","linkquality":255}'
[2024-09-07 00:19:45] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":"","device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-06T22:19:45.491Z","linkquality":255}'
[2024-09-07 00:19:45] info:     z2m:mqtt: MQTT publish: topic 'homeassistant/device_automation/0x0000000001718f82/action_press_3/config', payload '{"automation_type":"trigger","device":{"identifiers":["zigbee2mqtt_0x0000000001718f82"],"manufacturer":"EnOcean","model":"Pushbutton transmitter module (PTM 215Z)","name":"0x0000000001718f82","via_device":"zigbee2mqtt_bridge_0x00212effff07ad37"},"origin":{"name":"Zigbee2MQTT","sw":"1.40.0-dev","url":"https://www.zigbee2mqtt.io"},"payload":"press_3","subtype":"press_3","topic":"zigbee2mqtt/0x0000000001718f82/action","type":"action"}'
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: GP data notification
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-09-07 00:19:45] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82/action', payload 'press_3'
[2024-09-07 00:19:45] debug:    zh:deconz:driver: query aps data indication
[2024-09-07 00:19:45] debug:    zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 202
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 202 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: response payload: 17,110,0,168,84,130,143,113,1,164,38,0,0,19,0,109,74,255
[2024-09-07 00:19:45] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:19:45] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 202 srcAddr: 0x8f82 destAddr: 0xb84 profile id: 0xa1e0 cluster id: 0x21 lqi: 255
[2024-09-07 00:19:45] debug:    zh:deconz:frameparser: response payload: 17,110,0,168,84,130,143,113,1,164,38,0,0,19,0,109,74,255
[2024-09-07 00:19:45] debug:    zh:controller:greenpower: Received unhandled command '0x13' from '36738'
[2024-09-07 00:19:45] debug:    zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-09-07 00:19:45] debug:    zh:controller: Received payload: clusterID=33, address=36738, groupID=2948, endpoint=242, destinationEndpoint=0, wasBroadcast=true, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":110,"commandIdentifier":0},"payload":{"options":21672,"srcID":24219522,"frameCounter":9892,"commandID":19,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[109,74,255]}},"gppNwkAddr":19053,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
[2024-09-07 00:19:45] debug:    zh:controller: Received payload: clusterID=33, address=36738, groupID=2948, endpoint=242, destinationEndpoint=0, wasBroadcast=true, linkQuality=255, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":110,"commandIdentifier":0},"payload":{"options":21672,"srcID":24219522,"frameCounter":9892,"commandID":19,"payloadSize":0,"commandFrame":{"raw":{"type":"Buffer","data":[109,74,255]}},"gppNwkAddr":19053,"gppGddLink":255},"command":{"ID":0,"parameters":[{"name":"options","type":33},{"name":"srcID","type":35,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":0}]},{"name":"gpdIEEEAddr","type":240,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"gpdEndpoint","type":32,"conditions":[{"type":"bitFieldEnum","param":"options","offset":0,"size":3,"value":2}]},{"name":"frameCounter","type":35},{"name":"commandID","type":32},{"name":"payloadSize","type":32},{"name":"commandFrame","type":1009},{"name":"gppNwkAddr","type":33,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]},{"name":"gppGddLink","type":32,"conditions":[{"type":"bitMaskSet","param":"options","mask":16384}]}],"name":"notification"}}
[2024-09-07 00:19:45] debug:    z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[109,74,255],"type":"Buffer"}},"commandID":19,"frameCounter":9892,"gppGddLink":255,"gppNwkAddr":19053,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948
[2024-09-07 00:19:45] debug:    z2m: Received Zigbee message from '0x0000000001718f82', type 'commandNotification', cluster 'greenPower', data '{"commandFrame":{"raw":{"data":[109,74,255],"type":"Buffer"}},"commandID":19,"frameCounter":9892,"gppGddLink":255,"gppNwkAddr":19053,"options":21672,"payloadSize":0,"srcID":24219522}' from endpoint 242 with groupID 2948
[2024-09-07 00:19:45] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-06T22:19:45.530Z","linkquality":255}'
[2024-09-07 00:19:45] info:     z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x0000000001718f82', payload '{"action":null,"device":{"friendlyName":"0x0000000001718f82","ieeeAddr":"0x0000000001718f82","model":"PTM 215Z","networkAddress":36738,"type":"GreenPower"},"last_seen":"2024-09-06T22:19:45.530Z","linkquality":255}'

everything seems to work. yay

Nerivec commented 2 months ago

Thanks for the feedback :wink: zh:controller:greenpower: Received unhandled command in this scenario means the command is passed back to controller for regular command parsing (i.e. state update). That is the expected behavior.

tluethi71 commented 2 months ago

i have updated to 1.40.1-1. still not working at my end.. in zigbee2Mqtt it shows the correct payload Info 2024-09-07 17:44:40z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom', payload '{"action":"press_1","elapsed":15285,"linkquality":255}' Info 2024-09-07 17:44:40z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom/action', payload 'press_1' Info 2024-09-07 17:44:40z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom', payload '{"action":"release_1","elapsed":256,"linkquality":255}' Info 2024-09-07 17:44:40z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom/action', payload 'release_1' Info 2024-09-07 17:44:44z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom', payload '{"action":"press_1","elapsed":4102,"linkquality":255}' Info 2024-09-07 17:44:44z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom/action', payload 'press_1' Info 2024-09-07 17:44:44z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom', payload '{"action":"release_1","elapsed":254,"linkquality":255}' Info 2024-09-07 17:44:44z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/zb2m_taster_buero_tom/action', payload 'release_1'

but my mqtt device does only show the linkquality Bildschirmfoto 2024-09-07 um 17 45 50

Nerivec commented 2 months ago

@tluethi71 Looks like something didn't get updated. Try this:

tluethi71 commented 2 months ago

@Nerivec thank you for you answer. Did not work.

and still not showing the status in mqtt.

Nerivec commented 2 months ago

Did you try removing it and re-pairing it instead? These GP devices have a reset procedure, do that too, that will ensure you have a clean slate.

tluethi71 commented 2 months ago

Hey @Nerivec, yes i did.

Nerivec commented 2 months ago

What do you see if you click on MQTT INFO (in your screenshot) for the various Payload in Entities/Triggers?

tluethi71 commented 2 months ago

see the pic and the triggers:

Bildschirmfoto 2024-09-08 um 18 44 56

triggers:

Triggers

MQTT discovery data:
Topic: homeassistant/device_automation/0x0000000001567628/action_press_4/config
Payload
automation_type: trigger
device:
  identifiers:
    - zigbee2mqtt_0x0000000001567628
  manufacturer: EnOcean
  model: Pushbutton transmitter module (PTM 215ZE)
  via_device: zigbee2mqtt_bridge_0x00212effff0985cb
  name: zb2m_taster_buero_tom
origin:
  name: Zigbee2MQTT
  sw_version: 1.40.1
  support_url: https://www.zigbee2mqtt.io
payload: press_4
subtype: press_4
topic: zigbee2mqtt/zb2m_taster_buero_tom/action
type: action
platform: mqtt

MQTT discovery data:
Topic: homeassistant/device_automation/0x0000000001567628/action_release_4/config
Payload
automation_type: trigger
device:
  identifiers:
    - zigbee2mqtt_0x0000000001567628
  manufacturer: EnOcean
  model: Pushbutton transmitter module (PTM 215ZE)
  via_device: zigbee2mqtt_bridge_0x00212effff0985cb
  name: zb2m_taster_buero_tom
origin:
  name: Zigbee2MQTT
  sw_version: 1.40.1
  support_url: https://www.zigbee2mqtt.io
payload: release_4
subtype: release_4
topic: zigbee2mqtt/zb2m_taster_buero_tom/action
type: action
platform: mqtt

MQTT discovery data:
Topic: homeassistant/device_automation/0x0000000001567628/action_press_3/config
Payload
automation_type: trigger
device:
  identifiers:
    - zigbee2mqtt_0x0000000001567628
  manufacturer: EnOcean
  model: Pushbutton transmitter module (PTM 215ZE)
  via_device: zigbee2mqtt_bridge_0x00212effff0985cb
  name: zb2m_taster_buero_tom
origin:
  name: Zigbee2MQTT
  sw_version: 1.40.1
  support_url: https://www.zigbee2mqtt.io
payload: press_3
subtype: press_3
topic: zigbee2mqtt/zb2m_taster_buero_tom/action
type: action
platform: mqtt

MQTT discovery data:
Topic: homeassistant/device_automation/0x0000000001567628/action_release_3/config
Payload
automation_type: trigger
device:
  identifiers:
    - zigbee2mqtt_0x0000000001567628
  manufacturer: EnOcean
  model: Pushbutton transmitter module (PTM 215ZE)
  via_device: zigbee2mqtt_bridge_0x00212effff0985cb
  name: zb2m_taster_buero_tom
origin:
  name: Zigbee2MQTT
  sw_version: 1.40.1
  support_url: https://www.zigbee2mqtt.io
payload: release_3
subtype: release_3
topic: zigbee2mqtt/zb2m_taster_buero_tom/action
type: action
platform: mqtt

does it help?

Koenkk commented 2 months ago

@tluethi71

tluethi71 commented 2 months ago

@Koenkk sure

Koenkk commented 2 months ago

Nothing strange here, could you provide the debug log when starting z2m + waiting for 1 minute?

See this on how to enable debug logging.

tluethi71 commented 2 months ago

sure.. i did a restart of the whole system.. hmm, due to limited character per post I could not post the whole log.. Does it help. Looks kind of not reader friendly..

[2024-09-10 15:28:04] info: z2m: Logging to console, file (filename: log.log) [2024-09-10 15:28:04] debug: z2m: Loaded state from file /config/zigbee2mqtt/state.json [2024-09-10 15:28:04] info: z2m: Starting Zigbee2MQTT version 1.40.1 (commit #unknown) [2024-09-10 15:28:04] info: z2m: Starting zigbee-herdsman (0.57.3) [2024-09-10 15:28:04] debug: z2m: Using zigbee-herdsman with settings: '"{\"network\":{\"panID\":8309,\"extendedPanID\":[23,124,59,213,181,148,22,121],\"channelList\":[11],\"networkKey\":\"HIDDEN\"},\"databasePath\":\"/config/zigbee2mqtt/database.db\",\"databaseBackupPath\":\"/config/zigbee2mqtt/database.db.backup\",\"backupPath\":\"/config/zigbee2mqtt/coordinator_backup.json\",\"serialPort\":{\"path\":\"/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2671447-if00\",\"adapter\":\"deconz\"},\"adapter\":{\"concurrent\":null,\"delay\":null,\"disableLED\":true}}"' [2024-09-10 15:28:05] debug: zh:adapter: Failed to validate path: 'Error: spawn udevadm ENOENT' [2024-09-10 15:28:05] debug: zh:deconz:driver: Set delay to 0 [2024-09-10 15:28:05] debug: zh:controller: Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":"HIDDEN","panID":8309,"extendedPanID":[23,124,59,213,181,148,22,121],"channelList":[11]},"serialPort":{"path":"/dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2671447-if00","adapter":"deconz"},"adapter":{"disableLED":true,"concurrent":null,"delay":null},"databasePath":"/config/zigbee2mqtt/database.db","databaseBackupPath":"/config/zigbee2mqtt/database.db.backup","backupPath":"/config/zigbee2mqtt/coordinator_backup.json"}' [2024-09-10 15:28:05] debug: zh:deconz:driver: Opening with /dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2671447-if00 [2024-09-10 15:28:05] debug: zh:deconz:driver: Serialport opened [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 1 paramId: 5 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: PANID: 2075 [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 2 paramId: 11 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: APS_EXT_PANID: 0x177c3bd5b5941679 [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 3 paramId: 28 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: CHANNEL: 11 [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 4 paramId: 24 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: NETWORK_KEY: hidden [2024-09-10 15:28:05] debug: zh:controller: Started with result 'resumed' [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 5 paramId: 5 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: PANID: 2075 [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 6 paramId: 11 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: APS_EXT_PANID: 0x177c3bd5b5941679 [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 7 paramId: 28 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: CHANNEL: 11 [2024-09-10 15:28:05] debug: zh:controller: Injected database: true, adapter: true [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x00212effff0985cb): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x001788010bac3646): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x001788010d0e2d03): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x001788010389984b): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x00158d00011b079e): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x6c5cb1fffefdd5f1): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x54ef441000908c0c): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x54ef44100090873d): default expiration timeout set to 3600000 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x0000000001567628): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x54ef441000908a83): default expiration timeout set to 3600000 [2024-09-10 15:28:05] debug: zh:controller:device: Request Queue (0x9035eafffec88f04): default expiration timeout set to 0 [2024-09-10 15:28:05] debug: zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db' [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 8 paramId: 1 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10100010 [2024-09-10 15:28:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: MAC: 0x00212effff0985cb [2024-09-10 15:28:05] debug: zh:deconz:driver: send read parameter request from queue. seqNr: 9 paramId: 7 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: NWKADDR: 0 [2024-09-10 15:28:05] info: zh:controller: Coordinator address changed, updating to '162' [2024-09-10 15:28:05] debug: zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db' [2024-09-10 15:28:05] info: z2m: zigbee-herdsman started (resumed) [2024-09-10 15:28:05] debug: zh:deconz:driver: send read firmware version request from queue. seqNr: 10 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: read firmware version response - version: 0,7,114,38 [2024-09-10 15:28:05] info: z2m: Coordinator firmware version: '{"meta":{"maintrel":0,"majorrel":38,"minorrel":114,"product":0,"revision":"0x26720700","transportrev":0},"type":"ConBee2/RaspBee2"}' [2024-09-10 15:28:05] debug: z2m: Zigbee network parameters: {"channel":11,"extendedPanID":"0x177c3bd5b5941679","panID":8309} [2024-09-10 15:28:05] info: z2m: zb2m_bulp_buero_karo (0x001788010bac3646): 9290024684 - Philips Hue white ambiance E27 1100lm with Bluetooth (Router) [2024-09-10 15:28:05] info: z2m: zb2m_lightstrip_buero_tom (0x001788010d0e2d03): 8718699703424 - Philips Hue white and color ambiance LightStrip plus (Router) [2024-09-10 15:28:05] info: z2m: zb2m_bulp_buero_tom (0x001788010389984b): 9290012573A - Philips Hue white and color ambiance E26/E27/E14 (Router) [2024-09-10 15:28:05] info: z2m: zb2m_pendelleuchte_ez (0x00158d00011b079e): ZLED-2709 - Trust Smart Dimmable LED Bulb (Router) [2024-09-10 15:28:05] info: z2m: zb2m_repeater (0x6c5cb1fffefdd5f1): WG001 - Aeotec Range extender Zi (Router) [2024-09-10 15:28:05] info: z2m: zb2m_watersensonsor_kueche (0x54ef441000908c0c): SJCGQ12LM - Aqara Water leak sensor T1 (EndDevice) [2024-09-10 15:28:05] info: z2m: zb2m_watersensor_poolpumpe (0x54ef44100090873d): SJCGQ12LM - Aqara Water leak sensor T1 (EndDevice) [2024-09-10 15:28:05] info: z2m: zb2m_taster_buero_tom (0x0000000001567628): PTM 215ZE - EnOcean Pushbutton transmitter module (GreenPower) [2024-09-10 15:28:05] info: z2m: zb2m_watersensor_regenwasser (0x54ef441000908a83): SJCGQ12LM - Aqara Water leak sensor T1 (EndDevice) [2024-09-10 15:28:05] info: z2m: zb2m_nodon_technik (0x9035eafffec88f04): SIN-4-2-20 - NodOn Lighting relay switch (Router) [2024-09-10 15:28:05] info: z2m: Currently 10 devices are joined. [2024-09-10 15:28:05] info: z2m: Zigbee: disabling joining new devices. [2024-09-10 15:28:05] debug: zh:controller: Disable joining [2024-09-10 15:28:05] debug: zh:deconz: zclFrame to all - zclFrame.payload: [2024-09-10 15:28:05] debug: zh:deconz: [object Object] [2024-09-10 15:28:05] debug: zh:deconz: sendZclFrameToAll - message send [2024-09-10 15:28:05] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 11 request id: 1 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 1 status: 0 [2024-09-10 15:28:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10100110 [2024-09-10 15:28:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-10 15:28:05] debug: zh:deconz:driver: query aps data confirm [2024-09-10 15:28:05] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 12 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 1 confirm status: 0 [2024-09-10 15:28:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-10 15:28:05] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffc EP:0 SeqNr. 13 request id: 2 [2024-09-10 15:28:05] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 2 status: 0 [2024-09-10 15:28:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-10 15:28:06] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10100110 [2024-09-10 15:28:06] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-10 15:28:06] debug: zh:deconz:driver: query aps data confirm [2024-09-10 15:28:06] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 14 [2024-09-10 15:28:06] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffc request id: 2 confirm status: 0 [2024-09-10 15:28:06] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-10 15:28:06] debug: zh:deconz:driver: send write parameter request from queue. seqNr: 15 paramId: 33 param: 0 [2024-09-10 15:28:06] debug: zh:deconz:frameparser: write parameter response - parameter id: 33 - status: 0 [2024-09-10 15:28:06] debug: zh:deconz: PERMIT_JOIN - 0 seconds [2024-09-10 15:28:06] info: z2m: Connecting to MQTT server at mqtt://core-mosquitto:1883 [2024-09-10 15:28:06] debug: z2m: Using MQTT keepalive: 60 [2024-09-10 15:28:06] debug: z2m: Using MQTT login with username: addons [2024-09-10 15:28:06] info: z2m: Connected to MQTT server [2024-09-10 15:28:06] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload '{"state":"online"}' [2024-09-10 15:28:06] info: z2m: Started frontend on port 8099 [2024-09-10 15:28:06] debug: z2m: Discovering entities to Home Assistant in 5s

Koenkk commented 2 months ago

@tluethi71 please upload the full log as a file

tluethi71 commented 2 months ago

@Koenkk see attachment. the full log since yesterday afternoon after restart.. log.log

Koenkk commented 2 months ago

It doesn't contain the startup sequence of z2m, make sure it contains e.g. z2m: Logging to console

tluethi71 commented 2 months ago

@Koenkk arrrrghh... now starting with [2024-09-13 17:10:34] info: z2m: Logging to console, file (filename: log.log) sorry about that.. log.log

Koenkk commented 2 months ago

Found the issue, settings legacy_triggers: true should fix the issue.

tluethi71 commented 2 months ago

perfect!!! thanks a lot. It works perfectly.. I needed also to create an automation to trigger the actions (see https://github.com/Koenkk/zigbee2mqtt/issues/22578#issuecomment-2351170759)