[2022-06-07 17:00:49][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:49][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:49][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'0': {'1': {'value': '21', 'cluster_name': 'Basic'}}}}}}
[2022-06-07 17:00:49][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:00:49][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=39, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0x0000] Sending request: Read_Attributes(attribute_ids=[0, 2, 3])
[2022-06-07 17:00:50][DEBUG] : Sending Zigbee request with tsn 39 under 40 request id, data: b'002700000002000300'
[2022-06-07 17:00:50][DEBUG] : Command Command.aps_data_request (24, 40, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=1>, 260, 0, 1, b"\x00'\x00\x00\x00\x02\x00\x03\x00", <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:00:50][DEBUG] : Send: 0x1255001f001800280002770c01040100000109000027000000020003000200
[2022-06-07 17:00:50][DEBUG] : Frame received: 0x0e55000700aa00
[2022-06-07 17:00:50][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:00:50][DEBUG] : Frame received: 0x125500090002002a28
[2022-06-07 17:00:50][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, 40]
[2022-06-07 17:00:50][DEBUG] : Frame received: 0x0e56000700ae00
[2022-06-07 17:00:50][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2022-06-07 17:00:50][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:00:50][DEBUG] : Send: 0x1756000800010001
[2022-06-07 17:00:50][DEBUG] : Frame received: 0x17560057005000260200000102770c010401c0fc39001c5f11070af700413064100003281598390000000095390000000096392e8a1545973900000000052101009a20100b2000082115010921000100afff82f63100c3
[2022-06-07 17:00:50][DEBUG] : APS data indication response: [80, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 64704, b'\x1c_\x11\x07
\xf7\x00A0d\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01', 0, 175, 255, 130, 246, 49, 0, -61]
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0xfcc0] Received ZCL frame: b'\x1c_\x11\x07
\xf7\x00A0d\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01'
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=7, command_id=10, *is_reply=True)
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame: ManufacturerSpecificCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01'))])
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0xfcc0] Received command 0x0A (TSN 7): Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01'))])
[2022-06-07 17:00:50][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.manufacturer_specific.ManufacturerSpecificCluster object at 0x7fac555e80> cluster_id: 0xfcc0 command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=False, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=7, command_id=10, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01'))]),)
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0xfcc0] Attribute report received: 0x00F7=b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01'
[2022-06-07 17:00:50][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 247=b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01' on cluster 64704
[2022-06-07 17:00:50][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0xfcc0, data: b'1c5f11070af700413064100003281598390000000095390000000096392e8a1545973900000000052101009a20100b20000821150109210001'
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621250.628495, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:50][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:00:50][DEBUG] : Send: 0x04570007000000
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621250.628495, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:50][DEBUG] : Frame received: 0x04570013000c00222802770c01010000000000
[2022-06-07 17:00:50][DEBUG] : APS data confirm response for request with id 40: 00
[2022-06-07 17:00:50][DEBUG] : Request id: 0x28 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=1>, status: 0x00
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01'))
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01')) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621250.647285, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621250.647285, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:50][DEBUG] : Frame received: 0x0e58000700aa00
[2022-06-07 17:00:50][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:00:50][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:00:50][DEBUG] : Send: 0x1758000800010001
[2022-06-07 17:00:50][DEBUG] : Frame received: 0x17580030002900220200000102770c0104010000120008270100000020030200002000030000200100afff82f63100c1
[2022-06-07 17:00:50][DEBUG] : APS data indication response: [41, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 0, b"\x08'\x01\x00\x00\x00 \x03\x02\x00\x00 \x00\x03\x00\x00 \x01", 0, 175, 255, 130, 246, 49, 0, -63]
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0x0000] Received ZCL frame: b"\x08'\x01\x00\x00\x00 \x03\x02\x00\x00 \x00\x03\x00\x00 \x01"
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=39, command_id=1, *is_reply=True)
[2022-06-07 17:00:50][DEBUG] : [0x0C77:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=3)), ReadAttributeRecord(attrid=0x0002, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=0)), ReadAttributeRecord(attrid=0x0003, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=1))])
[2022-06-07 17:00:50][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'082701000000200302000020000300002001'
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621250.689898, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:50][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 0=3 on cluster 0
[2022-06-07 17:00:50][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 2=0 on cluster 0
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621250.689898, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:50][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 3=1 on cluster 0
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 0, 3))
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 0, 3)) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 2, 0))
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 2, 0)) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 3, 1))
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 3, 1)) completed
[2022-06-07 17:00:50][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:50][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:50][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'64704': {'247': {'value': "b'd\x10\x00\x03(\x15\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969.\x8a\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x0b \x00\x08!\x15\x01	!\x00\x01'", 'cluster_name': 'Manufacturer Specific'}}, '0': {'0': {'value': '3', 'cluster_name': 'Basic'}, '2': {'value': '0', 'cluster_name': 'Basic'}, '3': {'value': '1', 'cluster_name': 'Basic'}}}}}}
[2022-06-07 17:00:50][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:00:50][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:00:51][DEBUG] : Frame received: 0x0e59000700aa00
[2022-06-07 17:00:51][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:00:51][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:00:51][DEBUG] : Send: 0x1759000800010001
[2022-06-07 17:00:51][DEBUG] : Frame received: 0x1759002c002500220200000102770c01040106000e0018080a00001000f500230000000200afff82f63100c0
[2022-06-07 17:00:51][DEBUG] : APS data indication response: [37, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 6, b'\x18\x08
\x00\x00\x10\x00\xf5\x00#\x00\x00\x00\x02', 0, 175, 255, 130, 246, 49, 0, -64]
[2022-06-07 17:00:51][DEBUG] : Ignoring message (b'18080a00001000f5002300000002') on cluster 6: unknown endpoint or cluster id: 'No cluster ID 0x0006 on (54:ef:44:10:00:25:95:cf, 1)'
[2022-06-07 17:00:51][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'18080a00001000f5002300000002'
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621251.702488, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:51][DEBUG] : [0x0C77:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=41, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[2022-06-07 17:00:51][DEBUG] : [0x0C77:1:0x0000] Sending request: Read_Attributes(attribute_ids=[7])
[2022-06-07 17:00:51][DEBUG] : Sending Zigbee request with tsn 41 under 42 request id, data: b'0029000700'
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621251.702488, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:51][DEBUG] : Command Command.aps_data_request (20, 42, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=1>, 260, 0, 1, b'\x00)\x00\x07\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:00:51][DEBUG] : Send: 0x125a001b0014002a0002770c010401000001050000290007000200
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:51][DEBUG] : Frame received: 0x125a0009000200222a
[2022-06-07 17:00:51][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 42]
[2022-06-07 17:00:51][DEBUG] : Frame received: 0x0e5b000700a600
[2022-06-07 17:00:51][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2022-06-07 17:00:51][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:00:51][DEBUG] : Send: 0x045b0007000000
[2022-06-07 17:00:51][DEBUG] : Frame received: 0x045b0013000c00222a02770c01010000000000
[2022-06-07 17:00:51][DEBUG] : APS data confirm response for request with id 42: 00
[2022-06-07 17:00:51][DEBUG] : Request id: 0x2a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=1>, status: 0x00
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621251.736168, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621251.736168, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:51][DEBUG] : Frame received: 0x0e5c000700aa00
[2022-06-07 17:00:51][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:00:51][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:00:51][DEBUG] : Send: 0x175c000800010001
[2022-06-07 17:00:51][DEBUG] : Frame received: 0x175c0026001f00220200000102770c01040100000800082901070000300000afff82f63100c0
[2022-06-07 17:00:51][DEBUG] : APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 0, b'\x08)\x01\x07\x00\x000\x00', 0, 175, 255, 130, 246, 49, 0, -64]
[2022-06-07 17:00:51][DEBUG] : [0x0C77:1:0x0000] Received ZCL frame: b'\x08)\x01\x07\x00\x000\x00'
[2022-06-07 17:00:51][DEBUG] : [0x0C77:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=41, command_id=1, *is_reply=True)
[2022-06-07 17:00:51][DEBUG] : [0x0C77:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0007, status=<Status.SUCCESS: 0>, value=TypeValue(type=enum8, value=<enum8.undefined_0x00: 0>))])
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621251.793612, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621251.793612, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:51][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'0829010700003000'
[2022-06-07 17:00:51][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 7=PowerSource.Unknown on cluster 0
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 7, <PowerSource.Unknown: 0>))
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 7, <PowerSource.Unknown: 0>)) completed
[2022-06-07 17:00:51][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:51][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:51][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'0': {'7': {'value': 'PowerSource.Unknown', 'cluster_name': 'Basic'}}}}}}
[2022-06-07 17:00:51][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:00:51][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:00:52][DEBUG] : [0x0C77:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=43, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[2022-06-07 17:00:52][DEBUG] : [0x0C77:1:0x0000] Sending request: Read_Attributes(attribute_ids=[6, 16384])
[2022-06-07 17:00:52][DEBUG] : Sending Zigbee request with tsn 43 under 44 request id, data: b'002b0006000040'
[2022-06-07 17:00:52][DEBUG] : Command Command.aps_data_request (22, 44, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=1>, 260, 0, 1, b'\x00+\x00\x06\x00\x00@', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:00:52][DEBUG] : Send: 0x125d001d0016002c0002770c0104010000010700002b00060000400200
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x125d0009000200222c
[2022-06-07 17:00:52][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 44]
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x0e5e000700a600
[2022-06-07 17:00:52][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2022-06-07 17:00:52][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:00:52][DEBUG] : Send: 0x045e0007000000
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x045e0013000c00222c02770c01010000000000
[2022-06-07 17:00:52][DEBUG] : APS data confirm response for request with id 44: 00
[2022-06-07 17:00:52][DEBUG] : Request id: 0x2c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=1>, status: 0x00
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621252.830882, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621252.830882, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x0e5f000700aa00
[2022-06-07 17:00:52][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:00:52][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:00:52][DEBUG] : Send: 0x175f000800010001
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x175f0036002f00220200000102770c01040100001800082b01060000420b4175672020382032303230004000420000afff82f63100c2
[2022-06-07 17:00:52][DEBUG] : APS data indication response: [47, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 0, b'\x08+\x01\x06\x00\x00B\x0bAug  8 2020\x00@\x00B\x00', 0, 175, 255, 130, 246, 49, 0, -62]
[2022-06-07 17:00:52][DEBUG] : [0x0C77:1:0x0000] Received ZCL frame: b'\x08+\x01\x06\x00\x00B\x0bAug  8 2020\x00@\x00B\x00'
[2022-06-07 17:00:52][DEBUG] : [0x0C77:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=43, command_id=1, *is_reply=True)
[2022-06-07 17:00:52][DEBUG] : [0x0C77:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0006, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='Aug  8 2020')), ReadAttributeRecord(attrid=0x4000, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value=''))])
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621252.893038, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621252.893038, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:00:52][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'082b01060000420b41756720203820323032300040004200'
[2022-06-07 17:00:52][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 6=Aug  8 2020 on cluster 0
[2022-06-07 17:00:52][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 16384= on cluster 0
[2022-06-07 17:00:52][WARNING] : [54:ef:44:10:00:25:95:cf][zdevices.get_basic_info] End get basic info from device
[2022-06-07 17:00:52][INFO] : [54:ef:44:10:00:25:95:cf][zdevices.initialize] It's deconz key, force neightbors scan
[2022-06-07 17:00:52][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'0': {'6': {'value': 'Aug  8 2020', 'cluster_name': 'Basic'}, '16384': {'value': '', 'cluster_name': 'Basic'}}}}}}
[2022-06-07 17:00:52][DEBUG] : Tries remaining: 3
[2022-06-07 17:00:52][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:00:52][DEBUG] : Sending Zigbee request with tsn 45 under 46 request id, data: b'2d00'
[2022-06-07 17:00:52][DEBUG] : Command Command.aps_data_request (17, 46, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'-\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:00:52][DEBUG] : Send: 0x126000180011002e0002000000000031000002002d000200
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x12600009000200222e
[2022-06-07 17:00:52][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 46]
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x0e61000700ae00
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:52][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2022-06-07 17:00:52][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 6, 'Aug  8 2020'))
[2022-06-07 17:00:52][DEBUG] : Send: 0x1761000800010001
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 6, 'Aug  8 2020')) completed
[2022-06-07 17:00:52][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x17610039003200260200000002000000000031801b002d00010001abbc06ffff2e2100cf9525001044ef54770c250001ff00afff82f63100c2
[2022-06-07 17:00:52][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'-\x00\x01\x00\x01\xab\xbc\x06\xff\xff.!\x00\xcf\x95%\x00\x10D\xefTw\x0c%\x00\x01\xff', 0, 175, 255, 130, 246, 49, 0, -62]
[2022-06-07 17:00:52][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'2d00010001abbc06ffff2e2100cf9525001044ef54770c250001ff'
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:52][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:00:52][DEBUG] : Send: 0x04620007000000
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 16384, ''))
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 0, 16384, '')) completed
[2022-06-07 17:00:52][DEBUG] : Frame received: 0x04620013000c00222e02000000000000000000
[2022-06-07 17:00:52][DEBUG] : APS data confirm response for request with id 46: 00
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:52][DEBUG] : Request id: 0x2e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2022-06-07 17:00:52][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=1, start_index=0, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:06:bc:ab, ieee=54:ef:44:10:00:25:95:cf, nwk=0x0C77, device_type=<DeviceType.Router: 1>, rx_on_when_idle=<RxOnWhenIdle.On: 1>, relationship=<RelationShip.Sibling: 2>, reserved1=0, permit_joining=<PermitJoins.NotAccepting: 0>, reserved2=0, depth=1, lqi=255)])
[2022-06-07 17:00:52][DEBUG] : [0x0000] Done scanning. Total 1 neighbours
[2022-06-07 17:00:52][INFO] : [54:ef:44:10:00:25:95:cf][zdevices.initialize] End device initialize
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'DELETE FROM neighbors_v9 WHERE device_ieee = ?', (00:21:2e:ff:ff:06:bc:ab,))
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'DELETE FROM neighbors_v9 WHERE device_ieee = ?', (00:21:2e:ff:ff:06:bc:ab,)) completed
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO neighbors_v9 VALUES (?,?,?,?,?,?,?,?,?,?,?,?)', [(00:21:2e:ff:ff:06:bc:ab, 00:21:2e:ff:ff:06:bc:ab, 54:ef:44:10:00:25:95:cf, 0x0C77, <DeviceType.Router: 1>, <RxOnWhenIdle.On: 1>, <RelationShip.Sibling: 2>, 0, <PermitJoins.NotAccepting: 0>, 0, 1, 255)])
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO neighbors_v9 VALUES (?,?,?,?,?,?,?,?,?,?,?,?)', [(00:21:2e:ff:ff:06:bc:ab, 00:21:2e:ff:ff:06:bc:ab, 54:ef:44:10:00:25:95:cf, 0x0C77, <DeviceType.Router: 1>, <RxOnWhenIdle.On: 1>, <RelationShip.Sibling: 2>, 0, <PermitJoins.NotAccepting: 0>, 0, 1, 255)]) completed
[2022-06-07 17:00:52][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:00:52][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:01:18][INFO] : [DeviceHandler.prepare] Json arg : None
[2022-06-07 17:01:18][INFO] : [00:21:2e:ff:ff:06:bc:ab][zdevices.serialize] Serialize device with attributes : 2
[2022-06-07 17:01:18][INFO] : [54:ef:44:10:00:25:95:cf][zdevices.serialize] Serialize device with attributes : 2
[2022-06-07 17:01:18][INFO] : 200 GET /device/all?with_attributes=2 (127.0.0.1) 12.18ms
[2022-06-07 17:01:18][INFO] : [DeviceHandler.prepare] Json arg : {'ieee': '54:ef:44:10:00:25:95:cf', 'endpoint': 1, 'cluster': 6, 'cluster_type': 'in', 'attributes': [0], 'allowCache': 0}
[2022-06-07 17:01:18][INFO] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 162, in post
    raise Exception("Cluster not found : "+str(self.json_args['cluster']))
