Jeedom 4.1.28
installé sur un NAS Synology DSM 7.0.1-42218
sur une VM avec 2Go de Ram
Debian GNU/Linux 10 (buster) 64bits (x86_64)
ma clé zigbee est une Popp
Système à jour OK
Cron actif OK
Scénario actif OK
Démarré OK 2022-02-18 14:57:31
Date système (dernière heure enregistrée) OK 2022-03-14 17:34:50 (2022-03-14 16:50:02)
Droits sudo OK
Version Jeedom 4.2.14
Version OS debian 10.11
Version PHP 7.3.31-1~deb10u1
Apache 11
Version OS Linux jeepaul02 5.10.63-v7+ #1496 SMP Wed Dec 1 15:58:11 GMT 2021 armv7l GNU/Linux [10.11]
Version database 10.3.31-MariaDB-0+deb10u1
Espace disque libre 62 %
Espace disque libre tmp 99 %
Mémoire disponible 61 % (Total 973 Mo)
Mémoire suffisante 0
Erreur I/O 0
Swap disponible 92 % (Total 1024 Mo)
Swapiness 10 %
Charge 0.49 - 0.55 - 0.54
Configuration réseau interne OK
Configuration réseau externe OK
Persistance du cache OK (2022-03-14 17:30:06)
Apache private tmp OK
Plugins OK
Clé POP
Niveau LOG du plugin Zigbee: defaut
Pourtant dans le LOG Zigbeed_1 il y a plein de ligne debug, est ce normal ?
[2022-03-14 17:39:32][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 32, '0:PWRON@0')) completed
[2022-03-14 17:39:32][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:32][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:32][INFO] : Send to jeedom : {'devices': {'00:17:88:01:08:af:10:a1': {'11': {'0': {'33': {'value': '37446', 'cluster_name': 'Basic'}, '32': {'value': '0:PWRON@0', 'cluster_name': 'Basic'}}}}}}
[2022-03-14 17:39:32][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-03-14 17:39:32][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=CEE19rTiwP65uCb8Pk1UOtQCOAemWIFAThkeNiZll2JQCyW0mOW0sMxRlAT1YRAv HTTP/1.1" 200 0
[2022-03-14 17:39:35][DEBUG] : Data frame: b'3468b1a9112a15b658964d24ab5593499c8e8b6c08069874f1ce8183fc7e1a1aebcdde6f8ffb6e0d7e'
[2022-03-14 17:39:35][DEBUG] : Sending: b'8430fc7e'
[2022-03-14 17:39:35][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'0004010207010100010000c0acc7e5c8ffff0c08e20a000025bd000000000004'
[2022-03-14 17:39:35][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=1794, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=192), 172, -57, 0xc8e5, 255, 255, b'\x08\xe2
\x00\x00%\xbd\x00\x00\x00\x00\x00']
[2022-03-14 17:39:35][DEBUG] : [0xc8e5:1:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=226 command_id=Command.Report_Attributes>
[2022-03-14 17:39:35][DEBUG] : [0xc8e5:1:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=189>)]]
[2022-03-14 17:39:35][INFO] : [a4:c1:38:bd:69:48:13:e5][listener.general_command] Cluster: <zhaquirks.tuya.TuyaZBMeteringCluster object at 0x71388c10> cluster_id: 0x0702 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=226 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=189>)]],)
[2022-03-14 17:39:35][DEBUG] : [0xc8e5:1:0x0702] Attribute report received: current_summ_delivered=189
[2022-03-14 17:39:35][INFO] : [a4:c1:38:bd:69:48:13:e5][listener.attribute_updated] Received an attribute update 0=189 on cluster 1794
[2022-03-14 17:39:35][DEBUG] : Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xC8E5, EmberApsFrame(profileId=260, clusterId=1794, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=226), 111, b'\x18\xe2\x0b
\x00')
[2022-03-14 17:39:35][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (a4:c1:38:bd:69:48:13:e5, 1, 1794, 0, 189))
[2022-03-14 17:39:35][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (a4:c1:38:bd:69:48:13:e5, 1, 1794, 0, 189)) completed
[2022-03-14 17:39:35][DEBUG] : Sending: b'446921a9602a155791904b27ad5493099d4e274982cb7f69f6cc6392fd7e'
[2022-03-14 17:39:35][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:35][DEBUG] : Data frame: b'4569a1a9602a154c5c0d7e'
[2022-03-14 17:39:35][DEBUG] : Sending: b'8520dd7e'
[2022-03-14 17:39:35][DEBUG] : Application frame 52 (sendUnicast) received: b'00fe'
[2022-03-14 17:39:35][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:35][DEBUG] : Data frame: b'5569b1a96b2a155791904b27ad5493099d4e275582ce6773587e'
[2022-03-14 17:39:35][DEBUG] : Sending: b'8610be7e'
[2022-03-14 17:39:35][DEBUG] : Application frame 63 (messageSentHandler) received: b'00e5c804010207010140010000fe6f0000'
[2022-03-14 17:39:35][DEBUG] : Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 51429, EmberApsFrame(profileId=260, clusterId=1794, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=254), 111, <EmberStatus.SUCCESS: 0>, b'']
[2022-03-14 17:39:35][INFO] : Send to jeedom : {'devices': {'a4:c1:38:bd:69:48:13:e5': {'1': {'1794': {'0': {'value': '189', 'cluster_name': 'TuyaZBMeteringCluster'}}}}}}
[2022-03-14 17:39:35][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-03-14 17:39:36][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=CEE19rTiwP65uCb8Pk1UOtQCOAemWIFAThkeNiZll2JQCyW0mOW0sMxRlAT1YRAv HTTP/1.1" 200 0
[2022-03-14 17:39:40][DEBUG] : Send command readCounters: ()
[2022-03-14 17:39:40][DEBUG] : Sending: b'566e21a9a52a20457e'
[2022-03-14 17:39:41][DEBUG] : Data frame: b'666ea1a9a52aafb073948c25bc5592499c4e27abedce0a8bf1c66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a251904824c55d7e'
[2022-03-14 17:39:41][DEBUG] : Sending: b'87009f7e'
[2022-03-14 17:39:41][DEBUG] : Application frame 241 (readCounters) received: b'ba022a00c600160000000000000000006d000c000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000'
[2022-03-14 17:39:41][DEBUG] : Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
[2022-03-14 17:39:41][DEBUG] : Sending: b'676f21a9fe2a1619e67e'
[2022-03-14 17:39:41][DEBUG] : Data frame: b'776fa1a9fe2a15b3a34b837e'
[2022-03-14 17:39:41][DEBUG] : Sending: b'8070787e'
[2022-03-14 17:39:41][DEBUG] : Application frame 170 (getValue) received: b'0001fa'
[2022-03-14 17:39:41][DEBUG] : Free buffers status EzspStatus.SUCCESS, value: 250
[2022-03-14 17:39:41][DEBUG] : ezsp_counters: [MAC_RX_BROADCAST = 167314, MAC_TX_BROADCAST = 9975, MAC_RX_UNICAST = 82977, MAC_TX_UNICAST_SUCCESS = 23868, MAC_TX_UNICAST_RETRY = 937, MAC_TX_UNICAST_FAILED = 158, APS_DATA_RX_BROADCAST = 14, APS_DATA_TX_BROADCAST = 14, APS_DATA_RX_UNICAST = 42774, APS_DATA_TX_UNICAST_SUCCESS = 18367, APS_DATA_TX_UNICAST_RETRY = 24, APS_DATA_TX_UNICAST_FAILED = 7, ROUTE_DISCOVERY_INITIATED = 12, NEIGHBOR_ADDED = 875, NEIGHBOR_REMOVED = 859, NEIGHBOR_STALE = 380, JOIN_INDICATION = 1, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 62, APS_DECRYPTION_FAILURE = 2, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 1, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 2, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 250]
[2022-03-14 17:39:42][DEBUG] : Data frame: b'076fb1a90d2a0249f884e52dabdd85494c9e2594b5feadc5ef7e'
[2022-03-14 17:39:42][DEBUG] : Sending: b'8160597e'
[2022-03-14 17:39:42][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'17fba110af0801881700d0d0023f5830ca'
[2022-03-14 17:39:42][DEBUG] : Received incomingRouteRecordHandler frame with [0xfb17, 00:17:88:01:08:af:10:a1, 208, -48, [0x583f, 0xca30]]
[2022-03-14 17:39:42][DEBUG] : Processing route record request: (0xfb17, 00:17:88:01:08:af:10:a1, 208, -48, [0x583f, 0xca30])
[2022-03-14 17:39:42][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO relays_v7 VALUES (?, ?)
ON CONFLICT (ieee)
DO UPDATE SET relays=excluded.relays', (00:17:88:01:08:af:10:a1, b'\x02?X0\xca'))
[2022-03-14 17:39:42][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO relays_v7 VALUES (?, ?)
ON CONFLICT (ieee)
DO UPDATE SET relays=excluded.relays', (00:17:88:01:08:af:10:a1, b'\x02?X0\xca')) completed
[2022-03-14 17:39:42][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:42][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:42][DEBUG] : Data frame: b'176fb1a9112a15b658944a2eab5593499c6af77bfa359874e4da6899ec741ea7c89d4c6f8fdfc799dce253dc1171e6a2360ba3f8e47e'
[2022-03-14 17:39:42][DEBUG] : Sending: b'82503a7e'
[2022-03-14 17:39:42][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00040100000b010001000024d0d017fbffff191c0b10100a2100235092000020004209303a5057524f4e403006'
[2022-03-14 17:39:42][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=11, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=36), 208, -48, 0xfb17, 255, 255, b'\x1c\x0b\x10\x10
!\x00#P\x92\x00\x00 \x00B 0:PWRON@0']
[2022-03-14 17:39:42][DEBUG] : [0xfb17:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=16 command_id=Command.Report_Attributes>
[2022-03-14 17:39:42][DEBUG] : [0xfb17:11:0x0000] ZCL request 0x000a: [[Attribute(attrid=33, value=<TypeValue type=uint32_t, value=37456>), Attribute(attrid=32, value=<TypeValue type=CharacterString, value=0:PWRON@0>)]]
[2022-03-14 17:39:42][INFO] : [00:17:88:01:08:af:10:a1][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73a09710> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=False disable_default_response=True> manufacturer=4107 tsn=16 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=33, value=<TypeValue type=uint32_t, value=37456>), Attribute(attrid=32, value=<TypeValue type=CharacterString, value=0:PWRON@0>)]],)
[2022-03-14 17:39:42][DEBUG] : [0xfb17:11:0x0000] Attribute report received: 33=37456, 32=0:PWRON@0
[2022-03-14 17:39:42][INFO] : [00:17:88:01:08:af:10:a1][listener.attribute_updated] Received an attribute update 33=37456 on cluster 0
[2022-03-14 17:39:42][INFO] : [00:17:88:01:08:af:10:a1][listener.attribute_updated] Received an attribute update 32=0:PWRON@0 on cluster 0
[2022-03-14 17:39:42][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 33, 37456))
[2022-03-14 17:39:42][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 33, 37456)) completed
[2022-03-14 17:39:42][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:42][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:42][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 32, '0:PWRON@0'))
[2022-03-14 17:39:42][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 32, '0:PWRON@0')) completed
[2022-03-14 17:39:42][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:42][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:43][INFO] : Send to jeedom : {'devices': {'00:17:88:01:08:af:10:a1': {'11': {'0': {'33': {'value': '37456', 'cluster_name': 'Basic'}, '32': {'value': '0:PWRON@0', 'cluster_name': 'Basic'}}}}}}
[2022-03-14 17:39:43][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-03-14 17:39:43][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=CEE19rTiwP65uCb8Pk1UOtQCOAemWIFAThkeNiZll2JQCyW0mOW0sMxRlAT1YRAv HTTP/1.1" 200 0
[2022-03-14 17:39:51][DEBUG] : Send command readCounters: ()
[2022-03-14 17:39:51][DEBUG] : Sending: b'726c21a9a52a576f7e'
[2022-03-14 17:39:51][DEBUG] : Data frame: b'206ca1a9a52ad1b073948225bc5592499c4e27abedce098bf1c66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a251904824de067e'
[2022-03-14 17:39:51][DEBUG] : Sending: b'83401b7e'
[2022-03-14 17:39:51][DEBUG] : Application frame 241 (readCounters) received: b'c4022a00c800160000000000000000006e000c000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000'
[2022-03-14 17:39:51][DEBUG] : Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
[2022-03-14 17:39:51][DEBUG] : Sending: b'036d21a9fe2a16e07a7e'
[2022-03-14 17:39:51][DEBUG] : Data frame: b'316da1a9fe2a15b3a3c1747e'
[2022-03-14 17:39:51][DEBUG] : Sending: b'8430fc7e'
[2022-03-14 17:39:51][DEBUG] : Application frame 170 (getValue) received: b'0001fa'
[2022-03-14 17:39:51][DEBUG] : Free buffers status EzspStatus.SUCCESS, value: 250
[2022-03-14 17:39:51][DEBUG] : ezsp_counters: [MAC_RX_BROADCAST = 167324, MAC_TX_BROADCAST = 9975, MAC_RX_UNICAST = 82979, MAC_TX_UNICAST_SUCCESS = 23868, MAC_TX_UNICAST_RETRY = 937, MAC_TX_UNICAST_FAILED = 158, APS_DATA_RX_BROADCAST = 14, APS_DATA_TX_BROADCAST = 14, APS_DATA_RX_UNICAST = 42775, APS_DATA_TX_UNICAST_SUCCESS = 18367, APS_DATA_TX_UNICAST_RETRY = 24, APS_DATA_TX_UNICAST_FAILED = 7, ROUTE_DISCOVERY_INITIATED = 12, NEIGHBOR_ADDED = 875, NEIGHBOR_REMOVED = 859, NEIGHBOR_STALE = 380, JOIN_INDICATION = 1, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 62, APS_DECRYPTION_FAILURE = 2, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 1, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 2, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 250]
[2022-03-14 17:39:53][DEBUG] : Data frame: b'416db1a90d2a0249f884e52dabdd85494c9e2594b5fead919c7e'
[2022-03-14 17:39:53][DEBUG] : Sending: b'8520dd7e'
[2022-03-14 17:39:53][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'17fba110af0801881700d0d0023f5830ca'
[2022-03-14 17:39:53][DEBUG] : Received incomingRouteRecordHandler frame with [0xfb17, 00:17:88:01:08:af:10:a1, 208, -48, [0x583f, 0xca30]]
[2022-03-14 17:39:53][DEBUG] : Processing route record request: (0xfb17, 00:17:88:01:08:af:10:a1, 208, -48, [0x583f, 0xca30])
[2022-03-14 17:39:53][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO relays_v7 VALUES (?, ?)
ON CONFLICT (ieee)
DO UPDATE SET relays=excluded.relays', (00:17:88:01:08:af:10:a1, b'\x02?X0\xca'))
[2022-03-14 17:39:53][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO relays_v7 VALUES (?, ?)
ON CONFLICT (ieee)
DO UPDATE SET relays=excluded.relays', (00:17:88:01:08:af:10:a1, b'\x02?X0\xca')) completed
[2022-03-14 17:39:53][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:53][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:53][DEBUG] : Data frame: b'516db1a9112a15b658944a2eab5593499c6bf77bfa359874e4da6899ed741ea7c8974c6f8fdfc799dce253dc1171e6a2360ba3b3d17e'
[2022-03-14 17:39:53][DEBUG] : Sending: b'8610be7e'
[2022-03-14 17:39:53][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00040100000b010001000025d0d017fbffff191c0b10110a2100235a92000020004209303a5057524f4e403006'
[2022-03-14 17:39:53][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=11, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=37), 208, -48, 0xfb17, 255, 255, b'\x1c\x0b\x10\x11
!\x00#Z\x92\x00\x00 \x00B 0:PWRON@0']
[2022-03-14 17:39:53][DEBUG] : [0xfb17:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=17 command_id=Command.Report_Attributes>
[2022-03-14 17:39:53][DEBUG] : [0xfb17:11:0x0000] ZCL request 0x000a: [[Attribute(attrid=33, value=<TypeValue type=uint32_t, value=37466>), Attribute(attrid=32, value=<TypeValue type=CharacterString, value=0:PWRON@0>)]]
[2022-03-14 17:39:53][INFO] : [00:17:88:01:08:af:10:a1][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73a09710> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=False disable_default_response=True> manufacturer=4107 tsn=17 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=33, value=<TypeValue type=uint32_t, value=37466>), Attribute(attrid=32, value=<TypeValue type=CharacterString, value=0:PWRON@0>)]],)
[2022-03-14 17:39:53][DEBUG] : [0xfb17:11:0x0000] Attribute report received: 33=37466, 32=0:PWRON@0
[2022-03-14 17:39:53][INFO] : [00:17:88:01:08:af:10:a1][listener.attribute_updated] Received an attribute update 33=37466 on cluster 0
[2022-03-14 17:39:53][INFO] : [00:17:88:01:08:af:10:a1][listener.attribute_updated] Received an attribute update 32=0:PWRON@0 on cluster 0
[2022-03-14 17:39:53][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 33, 37466))
[2022-03-14 17:39:53][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 33, 37466)) completed
[2022-03-14 17:39:53][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:53][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:53][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 32, '0:PWRON@0'))
[2022-03-14 17:39:53][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 32, '0:PWRON@0')) completed
[2022-03-14 17:39:53][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:39:53][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:39:53][INFO] : Send to jeedom : {'devices': {'00:17:88:01:08:af:10:a1': {'11': {'0': {'33': {'value': '37466', 'cluster_name': 'Basic'}, '32': {'value': '0:PWRON@0', 'cluster_name': 'Basic'}}}}}}
[2022-03-14 17:39:53][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-03-14 17:39:53][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=CEE19rTiwP65uCb8Pk1UOtQCOAemWIFAThkeNiZll2JQCyW0mOW0sMxRlAT1YRAv HTTP/1.1" 200 0
[2022-03-14 17:40:01][DEBUG] : Send command readCounters: ()
[2022-03-14 17:40:01][DEBUG] : Sending: b'167221a9a52ac5247e'
[2022-03-14 17:40:01][DEBUG] : Data frame: b'6272a1a9a52ad8b072948025bc5592499c4e27abedce088bf1c66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a25190482496267e'
[2022-03-14 17:40:01][DEBUG] : Sending: b'87009f7e'
[2022-03-14 17:40:01][DEBUG] : Application frame 241 (readCounters) received: b'cd022b00ca00160000000000000000006f000c000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000'
[2022-03-14 17:40:01][DEBUG] : Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
[2022-03-14 17:40:01][DEBUG] : Sending: b'277321a9fe2a16d56d7e'
[2022-03-14 17:40:01][DEBUG] : Data frame: b'7373a1a9fe2a15b3a3fb6f7e'
[2022-03-14 17:40:01][DEBUG] : Sending: b'8070787e'
[2022-03-14 17:40:01][DEBUG] : Application frame 170 (getValue) received: b'0001fa'
[2022-03-14 17:40:01][DEBUG] : Free buffers status EzspStatus.SUCCESS, value: 250
[2022-03-14 17:40:01][DEBUG] : ezsp_counters: [MAC_RX_BROADCAST = 167333, MAC_TX_BROADCAST = 9976, MAC_RX_UNICAST = 82981, MAC_TX_UNICAST_SUCCESS = 23868, MAC_TX_UNICAST_RETRY = 937, MAC_TX_UNICAST_FAILED = 158, APS_DATA_RX_BROADCAST = 14, APS_DATA_TX_BROADCAST = 14, APS_DATA_RX_UNICAST = 42776, APS_DATA_TX_UNICAST_SUCCESS = 18367, APS_DATA_TX_UNICAST_RETRY = 24, APS_DATA_TX_UNICAST_FAILED = 7, ROUTE_DISCOVERY_INITIATED = 12, NEIGHBOR_ADDED = 875, NEIGHBOR_REMOVED = 859, NEIGHBOR_STALE = 380, JOIN_INDICATION = 1, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 62, APS_DECRYPTION_FAILURE = 2, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 1, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 2, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 250]
[2022-03-14 17:40:03][DEBUG] : Data frame: b'0373b1a90d2a0249f884e52dabdd85494c9e2594b5fead05467e'
[2022-03-14 17:40:03][DEBUG] : Sending: b'8160597e'
[2022-03-14 17:40:03][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'17fba110af0801881700d0d0023f5830ca'
[2022-03-14 17:40:03][DEBUG] : Received incomingRouteRecordHandler frame with [0xfb17, 00:17:88:01:08:af:10:a1, 208, -48, [0x583f, 0xca30]]
[2022-03-14 17:40:03][DEBUG] : Processing route record request: (0xfb17, 00:17:88:01:08:af:10:a1, 208, -48, [0x583f, 0xca30])
[2022-03-14 17:40:03][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO relays_v7 VALUES (?, ?)
ON CONFLICT (ieee)
DO UPDATE SET relays=excluded.relays', (00:17:88:01:08:af:10:a1, b'\x02?X0\xca'))
[2022-03-14 17:40:03][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO relays_v7 VALUES (?, ?)
ON CONFLICT (ieee)
DO UPDATE SET relays=excluded.relays', (00:17:88:01:08:af:10:a1, b'\x02?X0\xca')) completed
[2022-03-14 17:40:03][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:40:03][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:40:03][DEBUG] : Data frame: b'1373b1a9112a15b658944a2eab5593499c68f77bfa359874e4da6899ee741ea7c8a84c6f8fdfc799dce253dc1171e6a2360ba3f1eb7e'
[2022-03-14 17:40:03][DEBUG] : Sending: b'82503a7e'
[2022-03-14 17:40:03][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00040100000b010001000026d0d017fbffff191c0b10120a2100236592000020004209303a5057524f4e403006'
[2022-03-14 17:40:03][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=11, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=38), 208, -48, 0xfb17, 255, 255, b'\x1c\x0b\x10\x12
!\x00#e\x92\x00\x00 \x00B 0:PWRON@0']
[2022-03-14 17:40:03][DEBUG] : [0xfb17:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=18 command_id=Command.Report_Attributes>
[2022-03-14 17:40:03][DEBUG] : [0xfb17:11:0x0000] ZCL request 0x000a: [[Attribute(attrid=33, value=<TypeValue type=uint32_t, value=37477>), Attribute(attrid=32, value=<TypeValue type=CharacterString, value=0:PWRON@0>)]]
[2022-03-14 17:40:03][INFO] : [00:17:88:01:08:af:10:a1][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73a09710> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=False disable_default_response=True> manufacturer=4107 tsn=18 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=33, value=<TypeValue type=uint32_t, value=37477>), Attribute(attrid=32, value=<TypeValue type=CharacterString, value=0:PWRON@0>)]],)
[2022-03-14 17:40:03][DEBUG] : [0xfb17:11:0x0000] Attribute report received: 33=37477, 32=0:PWRON@0
[2022-03-14 17:40:03][INFO] : [00:17:88:01:08:af:10:a1][listener.attribute_updated] Received an attribute update 33=37477 on cluster 0
[2022-03-14 17:40:03][INFO] : [00:17:88:01:08:af:10:a1][listener.attribute_updated] Received an attribute update 32=0:PWRON@0 on cluster 0
[2022-03-14 17:40:03][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 33, 37477))
[2022-03-14 17:40:03][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 33, 37477)) completed
[2022-03-14 17:40:03][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:40:03][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:40:03][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 32, '0:PWRON@0'))
[2022-03-14 17:40:03][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73ad0a20>, 'INSERT INTO attributes_cache_v7 VALUES (?, ?, ?, ?, ?)
ON CONFLICT (ieee, endpoint_id, cluster, attrid)
DO UPDATE SET
value=excluded.value', (00:17:88:01:08:af:10:a1, 11, 0, 32, '0:PWRON@0')) completed
[2022-03-14 17:40:03][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>)
[2022-03-14 17:40:03][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73ad0a20>) completed
[2022-03-14 17:40:03][INFO] : Send to jeedom : {'devices': {'00:17:88:01:08:af:10:a1': {'11': {'0': {'33': {'value': '37477', 'cluster_name': 'Basic'}, '32': {'value': '0:PWRON@0', 'cluster_name': 'Basic'}}}}}}