Exception: Cluster not found : 6

[2022-06-07 17:01:18][INFO] : 200 POST /device/attributes (127.0.0.1) 4.75ms
[2022-06-07 17:01:18][INFO] : [DeviceHandler.prepare] Json arg : {'ieee': '54:ef:44:10:00:25:95:cf', 'endpoint': 1, 'cluster': 1794, 'cluster_type': 'in', 'attributes': [0], 'allowCache': 0}
[2022-06-07 17:01:18][INFO] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 162, in post
    raise Exception("Cluster not found : "+str(self.json_args['cluster']))
Exception: Cluster not found : 1794

[2022-06-07 17:01:18][INFO] : 200 POST /device/attributes (127.0.0.1) 3.65ms
[2022-06-07 17:01:18][INFO] : [DeviceHandler.prepare] Json arg : {'ieee': '54:ef:44:10:00:25:95:cf', 'endpoint': 1, 'cluster': 2820, 'cluster_type': 'in', 'attributes': [1291], 'allowCache': 0}
[2022-06-07 17:01:18][INFO] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 162, in post
    raise Exception("Cluster not found : "+str(self.json_args['cluster']))
Exception: Cluster not found : 2820

[2022-06-07 17:01:18][INFO] : 200 POST /device/attributes (127.0.0.1) 5.41ms
[2022-06-07 17:01:18][INFO] : [GroupHandler.prepare] Json arg : None
[2022-06-07 17:01:18][INFO] : 200 GET /group/all (127.0.0.1) 3.37ms
[2022-06-07 17:01:19][INFO] : [DeviceHandler.prepare] Json arg : None
[2022-06-07 17:01:19][INFO] : [54:ef:44:10:00:25:95:cf][zdevices.serialize] Serialize device with attributes : 1
[2022-06-07 17:01:19][INFO] : 200 GET /device/info?ieee=54%3Aef%3A44%3A10%3A00%3A25%3A95%3Acf (127.0.0.1) 9.89ms
[2022-06-07 17:01:19][INFO] : [DeviceHandler.prepare] Json arg : {'ieee': '54:ef:44:10:00:25:95:cf', 'attributes': [{'endpoint': 1, 'cluster_type': 'in', 'cluster': 10, 'attributes': {'0': 707936479, '1': 1, '2': '7200'}}], 'allowQueue': False}
[2022-06-07 17:01:19][DEBUG] : [0x0C77:1:0x000a] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=<GeneralCommand.Write_Attributes: 2>, *is_reply=False)
[2022-06-07 17:01:19][DEBUG] : [0x0C77:1:0x000a] Sending request: Write_Attributes(attributes=[Attribute(attrid=0x0000, value=TypeValue(type=UTCTime, value=707936479)), Attribute(attrid=0x0001, value=TypeValue(type=bitmap8, value=<bitmap8.1: 1>)), Attribute(attrid=0x0002, value=TypeValue(type=int32s, value=7200))])
[2022-06-07 17:01:19][DEBUG] : Sending Zigbee request with tsn 47 under 48 request id, data: b'002f020000e2df40322a0100180102002b201c0000'
[2022-06-07 17:01:19][DEBUG] : Command Command.aps_data_request (36, 48, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=1>, 260, 10, 1, b'\x00/\x02\x00\x00\xe2\xdf@2*\x01\x00\x18\x01\x02\x00+ \x1c\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:01:19][DEBUG] : Send: 0x1263002b002400300002770c0104010a00011500002f020000e2df40322a0100180102002b201c00000200
[2022-06-07 17:01:19][DEBUG] : Frame received: 0x126300090002002230
[2022-06-07 17:01:19][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 48]
[2022-06-07 17:01:19][DEBUG] : Frame received: 0x0e64000700a600
[2022-06-07 17:01:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2022-06-07 17:01:19][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:01:19][DEBUG] : Send: 0x04640007000000
[2022-06-07 17:01:19][DEBUG] : Frame received: 0x04640013000c00223002770c01010000000000
[2022-06-07 17:01:19][DEBUG] : APS data confirm response for request with id 48: 00
[2022-06-07 17:01:19][DEBUG] : Request id: 0x30 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=1>, status: 0x00
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621279.598853, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621279.598853, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:01:19][DEBUG] : Frame received: 0x0e65000700aa00
[2022-06-07 17:01:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:01:19][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:01:19][DEBUG] : Send: 0x1765000800010001
[2022-06-07 17:01:19][DEBUG] : Frame received: 0x17650022001b00220200000102770c0104010a000400082f040000afffa9f63100bd
[2022-06-07 17:01:19][DEBUG] : APS data indication response: [27, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 10, b'\x08/\x04\x00', 0, 175, 255, 169, 246, 49, 0, -67]
[2022-06-07 17:01:19][DEBUG] : [0x0C77:1:0x000a] Received ZCL frame: b'\x08/\x04\x00'
[2022-06-07 17:01:19][DEBUG] : [0x0C77:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=4, *is_reply=True)
[2022-06-07 17:01:19][DEBUG] : [0x0C77:1:0x000a] Decoded ZCL frame: Time:Write_Attributes_rsp(status_records=[WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)])
[2022-06-07 17:01:19][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0x000a, data: b'082f0400'
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621279.665155, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:01:19][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 0=707936479 on cluster 10
[2022-06-07 17:01:19][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 1=bitmap8.1 on cluster 10
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621279.665155, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:01:19][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 2=7200 on cluster 10
[2022-06-07 17:01:19][INFO] : 200 PUT /device/attributes (127.0.0.1) 649.47ms
[2022-06-07 17:01:19][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 10, 0, 707936479))
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 10, 0, 707936479)) completed
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 10, 1, <bitmap8.1: 1>))
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 10, 1, <bitmap8.1: 1>)) completed
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 10, 2, 7200))
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 10, 2, 7200)) completed
[2022-06-07 17:01:19][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:01:19][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:01:19][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'10': {'0': {'value': '707936479', 'cluster_name': 'Time'}, '1': {'value': 'bitmap8.1', 'cluster_name': 'Time'}, '2': {'value': '7200', 'cluster_name': 'Time'}}}}}}
[2022-06-07 17:01:19][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:01:19][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:01:34][INFO] : [54:ef:44:10:00:25:95:cf][zdevices.check_write_attributes] Check write attribute for : {'ieee': '54:ef:44:10:00:25:95:cf', 'attributes': [{'endpoint': 1, 'cluster_type': 'in', 'cluster': 10, 'attributes': {'0': 707936479, '1': 1, '2': '7200'}}], 'allowQueue': False}
[2022-06-07 17:01:34][INFO] : [54:ef:44:10:00:25:95:cf][zdevices.check_write_attributes] All attribute write succefull do nothing
[2022-06-07 17:02:06][DEBUG] : Frame received: 0x0e66000700aa00
[2022-06-07 17:02:06][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:02:06][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:02:06][DEBUG] : Send: 0x1766000800010001
[2022-06-07 17:02:06][DEBUG] : Frame received: 0x17660025001e00220200000102770c0104010a0007001009000000020000afffb0f63100bf
[2022-06-07 17:02:06][DEBUG] : APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 10, b'\x10	\x00\x00\x00\x02\x00', 0, 175, 255, 176, 246, 49, 0, -65]
[2022-06-07 17:02:06][DEBUG] : [0x0C77:1:0x000a] Received ZCL frame: b'\x10	\x00\x00\x00\x02\x00'
[2022-06-07 17:02:06][DEBUG] : [0x0C77:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=0, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=9, command_id=0, *is_reply=False)
[2022-06-07 17:02:06][DEBUG] : [0x0C77:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[0, 2])
[2022-06-07 17:02:06][DEBUG] : [0x0C77:1:0x000a] Received command 0x00 (TSN 9): Read_Attributes(attribute_ids=[0, 2])
[2022-06-07 17:02:06][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Time object at 0x7fac555da0> cluster_id: 0x000a command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=False, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=9, command_id=0, *is_reply=False) args: (Read_Attributes(attribute_ids=[0, 2]),)
[2022-06-07 17:02:06][DEBUG] : [0x0C77:1:0x000a] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=9, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *is_reply=True)
[2022-06-07 17:02:06][DEBUG] : [0x0C77:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=UTCTime, value=707936526)), ReadAttributeRecord(attrid=0x0002, status=<Status.SUCCESS: 0>, value=TypeValue(type=int32s, value=0))])
[2022-06-07 17:02:06][DEBUG] : Sending Zigbee request with tsn 9 under 49 request id, data: b'180901000000e20e41322a0200002b00000000'
[2022-06-07 17:02:06][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621326.781438, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:02:06][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0x000a, data: b'10090000000200'
[2022-06-07 17:02:06][DEBUG] : Command Command.aps_data_request (34, 49, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=1>, 260, 10, 1, b'\x18	\x01\x00\x00\x00\xe2\x0eA2*\x02\x00\x00+\x00\x00\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:02:06][DEBUG] : Send: 0x12670029002200310002770c0104010a00011300180901000000e20e41322a0200002b000000000200
[2022-06-07 17:02:06][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621326.781438, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:02:06][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:02:06][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:02:06][DEBUG] : Frame received: 0x126700090002002231
[2022-06-07 17:02:06][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 49]
[2022-06-07 17:02:06][DEBUG] : Frame received: 0x0e68000700a600
[2022-06-07 17:02:06][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2022-06-07 17:02:06][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:02:06][DEBUG] : Send: 0x04680007000000
[2022-06-07 17:02:06][DEBUG] : Frame received: 0x04680013000c00223102770c01010000000000
[2022-06-07 17:02:06][DEBUG] : APS data confirm response for request with id 49: 00
[2022-06-07 17:02:06][DEBUG] : Request id: 0x31 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=1>, status: 0x00
[2022-06-07 17:02:06][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621326.818821, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:02:06][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621326.818821, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:02:06][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:02:06][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:03:24][INFO] : [DeviceHandler.prepare] Json arg : {'ieee': '54:ef:44:10:00:25:95:cf', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'on', 'await': 1}], 'allowQueue': False}
[2022-06-07 17:03:24][INFO] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 269, in put
    await zdevices.command(self.json_args)
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zdevices.py", line 54, in command
    "["+str(device._ieee)+"][zdevices.command] Cluster not found : "+str(cmd['cluster']))
Exception: [54:ef:44:10:00:25:95:cf][zdevices.command] Cluster not found : on_off

[2022-06-07 17:03:24][INFO] : 200 PUT /device/command (127.0.0.1) 5.00ms
[2022-06-07 17:04:59][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2022-06-07 17:04:59][DEBUG] : Send: 0x0b69000c0005002658020000
[2022-06-07 17:04:59][DEBUG] : Frame received: 0x0b69000800010026
[2022-06-07 17:04:59][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2022-06-07 17:06:34][DEBUG] : Frame received: 0x0e6a000700aa00
[2022-06-07 17:06:34][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:06:34][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:06:34][DEBUG] : Send: 0x176a000800010001
[2022-06-07 17:06:34][DEBUG] : Frame received: 0x176a0057005000220200000102770c010401c0fc39001c5f110a0af70041306410000328189839000000009539000000009639946b1545973900000000052101009a20000b2000082115010921000100afffc5f63100bd
[2022-06-07 17:06:34][DEBUG] : APS data indication response: [80, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 64704, b'\x1c_\x11

\xf7\x00A0d\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01', 0, 175, 255, 197, 246, 49, 0, -67]
[2022-06-07 17:06:34][DEBUG] : [0x0C77:1:0xfcc0] Received ZCL frame: b'\x1c_\x11

\xf7\x00A0d\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'
[2022-06-07 17:06:34][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=10, command_id=10, *is_reply=True)
[2022-06-07 17:06:34][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame: ManufacturerSpecificCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))])
[2022-06-07 17:06:34][DEBUG] : [0x0C77:1:0xfcc0] Received command 0x0A (TSN 10): Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))])
[2022-06-07 17:06:34][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.manufacturer_specific.ManufacturerSpecificCluster object at 0x7fac555e80> cluster_id: 0xfcc0 command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=False, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=10, command_id=10, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))]),)
[2022-06-07 17:06:34][DEBUG] : [0x0C77:1:0xfcc0] Attribute report received: 0x00F7=b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'
[2022-06-07 17:06:34][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 247=b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01' on cluster 64704
[2022-06-07 17:06:34][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0xfcc0, data: b'1c5f110a0af70041306410000328189839000000009539000000009639946b1545973900000000052101009a20000b20000821150109210001'
[2022-06-07 17:06:34][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621594.387588, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:06:34][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621594.387588, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:06:34][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:06:34][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:06:34][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))
[2022-06-07 17:06:34][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01')) completed
[2022-06-07 17:06:34][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:06:34][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'64704': {'247': {'value': "b'd\x10\x00\x03(\x18\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x94k\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'", 'cluster_name': 'Manufacturer Specific'}}}}}}
[2022-06-07 17:06:34][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:06:34][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:06:35][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:12:21][DEBUG] : Frame received: 0x0e6b000700aa00
[2022-06-07 17:12:21][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:12:21][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:12:21][DEBUG] : Send: 0x176b000800010001
[2022-06-07 17:12:21][DEBUG] : Frame received: 0x176b0057005000220200000102770c010401c0fc39001c5f110b0af70041306410000328199839000000009539000000009639eef31545973900000000052101009a20000b2000082115010921000100afffdff63100bd
[2022-06-07 17:12:21][DEBUG] : APS data indication response: [80, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 64704, b'\x1c_\x11\x0b
\xf7\x00A0d\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01', 0, 175, 255, 223, 246, 49, 0, -67]
[2022-06-07 17:12:21][DEBUG] : [0x0C77:1:0xfcc0] Received ZCL frame: b'\x1c_\x11\x0b
\xf7\x00A0d\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'
[2022-06-07 17:12:21][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=11, command_id=10, *is_reply=True)
[2022-06-07 17:12:21][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame: ManufacturerSpecificCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))])
[2022-06-07 17:12:21][DEBUG] : [0x0C77:1:0xfcc0] Received command 0x0A (TSN 11): Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))])
[2022-06-07 17:12:21][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.manufacturer_specific.ManufacturerSpecificCluster object at 0x7fac555e80> cluster_id: 0xfcc0 command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=False, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=11, command_id=10, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))]),)
[2022-06-07 17:12:21][DEBUG] : [0x0C77:1:0xfcc0] Attribute report received: 0x00F7=b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'
[2022-06-07 17:12:21][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 247=b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01' on cluster 64704
[2022-06-07 17:12:21][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621941.009337, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:12:21][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654621941.009337, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:12:21][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0xfcc0, data: b'1c5f110b0af70041306410000328199839000000009539000000009639eef31545973900000000052101009a20000b20000821150109210001'
[2022-06-07 17:12:21][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:12:21][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:12:21][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'))
[2022-06-07 17:12:21][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01')) completed
[2022-06-07 17:12:21][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:12:21][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:12:21][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'64704': {'247': {'value': "b'd\x10\x00\x03(\x19\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\xee\xf3\x15E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01	!\x00\x01'", 'cluster_name': 'Manufacturer Specific'}}}}}}
[2022-06-07 17:12:21][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:12:21][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:12:29][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2022-06-07 17:12:29][DEBUG] : Send: 0x0b6c000c0005002658020000
[2022-06-07 17:12:29][DEBUG] : Frame received: 0x0b6c000800010026
[2022-06-07 17:12:29][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2022-06-07 17:14:58][DEBUG] : Tries remaining: 3
[2022-06-07 17:14:58][DEBUG] : Sending Zigbee request with tsn 50 under 51 request id, data: b'3200'
[2022-06-07 17:14:58][DEBUG] : Command Command.aps_data_request (17, 51, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'2\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:14:58][DEBUG] : Send: 0x126d00180011003300020000000000310000020032000200
[2022-06-07 17:14:58][DEBUG] : Frame received: 0x126d00090002002233
[2022-06-07 17:14:58][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 51]
[2022-06-07 17:14:58][DEBUG] : Frame received: 0x0e6e000700ae00
[2022-06-07 17:14:58][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2022-06-07 17:14:58][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:14:58][DEBUG] : Send: 0x176e000800010001
[2022-06-07 17:14:58][DEBUG] : Frame received: 0x176e0039003200260200000002000000000031801b003200010001abbc06ffff2e2100cf9525001044ef54770c250001fc00afddeaf631001e
[2022-06-07 17:14:58][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'2\x00\x01\x00\x01\xab\xbc\x06\xff\xff.!\x00\xcf\x95%\x00\x10D\xefTw\x0c%\x00\x01\xfc', 0, 175, 221, 234, 246, 49, 0, 30]
[2022-06-07 17:14:58][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'3200010001abbc06ffff2e2100cf9525001044ef54770c250001fc'
[2022-06-07 17:14:58][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:14:58][DEBUG] : Send: 0x046f0007000000
[2022-06-07 17:14:58][DEBUG] : Frame received: 0x046f0013000c00223302000000000000000000
[2022-06-07 17:14:58][DEBUG] : APS data confirm response for request with id 51: 00
[2022-06-07 17:14:58][DEBUG] : Request id: 0x33 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2022-06-07 17:14:58][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=1, start_index=0, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:06:bc:ab, ieee=54:ef:44:10:00:25:95:cf, nwk=0x0C77, device_type=<DeviceType.Router: 1>, rx_on_when_idle=<RxOnWhenIdle.On: 1>, relationship=<RelationShip.Sibling: 2>, reserved1=0, permit_joining=<PermitJoins.NotAccepting: 0>, reserved2=0, depth=1, lqi=252)])
[2022-06-07 17:14:58][DEBUG] : [0x0000] Done scanning. Total 1 neighbours
[2022-06-07 17:14:58][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'DELETE FROM neighbors_v9 WHERE device_ieee = ?', (00:21:2e:ff:ff:06:bc:ab,))
[2022-06-07 17:14:58][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'DELETE FROM neighbors_v9 WHERE device_ieee = ?', (00:21:2e:ff:ff:06:bc:ab,)) completed
[2022-06-07 17:14:58][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO neighbors_v9 VALUES (?,?,?,?,?,?,?,?,?,?,?,?)', [(00:21:2e:ff:ff:06:bc:ab, 00:21:2e:ff:ff:06:bc:ab, 54:ef:44:10:00:25:95:cf, 0x0C77, <DeviceType.Router: 1>, <RxOnWhenIdle.On: 1>, <RelationShip.Sibling: 2>, 0, <PermitJoins.NotAccepting: 0>, 0, 1, 252)])
[2022-06-07 17:14:58][DEBUG] : operation functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO neighbors_v9 VALUES (?,?,?,?,?,?,?,?,?,?,?,?)', [(00:21:2e:ff:ff:06:bc:ab, 00:21:2e:ff:ff:06:bc:ab, 54:ef:44:10:00:25:95:cf, 0x0C77, <DeviceType.Router: 1>, <RxOnWhenIdle.On: 1>, <RelationShip.Sibling: 2>, 0, <PermitJoins.NotAccepting: 0>, 0, 1, 252)]) completed
[2022-06-07 17:14:58][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:14:58][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:04][INFO] : [DeviceHandler.prepare] Json arg : None
[2022-06-07 17:15:04][INFO] : [00:21:2e:ff:ff:06:bc:ab][zdevices.serialize] Serialize device with attributes : 1
[2022-06-07 17:15:04][INFO] : [54:ef:44:10:00:25:95:cf][zdevices.serialize] Serialize device with attributes : 1
[2022-06-07 17:15:04][INFO] : 200 GET /device/all (127.0.0.1) 66.91ms
[2022-06-07 17:15:44][DEBUG] : Frame received: 0x0e70000700aa00
[2022-06-07 17:15:44][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:15:44][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:15:44][DEBUG] : Send: 0x1770000800010001
[2022-06-07 17:15:44][DEBUG] : Frame received: 0x17700028002100220200000102770c29040112000a00080c0a5500390000000000afffeef63100ba
[2022-06-07 17:15:44][DEBUG] : APS data indication response: [33, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 41, 260, 18, b'\x08\x0c\nU\x009\x00\x00\x00\x00', 0, 175, 255, 238, 246, 49, 0, -70]
[2022-06-07 17:15:44][DEBUG] : [0x0C77:41:0x0012] Received ZCL frame: b'\x08\x0c\nU\x009\x00\x00\x00\x00'
[2022-06-07 17:15:44][DEBUG] : [0x0C77:41:0x0012] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=12, command_id=10, *is_reply=True)
[2022-06-07 17:15:44][DEBUG] : [0x0C77:41:0x0012] Decoded ZCL frame: MultistateInput:Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))])
[2022-06-07 17:15:44][DEBUG] : [0x0C77:41:0x0012] Received command 0x0A (TSN 12): Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))])
[2022-06-07 17:15:44][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.general.MultistateInput object at 0x7fac59e470> cluster_id: 0x0012 command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=False, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=12, command_id=10, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))]),)
[2022-06-07 17:15:44][DEBUG] : [0x0C77:41:0x0012] Attribute report received: present_value=0.0
[2022-06-07 17:15:44][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 85=0.0 on cluster 18
[2022-06-07 17:15:44][DEBUG] : [0x0C77:41:0x0012] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=12, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[2022-06-07 17:15:44][DEBUG] : [0x0C77:41:0x0012] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
[2022-06-07 17:15:44][DEBUG] : Sending Zigbee request with tsn 12 under 52 request id, data: b'180c0b0a00'
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622144.843379, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622144.843379, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:15:44][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 41, profile: 0x0104, cluster_id: 0x0012, data: b'080c0a55003900000000'
[2022-06-07 17:15:44][DEBUG] : Command Command.aps_data_request (20, 52, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=41>, 260, 18, 1, b'\x18\x0c\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:15:44][DEBUG] : Send: 0x1271001b001400340002770c2904011200010500180c0b0a000200
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 41, 18, 85, 0.0))
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 41, 18, 85, 0.0)) completed
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:44][DEBUG] : Frame received: 0x127100090002002234
[2022-06-07 17:15:44][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 52]
[2022-06-07 17:15:44][DEBUG] : Frame received: 0x0e72000700a600
[2022-06-07 17:15:44][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2022-06-07 17:15:44][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:15:44][DEBUG] : Send: 0x04720007000000
[2022-06-07 17:15:44][DEBUG] : Frame received: 0x04720013000c00223402770c29010000000000
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:44][DEBUG] : APS data confirm response for request with id 52: 00
[2022-06-07 17:15:44][DEBUG] : Request id: 0x34 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=41>, status: 0x00
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622144.888811, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622144.888811, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:44][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'41': {'18': {'85': {'value': '0.0', 'cluster_name': 'MultistateInput'}}}}}}
[2022-06-07 17:15:44][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:15:44][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:15:44][DEBUG] : Frame received: 0x0e73000700aa00
[2022-06-07 17:15:44][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:15:44][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:15:44][DEBUG] : Send: 0x1773000800010001
[2022-06-07 17:15:44][DEBUG] : Frame received: 0x17730028002100220200000102770c1504010c000a00080d0a5500390000000000afffeef63100ba
[2022-06-07 17:15:44][DEBUG] : APS data indication response: [33, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 21, 260, 12, b'\x08\r\nU\x009\x00\x00\x00\x00', 0, 175, 255, 238, 246, 49, 0, -70]
[2022-06-07 17:15:44][DEBUG] : [0x0C77:21:0x000c] Received ZCL frame: b'\x08\r\nU\x009\x00\x00\x00\x00'
[2022-06-07 17:15:44][DEBUG] : [0x0C77:21:0x000c] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=13, command_id=10, *is_reply=True)
[2022-06-07 17:15:44][DEBUG] : [0x0C77:21:0x000c] Decoded ZCL frame: AnalogInput:Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))])
[2022-06-07 17:15:44][DEBUG] : [0x0C77:21:0x000c] Received command 0x0A (TSN 13): Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))])
[2022-06-07 17:15:44][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.general.AnalogInput object at 0x7faeebfbe0> cluster_id: 0x000c command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=False, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=13, command_id=10, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))]),)
[2022-06-07 17:15:44][DEBUG] : [0x0C77:21:0x000c] Attribute report received: present_value=0.0
[2022-06-07 17:15:44][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 85=0.0 on cluster 12
[2022-06-07 17:15:44][DEBUG] : [0x0C77:21:0x000c] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=13, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[2022-06-07 17:15:44][DEBUG] : [0x0C77:21:0x000c] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
[2022-06-07 17:15:44][DEBUG] : Sending Zigbee request with tsn 13 under 53 request id, data: b'180d0b0a00'
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622144.976336, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:15:44][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 21, profile: 0x0104, cluster_id: 0x000c, data: b'080d0a55003900000000'
[2022-06-07 17:15:44][DEBUG] : Command Command.aps_data_request (20, 53, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=21>, 260, 12, 1, b'\x18\r\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:15:44][DEBUG] : Send: 0x1274001b001400350002770c1504010c00010500180d0b0a000200
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622144.976336, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:44][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 21, 12, 85, 0.0))
[2022-06-07 17:15:44][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 21, 12, 85, 0.0)) completed
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x127400090002002235
[2022-06-07 17:15:45][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 53]
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x0e75000700a600
[2022-06-07 17:15:45][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:45][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:15:45][DEBUG] : Send: 0x04750007000000
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x04750013000c00223502770c15010000000000
[2022-06-07 17:15:45][DEBUG] : APS data confirm response for request with id 53: 00
[2022-06-07 17:15:45][DEBUG] : Request id: 0x35 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=21>, status: 0x00
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622145.013014, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622145.013014, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:45][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'21': {'12': {'85': {'value': '0.0', 'cluster_name': 'AnalogInput'}}}}}}
[2022-06-07 17:15:45][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:15:45][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x0e76000700aa00
[2022-06-07 17:15:45][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:15:45][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:15:45][DEBUG] : Send: 0x1776000800010001
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x17760028002100220200000102770c1f04010c000a00080e0a5500390000000000afffeef63100ba
[2022-06-07 17:15:45][DEBUG] : APS data indication response: [33, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 31, 260, 12, b'\x08\x0e\nU\x009\x00\x00\x00\x00', 0, 175, 255, 238, 246, 49, 0, -70]
[2022-06-07 17:15:45][DEBUG] : [0x0C77:31:0x000c] Received ZCL frame: b'\x08\x0e\nU\x009\x00\x00\x00\x00'
[2022-06-07 17:15:45][DEBUG] : [0x0C77:31:0x000c] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=14, command_id=10, *is_reply=True)
[2022-06-07 17:15:45][DEBUG] : [0x0C77:31:0x000c] Decoded ZCL frame: AnalogInput:Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))])
[2022-06-07 17:15:45][DEBUG] : [0x0C77:31:0x000c] Received command 0x0A (TSN 14): Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))])
[2022-06-07 17:15:45][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.general.AnalogInput object at 0x7faeed1390> cluster_id: 0x000c command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=False, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=14, command_id=10, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x0055, value=TypeValue(type=Single, value=0.0))]),)
[2022-06-07 17:15:45][DEBUG] : [0x0C77:31:0x000c] Attribute report received: present_value=0.0
[2022-06-07 17:15:45][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 85=0.0 on cluster 12
[2022-06-07 17:15:45][DEBUG] : [0x0C77:31:0x000c] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=14, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[2022-06-07 17:15:45][DEBUG] : [0x0C77:31:0x000c] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
[2022-06-07 17:15:45][DEBUG] : Sending Zigbee request with tsn 14 under 54 request id, data: b'180e0b0a00'
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622145.182828, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622145.182828, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:15:45][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 31, profile: 0x0104, cluster_id: 0x000c, data: b'080e0a55003900000000'
[2022-06-07 17:15:45][DEBUG] : Command Command.aps_data_request (20, 54, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0C77 endpoint=31>, 260, 12, 1, b'\x18\x0e\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2022-06-07 17:15:45][DEBUG] : Send: 0x1277001b001400360002770c1f04010c00010500180e0b0a000200
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 31, 12, 85, 0.0))
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 31, 12, 85, 0.0)) completed
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x127700090002002236
[2022-06-07 17:15:45][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 54]
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x0e78000700a600
[2022-06-07 17:15:45][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2022-06-07 17:15:45][DEBUG] : Command Command.aps_data_confirm (0,)
[2022-06-07 17:15:45][DEBUG] : Send: 0x04780007000000
[2022-06-07 17:15:45][DEBUG] : Frame received: 0x04780013000c00223602770c1f010000000000
[2022-06-07 17:15:45][DEBUG] : APS data confirm response for request with id 54: 00
[2022-06-07 17:15:45][DEBUG] : Request id: 0x36 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0c77 endpoint=31>, status: 0x00
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622145.230692, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622145.230692, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:15:45][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:15:45][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:15:45][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'31': {'12': {'85': {'value': '0.0', 'cluster_name': 'AnalogInput'}}}}}}
[2022-06-07 17:15:45][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:15:45][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0
[2022-06-07 17:15:50][INFO] : [DeviceHandler.prepare] Json arg : {'ieee': '54:ef:44:10:00:25:95:cf', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'on', 'await': 1}], 'allowQueue': False}
[2022-06-07 17:15:50][INFO] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 269, in put
    await zdevices.command(self.json_args)
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zdevices.py", line 54, in command
    "["+str(device._ieee)+"][zdevices.command] Cluster not found : "+str(cmd['cluster']))
Exception: [54:ef:44:10:00:25:95:cf][zdevices.command] Cluster not found : on_off

[2022-06-07 17:15:50][INFO] : 200 PUT /device/command (127.0.0.1) 4.28ms
[2022-06-07 17:18:04][DEBUG] : Frame received: 0x0e79000700aa00
[2022-06-07 17:18:04][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2022-06-07 17:18:04][DEBUG] : Command Command.aps_data_indication (1, 1)
[2022-06-07 17:18:04][DEBUG] : Send: 0x1779000800010001
[2022-06-07 17:18:04][DEBUG] : Frame received: 0x17790057005000220200000102770c010401c0fc39001c5f110f0af700413064100003281a983900000000953900000000963965131645973900000000052101009a20000b2000082115010921000100afff01f73100bf
[2022-06-07 17:18:04][DEBUG] : APS data indication response: [80, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, 1, 260, 64704, b'\x1c_\x11\x0f\n\xf7\x00A0d\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01', 0, 175, 255, 1, 247, 49, 0, -65]
[2022-06-07 17:18:04][DEBUG] : [0x0C77:1:0xfcc0] Received ZCL frame: b'\x1c_\x11\x0f\n\xf7\x00A0d\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01'
[2022-06-07 17:18:04][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=15, command_id=10, *is_reply=True)
[2022-06-07 17:18:04][DEBUG] : [0x0C77:1:0xfcc0] Decoded ZCL frame: ManufacturerSpecificCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01'))])
[2022-06-07 17:18:04][DEBUG] : [0x0C77:1:0xfcc0] Received command 0x0A (TSN 15): Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01'))])
[2022-06-07 17:18:04][INFO] : [54:ef:44:10:00:25:95:cf][listener.general_command] Cluster: <zigpy.zcl.clusters.manufacturer_specific.ManufacturerSpecificCluster object at 0x7fac555e80> cluster_id: 0xfcc0 command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=False, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=15, command_id=10, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01'))]),)
[2022-06-07 17:18:04][DEBUG] : [0x0C77:1:0xfcc0] Attribute report received: 0x00F7=b'd\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01'
[2022-06-07 17:18:04][INFO] : [54:ef:44:10:00:25:95:cf][listener.attribute_updated] Received an attribute update 247=b'd\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01' on cluster 64704
[2022-06-07 17:18:04][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0c77>, ep: 1, profile: 0x0104, cluster_id: 0xfcc0, data: b'1c5f110f0af700413064100003281a983900000000953900000000963965131645973900000000052101009a20000b20000821150109210001'
[2022-06-07 17:18:04][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622284.538943, 54:ef:44:10:00:25:95:cf))
[2022-06-07 17:18:04][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'UPDATE devices_v9 SET last_seen=? WHERE ieee=?', (1654622284.538943, 54:ef:44:10:00:25:95:cf)) completed
[2022-06-07 17:18:04][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:18:04][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:18:04][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01'))
[2022-06-07 17:18:04][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7faef21570>, 'INSERT INTO attributes_cache_v9 VALUES (?, ?, ?, ?, ?)\n                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n                    DO UPDATE SET\n                        value=excluded.value', (54:ef:44:10:00:25:95:cf, 1, 64704, 247, b'd\x10\x00\x03(\x1a\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969e\x13\x16E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x0b \x00\x08!\x15\x01\t!\x00\x01')) completed
[2022-06-07 17:18:04][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>)
[2022-06-07 17:18:04][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7faef21570>) completed
[2022-06-07 17:18:04][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:25:95:cf': {'1': {'64704': {'247': {'value': "b'd\\x10\\x00\\x03(\\x1a\\x989\\x00\\x00\\x00\\x00\\x959\\x00\\x00\\x00\\x00\\x969e\\x13\\x16E\\x979\\x00\\x00\\x00\\x00\\x05!\\x01\\x00\\x9a \\x00\\x0b \\x00\\x08!\\x15\\x01\\t!\\x00\\x01'", 'cluster_name': 'Manufacturer Specific'}}}}}}
[2022-06-07 17:18:04][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-06-07 17:18:04][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=m0aj1qp03qBiFbjTnKg3b4T5aGV86gl5YaZmGTrqlOuOfkFZPEirebfEpOlWQAKx HTTP/1.1" 200 0