[2022-11-14 15:53:43][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 2, 0, 2400))
[2022-11-14 15:53:43][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 2, 0, 2400)) completed
[2022-11-14 15:53:43][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 15:53:43][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 15:53:43][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:5d:87:ec': {'1': {'64704': {'247': {'value': "b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'", 'cluster_name': 'Manufacturer Specific'}}, '2': {'0': {'value': '2400', 'cluster_name': 'Device Temperature'}}}}}}
[2022-11-14 15:53:43][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-11-14 15:53:43][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=fYs3tIy5fL8lDK2idHJR0DY5eFOjC2ixyIYQUuKJjvvANyQbeOnLedh5rNm5YPHh HTTP/1.1" 200 0
[2022-11-14 15:53:47][DEBUG] : Frame received: 801100088da712380080051c00
[2022-11-14 15:53:47][DEBUG] : data received 0x8011 b'a712380080051c' LQI:0
[2022-11-14 15:53:47][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 32773, 28]
[2022-11-14 15:53:47][DEBUG] : ACK Data received 28 Status.unknown_0xA7
[2022-11-14 15:53:47][DEBUG] : Frame received: 801100080da712380000041d00
[2022-11-14 15:53:47][DEBUG] : data received 0x8011 b'a712380000041d' LQI:0
[2022-11-14 15:53:47][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 29]
[2022-11-14 15:53:47][DEBUG] : ACK Data received 29 Status.unknown_0xA7
[2022-11-14 15:53:48][DEBUG] : Frame received: 8702000a4bf000000212381c000000
[2022-11-14 15:53:48][DEBUG] : data received 0x8702 b'f000000212381c0000' LQI:0
[2022-11-14 15:53:48][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 28]
[2022-11-14 15:53:48][DEBUG] : APS Data confirm Fail 28 Status.unknown_0xF0
[2022-11-14 15:53:48][WARNING] : Unexpected message send failure
[2022-11-14 15:53:48][DEBUG] : Frame received: 8702000a4af000000212381d000000
[2022-11-14 15:53:48][DEBUG] : data received 0x8702 b'f000000212381d0000' LQI:0
[2022-11-14 15:53:48][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 29]
[2022-11-14 15:53:48][DEBUG] : APS Data confirm Fail 29 Status.unknown_0xF0
[2022-11-14 15:54:11][DEBUG] : Tries remaining: 2
[2022-11-14 15:54:11][DEBUG] : [0x1238] Extending timeout for 0xc8 request
[2022-11-14 15:54:11][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 200, b'\xc88\x12\x01', True, False)
[2022-11-14 15:54:11][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xc88\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:54:11][DEBUG] : Send: 0x0530 b'021238000000040000000004c8381201'
[2022-11-14 15:54:11][DEBUG] : Frame to send: b'\x050\x00\x10\xee\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xc88\x12\x01'
[2022-11-14 15:54:11][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xee\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xc88\x12\x02\x11'
[2022-11-14 15:54:11][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:54:11][DEBUG] : Frame received: 80000009bc001e0530011e000100
[2022-11-14 15:54:11][DEBUG] : data received 0x8000 b'001e0530011e0001' LQI:0
[2022-11-14 15:54:11][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 30, 1328, b'\x01\x1e\x00\x01']
[2022-11-14 15:54:17][DEBUG] : Frame received: 801100080ea712380000041e00
[2022-11-14 15:54:17][DEBUG] : data received 0x8011 b'a712380000041e' LQI:0
[2022-11-14 15:54:17][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 30]
[2022-11-14 15:54:17][DEBUG] : ACK Data received 30 Status.unknown_0xA7
[2022-11-14 15:54:18][DEBUG] : Frame received: 8702000a49f000000212381e000000
[2022-11-14 15:54:18][DEBUG] : data received 0x8702 b'f000000212381e0000' LQI:0
[2022-11-14 15:54:18][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 30]
[2022-11-14 15:54:18][DEBUG] : APS Data confirm Fail 30 Status.unknown_0xF0
[2022-11-14 15:54:41][DEBUG] : Tries remaining: 1
[2022-11-14 15:54:41][DEBUG] : [0x1238] Extending timeout for 0xc9 request
[2022-11-14 15:54:41][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 201, b'\xc98\x12\x01', True, False)
[2022-11-14 15:54:41][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xc98\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:54:41][DEBUG] : Send: 0x0530 b'021238000000040000000004c9381201'
[2022-11-14 15:54:41][DEBUG] : Frame to send: b'\x050\x00\x10\xef\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xc98\x12\x01'
[2022-11-14 15:54:41][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xef\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xc98\x12\x02\x11'
[2022-11-14 15:54:41][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:54:41][DEBUG] : Frame received: 80000009bc001f0530011f000100
[2022-11-14 15:54:41][DEBUG] : data received 0x8000 b'001f0530011f0001' LQI:0
[2022-11-14 15:54:41][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 31, 1328, b'\x01\x1f\x00\x01']
[2022-11-14 15:54:47][DEBUG] : Frame received: 801100080fa712380000041f00
[2022-11-14 15:54:47][DEBUG] : data received 0x8011 b'a712380000041f' LQI:0
[2022-11-14 15:54:47][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 31]
[2022-11-14 15:54:47][DEBUG] : ACK Data received 31 Status.unknown_0xA7
[2022-11-14 15:54:48][DEBUG] : Frame received: 8702000a48f000000212381f000000
[2022-11-14 15:54:48][DEBUG] : data received 0x8702 b'f000000212381f0000' LQI:0
[2022-11-14 15:54:48][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 31]
[2022-11-14 15:54:48][DEBUG] : APS Data confirm Fail 31 Status.unknown_0xF0
[2022-11-14 15:55:09][DEBUG] : Tries remaining: 2
[2022-11-14 15:55:09][INFO] : [0x1238] Already have endpoints: {0: <zigpy.zdo.ZDO object at 0x7f7664234080>, 1: <Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>}
[2022-11-14 15:55:09][INFO] : [0x1238] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
[2022-11-14 15:55:09][INFO] : [0x1238:1] Discovering endpoint information
[2022-11-14 15:55:09][DEBUG] : Tries remaining: 3
[2022-11-14 15:55:09][DEBUG] : [0x1238] Extending timeout for 0xca request
[2022-11-14 15:55:09][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 202, b'\xca8\x12\x01', True, False)
[2022-11-14 15:55:09][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xca8\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:55:09][DEBUG] : Send: 0x0530 b'021238000000040000000004ca381201'
[2022-11-14 15:55:09][DEBUG] : Frame to send: b'\x050\x00\x10\xec\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xca8\x12\x01'
[2022-11-14 15:55:09][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xec\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xca8\x12\x02\x11'
[2022-11-14 15:55:09][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:55:09][DEBUG] : Frame received: 80000009bc002005300120000100
[2022-11-14 15:55:09][DEBUG] : data received 0x8000 b'0020053001200001' LQI:0
[2022-11-14 15:55:09][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 32, 1328, b'\x01 \x00\x01']
[2022-11-14 15:55:16][DEBUG] : Frame received: 8011000830a712380000042000
[2022-11-14 15:55:16][DEBUG] : data received 0x8011 b'a7123800000420' LQI:0
[2022-11-14 15:55:16][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 32]
[2022-11-14 15:55:16][DEBUG] : ACK Data received 32 Status.unknown_0xA7
[2022-11-14 15:55:17][DEBUG] : Frame received: 8702000a77f0000002123820000000
[2022-11-14 15:55:17][DEBUG] : data received 0x8702 b'f00000021238200000' LQI:0
[2022-11-14 15:55:17][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 32]
[2022-11-14 15:55:17][DEBUG] : APS Data confirm Fail 32 Status.unknown_0xF0
[2022-11-14 15:55:39][DEBUG] : Tries remaining: 2
[2022-11-14 15:55:39][DEBUG] : [0x1238] Extending timeout for 0xcb request
[2022-11-14 15:55:39][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 203, b'\xcb8\x12\x01', True, False)
[2022-11-14 15:55:39][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcb8\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:55:39][DEBUG] : Send: 0x0530 b'021238000000040000000004cb381201'
[2022-11-14 15:55:39][DEBUG] : Frame to send: b'\x050\x00\x10\xed\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcb8\x12\x01'
[2022-11-14 15:55:39][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xed\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xcb8\x12\x02\x11'
[2022-11-14 15:55:39][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:55:39][DEBUG] : Frame received: 80000009bc002105300121000100
[2022-11-14 15:55:39][DEBUG] : data received 0x8000 b'0021053001210001' LQI:0
[2022-11-14 15:55:39][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 33, 1328, b'\x01!\x00\x01']
[2022-11-14 15:55:46][DEBUG] : Frame received: 8011000831a712380000042100
[2022-11-14 15:55:46][DEBUG] : data received 0x8011 b'a7123800000421' LQI:0
[2022-11-14 15:55:46][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 33]
[2022-11-14 15:55:46][DEBUG] : ACK Data received 33 Status.unknown_0xA7
[2022-11-14 15:55:47][DEBUG] : Frame received: 8702000a76f0000002123821000000
[2022-11-14 15:55:47][DEBUG] : data received 0x8702 b'f00000021238210000' LQI:0
[2022-11-14 15:55:47][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 33]
[2022-11-14 15:55:47][DEBUG] : APS Data confirm Fail 33 Status.unknown_0xF0
[2022-11-14 15:56:08][DEBUG] : Frame received: 800200383a00010400000101022c780200001c5f11ff0a01ff42210121d10b03281b0421a84305210d00062402000000000a2100006410000b21000093
[2022-11-14 15:56:08][DEBUG] : data received 0x8002 b'00010400000101022c780200001c5f11ff0a01ff42210121d10b03281b0421a84305210d00062402000000000a2100006410000b210000' LQI:147
[2022-11-14 15:56:08][DEBUG] : zigate_callback_handler ResponseId.DATA_INDICATION [<Status.Success: 0>, 260, 0, 1, 1, <Address address_mode=AddressMode.NWK address=0x2c78>, <Address address_mode=AddressMode.NWK address=0x0000>, b'\x1c_\x11\xff
\x01\xffB!\x01!\xd1\x0b\x03(\x1b\x04!\xa8C\x05!
\x00\x06$\x02\x00\x00\x00\x00
!\x00\x00d\x10\x00\x0b!\x00\x00']
[2022-11-14 15:56:08][DEBUG] : No such device 0x2c78
[2022-11-14 15:56:09][DEBUG] : Tries remaining: 1
[2022-11-14 15:56:09][DEBUG] : [0x1238] Extending timeout for 0xcc request
[2022-11-14 15:56:09][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 204, b'\xcc8\x12\x01', True, False)
[2022-11-14 15:56:09][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcc8\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:56:09][DEBUG] : Send: 0x0530 b'021238000000040000000004cc381201'
[2022-11-14 15:56:09][DEBUG] : Frame to send: b'\x050\x00\x10\xea\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcc8\x12\x01'
[2022-11-14 15:56:09][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xea\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xcc8\x12\x02\x11'
[2022-11-14 15:56:09][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:56:09][DEBUG] : Frame received: 80000009bc002205300122000100
[2022-11-14 15:56:09][DEBUG] : data received 0x8000 b'0022053001220001' LQI:0
[2022-11-14 15:56:09][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 34, 1328, b'\x01"\x00\x01']
[2022-11-14 15:56:16][DEBUG] : Frame received: 8011000832a712380000042200
[2022-11-14 15:56:16][DEBUG] : data received 0x8011 b'a7123800000422' LQI:0
[2022-11-14 15:56:16][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 34]
[2022-11-14 15:56:16][DEBUG] : ACK Data received 34 Status.unknown_0xA7
[2022-11-14 15:56:17][DEBUG] : Frame received: 8702000a75f0000002123822000000
[2022-11-14 15:56:17][DEBUG] : data received 0x8702 b'f00000021238220000' LQI:0
[2022-11-14 15:56:17][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 34]
[2022-11-14 15:56:17][DEBUG] : APS Data confirm Fail 34 Status.unknown_0xF0
[2022-11-14 15:56:38][DEBUG] : Tries remaining: 1
[2022-11-14 15:56:38][INFO] : [0x1238] Already have endpoints: {0: <zigpy.zdo.ZDO object at 0x7f7664234080>, 1: <Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>}
[2022-11-14 15:56:38][INFO] : [0x1238] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
[2022-11-14 15:56:38][INFO] : [0x1238:1] Discovering endpoint information
[2022-11-14 15:56:38][DEBUG] : Tries remaining: 3
[2022-11-14 15:56:38][DEBUG] : [0x1238] Extending timeout for 0xcd request
[2022-11-14 15:56:38][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 205, b'\xcd8\x12\x01', True, False)
[2022-11-14 15:56:38][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcd8\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:56:38][DEBUG] : Send: 0x0530 b'021238000000040000000004cd381201'
[2022-11-14 15:56:38][DEBUG] : Frame to send: b'\x050\x00\x10\xeb\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcd8\x12\x01'
[2022-11-14 15:56:38][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xeb\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xcd8\x12\x02\x11'
[2022-11-14 15:56:38][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:56:38][DEBUG] : Frame received: 80000009bc002305300123000100
[2022-11-14 15:56:38][DEBUG] : data received 0x8000 b'0023053001230001' LQI:0
[2022-11-14 15:56:38][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 35, 1328, b'\x01#\x00\x01']
[2022-11-14 15:56:44][DEBUG] : Frame received: 8011000833a712380000042300
[2022-11-14 15:56:44][DEBUG] : data received 0x8011 b'a7123800000423' LQI:0
[2022-11-14 15:56:44][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 35]
[2022-11-14 15:56:44][DEBUG] : ACK Data received 35 Status.unknown_0xA7
[2022-11-14 15:56:45][DEBUG] : Frame received: 8702000a74f0000002123823000000
[2022-11-14 15:56:45][DEBUG] : data received 0x8702 b'f00000021238230000' LQI:0
[2022-11-14 15:56:45][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 35]
[2022-11-14 15:56:45][DEBUG] : APS Data confirm Fail 35 Status.unknown_0xF0
[2022-11-14 15:57:08][DEBUG] : Tries remaining: 2
[2022-11-14 15:57:08][DEBUG] : [0x1238] Extending timeout for 0xce request
[2022-11-14 15:57:08][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 206, b'\xce8\x12\x01', True, False)
[2022-11-14 15:57:08][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xce8\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:57:08][DEBUG] : Send: 0x0530 b'021238000000040000000004ce381201'
[2022-11-14 15:57:08][DEBUG] : Frame to send: b'\x050\x00\x10\xe8\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xce8\x12\x01'
[2022-11-14 15:57:08][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xe8\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xce8\x12\x02\x11'
[2022-11-14 15:57:08][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:57:08][DEBUG] : Frame received: 80000009bc002405300124000100
[2022-11-14 15:57:08][DEBUG] : data received 0x8000 b'0024053001240001' LQI:0
[2022-11-14 15:57:08][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 36, 1328, b'\x01$\x00\x01']
[2022-11-14 15:57:14][DEBUG] : Frame received: 8011000834a712380000042400
[2022-11-14 15:57:14][DEBUG] : data received 0x8011 b'a7123800000424' LQI:0
[2022-11-14 15:57:14][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 36]
[2022-11-14 15:57:14][DEBUG] : ACK Data received 36 Status.unknown_0xA7
[2022-11-14 15:57:15][DEBUG] : Frame received: 8702000a73f0000002123824000000
[2022-11-14 15:57:15][DEBUG] : data received 0x8702 b'f00000021238240000' LQI:0
[2022-11-14 15:57:15][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 36]
[2022-11-14 15:57:15][DEBUG] : APS Data confirm Fail 36 Status.unknown_0xF0
[2022-11-14 15:57:38][DEBUG] : Tries remaining: 1
[2022-11-14 15:57:38][DEBUG] : [0x1238] Extending timeout for 0xcf request
[2022-11-14 15:57:38][DEBUG] : request (0x1238, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, 0, 207, b'\xcf8\x12\x01', True, False)
[2022-11-14 15:57:38][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcf8\x12\x01'), waiting for status: True, waiting for response: None
[2022-11-14 15:57:38][DEBUG] : Send: 0x0530 b'021238000000040000000004cf381201'
[2022-11-14 15:57:38][DEBUG] : Frame to send: b'\x050\x00\x10\xe9\x02\x128\x00\x00\x00\x04\x00\x00\x00\x00\x04\xcf8\x12\x01'
[2022-11-14 15:57:38][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x10\xe9\x02\x12\x128\x02\x10\x02\x10\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10\x02\x10\x02\x14\xcf8\x12\x02\x11'
[2022-11-14 15:57:38][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 15:57:38][DEBUG] : Frame received: 80000009bc002505300125000100
[2022-11-14 15:57:38][DEBUG] : data received 0x8000 b'0025053001250001' LQI:0
[2022-11-14 15:57:38][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 37, 1328, b'\x01%\x00\x01']
[2022-11-14 15:57:44][DEBUG] : Frame received: 8011000835a712380000042500
[2022-11-14 15:57:44][DEBUG] : data received 0x8011 b'a7123800000425' LQI:0
[2022-11-14 15:57:44][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0x1238, 0, 4, 37]
[2022-11-14 15:57:44][DEBUG] : ACK Data received 37 Status.unknown_0xA7
[2022-11-14 15:57:45][DEBUG] : Frame received: 8702000a72f0000002123825000000
[2022-11-14 15:57:45][DEBUG] : data received 0x8702 b'f00000021238250000' LQI:0
[2022-11-14 15:57:45][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 0, 0, <Address address_mode=AddressMode.NWK address=0x1238>, 37]
[2022-11-14 15:57:45][DEBUG] : APS Data confirm Fail 37 Status.unknown_0xF0
[2022-11-14 15:59:02][DEBUG] : Frame received: 80020042ea000104fcc0010102eb9202000018290af700412d03281805210400082136010a2100000c20141020011220006520006620036720006820006920016a20016b200393
[2022-11-14 15:59:02][DEBUG] : data received 0x8002 b'000104fcc0010102eb9202000018290af700412d03281805210400082136010a2100000c20141020011220006520006620036720006820006920016a20016b2003' LQI:147
[2022-11-14 15:59:02][DEBUG] : zigate_callback_handler ResponseId.DATA_INDICATION [<Status.Success: 0>, 260, 64704, 1, 1, <Address address_mode=AddressMode.NWK address=0xeb92>, <Address address_mode=AddressMode.NWK address=0x0000>, b'\x18)
\xf7\x00A-\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03']
[2022-11-14 15:59:02][DEBUG] : [0xEB92:1:0xfcc0] Received ZCL frame: b'\x18)
\xf7\x00A-\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'
[2022-11-14 15:59:02][DEBUG] : [0xEB92:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=41, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
[2022-11-14 15:59:02][DEBUG] : [0xEB92:1:0xfcc0] Decoded ZCL frame: OppleCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'))])
[2022-11-14 15:59:02][DEBUG] : [0xEB92:1:0xfcc0] Received command 0x0A (TSN 41): Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'))])
[2022-11-14 15:59:02][INFO] : [54:ef:44:10:00:5d:87:ec][listener.general_command] Cluster: <zhaquirks.xiaomi.aqara.motion_ac01.OppleCluster object at 0x7f76641f0860> cluster_id: 0xfcc0 command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=41, command_id=10, *direction=<Direction.Server_to_Client: 0>, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'))]),)
[2022-11-14 15:59:02][DEBUG] : [0xEB92:1:0xfcc0] Attribute report received: 0x00F7=b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'
[2022-11-14 15:59:02][INFO] : [54:ef:44:10:00:5d:87:ec][listener.attribute_updated] Received an attribute update 247=b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03' on cluster 64704
[2022-11-14 15:59:02][DEBUG] : 54:ef:44:10:00:5d:87:ec - Attribute report. attribute_id: [247] value: [{'temperature': 24, 'power_outage_count': 4, '0xff01-8': 310, 'path': 0, '0xff01-12': 20, '0xff01-16': 1, '0xff01-18': 0, 'presence_detected': 0, 'presence_event': 3, 'monitoring_mode': 0, '0xff01-104': 0, 'approach_distance': 1, '0xff01-106': 1, '0xff01-107': 3}]
[2022-11-14 15:59:02][INFO] : [54:ef:44:10:00:5d:87:ec][listener.attribute_updated] Received an attribute update 0=2400 on cluster 2
[2022-11-14 15:59:02][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668437942.27554, 'ieee': 54:ef:44:10:00:5d:87:ec, 'min_last_seen_delta': 30.0})
[2022-11-14 15:59:02][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668437942.27554, 'ieee': 54:ef:44:10:00:5d:87:ec, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 15:59:02][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 15:59:02][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 15:59:02][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 64704, 247, b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'))
[2022-11-14 15:59:02][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 64704, 247, b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03')) completed
[2022-11-14 15:59:02][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 15:59:02][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 15:59:02][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 2, 0, 2400))
[2022-11-14 15:59:02][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 2, 0, 2400)) completed
[2022-11-14 15:59:02][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 15:59:02][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 15:59:02][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:5d:87:ec': {'1': {'64704': {'247': {'value': "b'\x03(\x18\x05!\x04\x00\x08!6\x01
!\x00\x00\x0c \x14\x10 \x01\x12 \x00e \x00f \x03g \x00h \x00i \x01j \x01k \x03'", 'cluster_name': 'Manufacturer Specific'}}, '2': {'0': {'value': '2400', 'cluster_name': 'Device Temperature'}}}}}}
[2022-11-14 15:59:02][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-11-14 15:59:02][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=fYs3tIy5fL8lDK2idHJR0DY5eFOjC2ixyIYQUuKJjvvANyQbeOnLedh5rNm5YPHh HTTP/1.1" 200 0
[2022-11-14 15:59:25][DEBUG] : Frame received: 800200166400010404000101022c7802000018000a000021000096
[2022-11-14 15:59:25][DEBUG] : data received 0x8002 b'00010404000101022c7802000018000a0000210000' LQI:150
[2022-11-14 15:59:25][DEBUG] : zigate_callback_handler ResponseId.DATA_INDICATION [<Status.Success: 0>, 260, 1024, 1, 1, <Address address_mode=AddressMode.NWK address=0x2c78>, <Address address_mode=AddressMode.NWK address=0x0000>, b'\x18\x00
\x00\x00!\x00\x00']
[2022-11-14 15:59:25][DEBUG] : No such device 0x2c78
[2022-11-14 15:59:25][DEBUG] : Frame received: 800200155800010404060101022c7802000018010a0000180196
[2022-11-14 15:59:25][DEBUG] : data received 0x8002 b'00010404060101022c7802000018010a00001801' LQI:150
[2022-11-14 15:59:25][DEBUG] : zigate_callback_handler ResponseId.DATA_INDICATION [<Status.Success: 0>, 260, 1030, 1, 1, <Address address_mode=AddressMode.NWK address=0x2c78>, <Address address_mode=AddressMode.NWK address=0x0000>, b'\x18\x01
\x00\x00\x18\x01']
[2022-11-14 15:59:25][DEBUG] : No such device 0x2c78
[2022-11-14 15:59:31][DEBUG] : Frame received: 80020015870001040406010102d2e002000018420a000018016c
[2022-11-14 15:59:31][DEBUG] : data received 0x8002 b'0001040406010102d2e002000018420a00001801' LQI:108
[2022-11-14 15:59:31][DEBUG] : zigate_callback_handler ResponseId.DATA_INDICATION [<Status.Success: 0>, 260, 1030, 1, 1, <Address address_mode=AddressMode.NWK address=0xd2e0>, <Address address_mode=AddressMode.NWK address=0x0000>, b'\x18B
\x00\x00\x18\x01']
[2022-11-14 15:59:31][DEBUG] : No such device 0xd2e0
[2022-11-14 15:59:39][DEBUG] : Frame received: 800200151e000104fcc0010102eb92020000182a0a4301200093
[2022-11-14 15:59:39][DEBUG] : data received 0x8002 b'000104fcc0010102eb92020000182a0a43012000' LQI:147
[2022-11-14 15:59:39][DEBUG] : zigate_callback_handler ResponseId.DATA_INDICATION [<Status.Success: 0>, 260, 64704, 1, 1, <Address address_mode=AddressMode.NWK address=0xeb92>, <Address address_mode=AddressMode.NWK address=0x0000>, b'\x18*
C\x01 \x00']
[2022-11-14 15:59:39][DEBUG] : [0xEB92:1:0xfcc0] Received ZCL frame: b'\x18*
C\x01 \x00'
[2022-11-14 15:59:39][DEBUG] : [0xEB92:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=42, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
[2022-11-14 15:59:39][DEBUG] : [0xEB92:1:0xfcc0] Decoded ZCL frame: OppleCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0143, value=TypeValue(type=uint8_t, value=0))])
[2022-11-14 15:59:39][DEBUG] : [0xEB92:1:0xfcc0] Received command 0x0A (TSN 42): Report_Attributes(attribute_reports=[Attribute(attrid=0x0143, value=TypeValue(type=uint8_t, value=0))])
[2022-11-14 15:59:39][INFO] : [54:ef:44:10:00:5d:87:ec][listener.general_command] Cluster: <zhaquirks.xiaomi.aqara.motion_ac01.OppleCluster object at 0x7f76641f0860> cluster_id: 0xfcc0 command_id: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=42, command_id=10, *direction=<Direction.Server_to_Client: 0>, *is_reply=False) args: (Report_Attributes(attribute_reports=[Attribute(attrid=0x0143, value=TypeValue(type=uint8_t, value=0))]),)
[2022-11-14 15:59:39][DEBUG] : [0xEB92:1:0xfcc0] Attribute report received: 0x0143=0
[2022-11-14 15:59:39][INFO] : [54:ef:44:10:00:5d:87:ec][listener.attribute_updated] Received an attribute update 323=0 on cluster 64704
[2022-11-14 15:59:39][INFO] : [54:ef:44:10:00:5d:87:ec][listener.zha_send_event] Received an event update Enter={} on cluster 64704
[2022-11-14 15:59:39][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668437979.421249, 'ieee': 54:ef:44:10:00:5d:87:ec, 'min_last_seen_delta': 30.0})
[2022-11-14 15:59:39][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668437979.421249, 'ieee': 54:ef:44:10:00:5d:87:ec, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 15:59:39][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 15:59:39][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 15:59:39][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 64704, 323, 0))
[2022-11-14 15:59:39][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'INSERT INTO attributes_cache_v10 VALUES (?, ?, ?, ?, ?)
                    ON CONFLICT (ieee, endpoint_id, cluster, attrid)
                    DO UPDATE SET
                        value=excluded.value WHERE value != excluded.value', (54:ef:44:10:00:5d:87:ec, 1, 64704, 323, 0)) completed
[2022-11-14 15:59:39][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 15:59:39][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 15:59:39][INFO] : Send to jeedom : {'devices': {'54:ef:44:10:00:5d:87:ec': {'1': {'64704': {'323': {'value': '0', 'cluster_name': 'Manufacturer Specific'}, 'event': {'Enter': {'value': {}, 'cluster_name': 'Manufacturer Specific'}}}}}}}
[2022-11-14 15:59:39][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2022-11-14 15:59:39][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=fYs3tIy5fL8lDK2idHJR0DY5eFOjC2ixyIYQUuKJjvvANyQbeOnLedh5rNm5YPHh HTTP/1.1" 200 0
[2022-11-14 16:00:27][INFO] : [DeviceHandler.prepare] Json arg : None
[2022-11-14 16:00:27][INFO] : [00:15:8d:00:01:ed:e8:0c][zdevices.serialize] Serialize device with attributes : 1
[2022-11-14 16:00:27][DEBUG] : [0x0000:11:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=208, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
[2022-11-14 16:00:27][DEBUG] : [0x0000:11:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[2022-11-14 16:00:27][DEBUG] : request (0x0000, 260, 0, 1, 11, 208, b'\x00\xd0\x00\x04\x00\x05\x00', True, False)
[2022-11-14 16:00:27][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x00\x00\x01\x0b\x00\x00\x01\x04\x00\x00\x07\x00\xd0\x00\x04\x00\x05\x00'), waiting for status: True, waiting for response: None
[2022-11-14 16:00:27][DEBUG] : Send: 0x0530 b'020000010b0000010400000700d00004000500'
[2022-11-14 16:00:27][DEBUG] : Frame to send: b'\x050\x00\x13\xfd\x02\x00\x00\x01\x0b\x00\x00\x01\x04\x00\x00\x07\x00\xd0\x00\x04\x00\x05\x00'
[2022-11-14 16:00:27][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13\xfd\x02\x12\x02\x10\x02\x10\x02\x11\x02\x1b\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10\xd0\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2022-11-14 16:00:27][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 16:00:27][DEBUG] : Frame received: 80000009bc002605300126010000
[2022-11-14 16:00:27][DEBUG] : data received 0x8000 b'0026053001260100' LQI:0
[2022-11-14 16:00:27][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 38, 1328, b'\x01&\x01\x00']
[2022-11-14 16:00:27][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438027.142936, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0})
[2022-11-14 16:00:27][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438027.142936, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 16:00:27][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 16:00:27][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 16:00:29][DEBUG] : [0x0000:10:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=209, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
[2022-11-14 16:00:29][DEBUG] : [0x0000:10:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[2022-11-14 16:00:29][DEBUG] : request (0x0000, 260, 0, 1, 10, 209, b'\x00\xd1\x00\x04\x00\x05\x00', True, False)
[2022-11-14 16:00:29][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x00\x00\x01
\x00\x00\x01\x04\x00\x00\x07\x00\xd1\x00\x04\x00\x05\x00'), waiting for status: True, waiting for response: None
[2022-11-14 16:00:29][DEBUG] : Send: 0x0530 b'020000010a0000010400000700d10004000500'
[2022-11-14 16:00:29][DEBUG] : Frame to send: b'\x050\x00\x13\xfd\x02\x00\x00\x01
\x00\x00\x01\x04\x00\x00\x07\x00\xd1\x00\x04\x00\x05\x00'
[2022-11-14 16:00:29][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13\xfd\x02\x12\x02\x10\x02\x10\x02\x11\x02\x1a\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10\xd1\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2022-11-14 16:00:29][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 16:00:29][DEBUG] : Frame received: 80000009bc002705300127010000
[2022-11-14 16:00:29][DEBUG] : data received 0x8000 b'0027053001270100' LQI:0
[2022-11-14 16:00:29][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 39, 1328, b"\x01'\x01\x00"]
[2022-11-14 16:00:29][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438029.146617, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0})
[2022-11-14 16:00:29][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438029.146617, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 16:00:29][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 16:00:29][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 16:00:31][DEBUG] : [0x0000:110:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=210, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
[2022-11-14 16:00:31][DEBUG] : [0x0000:110:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[2022-11-14 16:00:31][DEBUG] : request (0x0000, 260, 0, 1, 110, 210, b'\x00\xd2\x00\x04\x00\x05\x00', True, False)
[2022-11-14 16:00:31][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x00\x00\x01n\x00\x00\x01\x04\x00\x00\x07\x00\xd2\x00\x04\x00\x05\x00'), waiting for status: True, waiting for response: None
[2022-11-14 16:00:31][DEBUG] : Send: 0x0530 b'020000016e0000010400000700d20004000500'
[2022-11-14 16:00:31][DEBUG] : Frame to send: b'\x050\x00\x13\x9a\x02\x00\x00\x01n\x00\x00\x01\x04\x00\x00\x07\x00\xd2\x00\x04\x00\x05\x00'
[2022-11-14 16:00:31][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13\x9a\x02\x12\x02\x10\x02\x10\x02\x11n\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10\xd2\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2022-11-14 16:00:31][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 16:00:31][DEBUG] : Frame received: 80000009bc002805300128010000
[2022-11-14 16:00:31][DEBUG] : data received 0x8000 b'0028053001280100' LQI:0
[2022-11-14 16:00:31][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 40, 1328, b'\x01(\x01\x00']
[2022-11-14 16:00:31][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438031.149504, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0})
[2022-11-14 16:00:31][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438031.149504, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 16:00:31][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 16:00:31][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 16:00:33][DEBUG] : [0x0000:21:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=211, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
[2022-11-14 16:00:33][DEBUG] : [0x0000:21:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[2022-11-14 16:00:33][DEBUG] : request (0x0000, 260, 0, 1, 21, 211, b'\x00\xd3\x00\x04\x00\x05\x00', True, False)
[2022-11-14 16:00:33][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x00\x00\x01\x15\x00\x00\x01\x04\x00\x00\x07\x00\xd3\x00\x04\x00\x05\x00'), waiting for status: True, waiting for response: None
[2022-11-14 16:00:33][DEBUG] : Send: 0x0530 b'02000001150000010400000700d30004000500'
[2022-11-14 16:00:33][DEBUG] : Frame to send: b'\x050\x00\x13\xe0\x02\x00\x00\x01\x15\x00\x00\x01\x04\x00\x00\x07\x00\xd3\x00\x04\x00\x05\x00'
[2022-11-14 16:00:33][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13\xe0\x02\x12\x02\x10\x02\x10\x02\x11\x15\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10\xd3\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2022-11-14 16:00:33][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 16:00:33][DEBUG] : Frame received: 80000009bc002905300129010000
[2022-11-14 16:00:33][DEBUG] : data received 0x8000 b'0029053001290100' LQI:0
[2022-11-14 16:00:33][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 41, 1328, b'\x01)\x01\x00']
[2022-11-14 16:00:33][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438033.152356, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0})
[2022-11-14 16:00:33][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438033.152356, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 16:00:33][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 16:00:33][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 16:00:34][DEBUG] : Frame received: 8002002b670001040000010102d2e00200001c3412430a02ff4c0600100121c70b21a843240300000000210600205972
[2022-11-14 16:00:34][DEBUG] : data received 0x8002 b'0001040000010102d2e00200001c3412430a02ff4c0600100121c70b21a8432403000000002106002059' LQI:114
[2022-11-14 16:00:34][DEBUG] : zigate_callback_handler ResponseId.DATA_INDICATION [<Status.Success: 0>, 260, 0, 1, 1, <Address address_mode=AddressMode.NWK address=0xd2e0>, <Address address_mode=AddressMode.NWK address=0x0000>, b'\x1c4\x12C
\x02\xffL\x06\x00\x10\x01!\xc7\x0b!\xa8C$\x03\x00\x00\x00\x00!\x06\x00 Y']
[2022-11-14 16:00:34][DEBUG] : No such device 0xd2e0
[2022-11-14 16:00:35][DEBUG] : [0x0000:3:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=212, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
[2022-11-14 16:00:35][DEBUG] : [0x0000:3:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[2022-11-14 16:00:35][DEBUG] : request (0x0000, 260, 0, 1, 3, 212, b'\x00\xd4\x00\x04\x00\x05\x00', True, False)
[2022-11-14 16:00:35][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\x00\x00\x01\x03\x00\x00\x01\x04\x00\x00\x07\x00\xd4\x00\x04\x00\x05\x00'), waiting for status: True, waiting for response: None
[2022-11-14 16:00:35][DEBUG] : Send: 0x0530 b'02000001030000010400000700d40004000500'
[2022-11-14 16:00:35][DEBUG] : Frame to send: b'\x050\x00\x13\xf1\x02\x00\x00\x01\x03\x00\x00\x01\x04\x00\x00\x07\x00\xd4\x00\x04\x00\x05\x00'
[2022-11-14 16:00:35][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13\xf1\x02\x12\x02\x10\x02\x10\x02\x11\x02\x13\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10\xd4\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2022-11-14 16:00:35][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 16:00:35][DEBUG] : Frame received: 80000009bd002b0530012b010100
[2022-11-14 16:00:35][DEBUG] : data received 0x8000 b'002b0530012b0101' LQI:0
[2022-11-14 16:00:35][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 43, 1328, b'\x01+\x01\x01']
[2022-11-14 16:00:35][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438035.153825, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0})
[2022-11-14 16:00:35][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438035.153825, 'ieee': 00:15:8d:00:01:ed:e8:0c, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 16:00:35][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 16:00:35][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 16:00:37][INFO] : [00:15:8d:00:03:09:9a:eb][zdevices.serialize] Serialize device with attributes : 1
[2022-11-14 16:00:37][DEBUG] : [0xAF16:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=213, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
[2022-11-14 16:00:37][DEBUG] : [0xAF16:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[2022-11-14 16:00:37][DEBUG] : [0xaf16] Extending timeout for 0xd5 request
[2022-11-14 16:00:37][DEBUG] : request (0xAF16, 260, 0, 1, 1, 213, b'\x00\xd5\x00\x04\x00\x05\x00', True, False)
[2022-11-14 16:00:37][DEBUG] : Sending CommandId.SEND_RAW_APS_DATA_PACKET (b'\x02\xaf\x16\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00\xd5\x00\x04\x00\x05\x00'), waiting for status: True, waiting for response: None
[2022-11-14 16:00:37][DEBUG] : Send: 0x0530 b'02af1601010000010400000700d50004000500'
[2022-11-14 16:00:37][DEBUG] : Frame to send: b'\x050\x00\x13K\x02\xaf\x16\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00\xd5\x00\x04\x00\x05\x00'
[2022-11-14 16:00:37][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13K\x02\x12\xaf\x16\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10\xd5\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2022-11-14 16:00:37][DEBUG] : Wait for status to command CommandId.SEND_RAW_APS_DATA_PACKET
[2022-11-14 16:00:37][DEBUG] : Frame received: 80000009bf002c0530012c000200
[2022-11-14 16:00:37][DEBUG] : data received 0x8000 b'002c0530012c0002' LQI:0
[2022-11-14 16:00:37][DEBUG] : zigate_callback_handler ResponseId.STATUS [<Status.Success: 0>, 44, 1328, b'\x01,\x00\x02']
[2022-11-14 16:00:37][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438037.157134, 'ieee': 00:15:8d:00:03:09:9a:eb, 'min_last_seen_delta': 30.0})
[2022-11-14 16:00:37][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f766673dd50>, 'UPDATE devices_v10
                    SET last_seen=:ts
                    WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1668438037.157134, 'ieee': 00:15:8d:00:03:09:9a:eb, 'min_last_seen_delta': 30.0}) completed
[2022-11-14 16:00:37][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>)
[2022-11-14 16:00:37][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f766673dd50>) completed
[2022-11-14 16:00:39][INFO] : [54:ef:44:10:00:5d:87:ec][zdevices.serialize] Serialize device with attributes : 1
[2022-11-14 16:00:39][INFO] : [00:15:8d:00:07:90:0e:0f][zdevices.serialize] Serialize device with attributes : 1
[2022-11-14 16:00:39][INFO] : 200 GET /device/all (127.0.0.1) 12017.81ms
[2022-11-14 16:00:41][DEBUG] : Frame received: 8011000827a7d2e00100002a00
[2022-11-14 16:00:41][DEBUG] : data received 0x8011 b'a7d2e00100002a' LQI:0
[2022-11-14 16:00:41][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0xd2e0, 1, 0, 42]
[2022-11-14 16:00:41][DEBUG] : ACK Data received 42 Status.unknown_0xA7
[2022-11-14 16:00:42][DEBUG] : Frame received: 8702000a64f0010102d2e02a000100
[2022-11-14 16:00:42][DEBUG] : data received 0x8702 b'f0010102d2e02a0001' LQI:0
[2022-11-14 16:00:42][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 1, 1, <Address address_mode=AddressMode.NWK address=0xd2e0>, 42]
[2022-11-14 16:00:42][DEBUG] : APS Data confirm Fail 42 Status.unknown_0xF0
[2022-11-14 16:00:43][DEBUG] : Frame received: 80110008aaa7af160100002c00
[2022-11-14 16:00:43][DEBUG] : data received 0x8011 b'a7af160100002c' LQI:0
[2022-11-14 16:00:43][DEBUG] : zigate_callback_handler ResponseId.ACK_DATA [<Status.unknown_0xA7: 167>, 0xaf16, 1, 0, 44]
[2022-11-14 16:00:43][DEBUG] : ACK Data received 44 Status.unknown_0xA7
[2022-11-14 16:00:44][DEBUG] : Frame received: 8702000ae8f0010102af162c000000
[2022-11-14 16:00:44][DEBUG] : data received 0x8702 b'f0010102af162c0000' LQI:0
[2022-11-14 16:00:44][DEBUG] : zigate_callback_handler ResponseId.APS_DATA_CONFIRM_FAILED [<Status.unknown_0xF0: 240>, 1, 1, <Address address_mode=AddressMode.NWK address=0xaf16>, 44]
[2022-11-14 16:00:44][DEBUG] : APS Data confirm Fail 44 Status.unknown_0xF0
[2022-11-15 14:47:07][INFO] : Start zigbeed
[2022-11-15 14:47:07][INFO] : Log level : info
[2022-11-15 14:47:07][INFO] : PID file : /tmp/jeedom/zigbee/deamon_1.pid
[2022-11-15 14:47:07][INFO] : Device : /dev/ttyUSB0
[2022-11-15 14:47:07][INFO] : Apikey : fYs3tIy5fL8lDK2idHJR0DY5eFOjC2ixyIYQUuKJjvvANyQbeOnLedh5rNm5YPHh
[2022-11-15 14:47:07][INFO] : Callback : http://127.0.0.1:80/plugins/zigbee/core/php/jeeZigbee.php
[2022-11-15 14:47:07][INFO] : Cycle : 0.3
[2022-11-15 14:47:07][INFO] : Controller : zigate
[2022-11-15 14:47:07][INFO] : Channel : 15
[2022-11-15 14:47:07][INFO] : Data folder : /var/www/html/plugins/zigbee/data/1
[2022-11-15 14:47:07][INFO] : Device folder : /var/www/html/plugins/zigbee/data/device
[2022-11-15 14:47:07][INFO] : Folder OTA : /var/www/html/plugins/zigbee/data/ota
[2022-11-15 14:47:07][INFO] : Zigpy advance configuration file : None
[2022-11-15 14:47:07][INFO] : Find device : /dev/ttyUSB0
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.danfoss
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.danfoss.details
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.develco
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.develco.details
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.frient
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.frient.details
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.legrand
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.legrand.details
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.schneider
[2022-11-15 14:47:07][INFO] : LOADER------Import de la configuration specifics.schneider.details
[2022-11-15 14:47:07][INFO] : Writing PID 13994 to /tmp/jeedom/zigbee/deamon_1.pid
[2022-11-15 14:47:07][INFO] : Init request module v2.28.1
[2022-11-15 14:47:07][INFO] : [start_zigbee] Init zigbee network with config : {'database_path': '/var/www/html/plugins/zigbee/data/1/network_zigate.db', 'device': {'path': '/dev/ttyUSB0'}, 'network': {'channel': 15}, 'ota': {'otau_directory': '/var/www/html/plugins/zigbee/data/ota', 'ikea_provider': True, 'ikea_update_url': 'http://fw.ota.homesmart.ikea.net/feed/version_info.json', 'ledvance_provider': True}}
[2022-11-15 14:47:07][INFO] : Trådfri: OTA provider enabled
[2022-11-15 14:47:07][INFO] : Ledvance: OTA provider enabled
[2022-11-15 14:47:08][INFO] : [0x0000] Requesting 'Node Descriptor'
[2022-11-15 14:47:08][INFO] : [0x0000] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.Coordinator: 0>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice|AlternatePanCoordinator: 143>, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=0, server_mask=11329, maximum_outgoing_transfer_size=0, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=True, *is_coordinator=True, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=False, *is_security_capable=False)
[2022-11-15 14:47:08][INFO] : [0x0000] Discovering endpoints
[2022-11-15 14:47:08][INFO] : [0x0000] Discovered endpoints: [1, 11, 10, 110, 21, 8, 3]
[2022-11-15 14:47:08][INFO] : [0x0000] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=11 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=10 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=110 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=21 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=8 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=3 in=[] out=[] status=<Status.NEW: 0>>]
[2022-11-15 14:47:08][INFO] : [0x0000:1] Discovering endpoint information
[2022-11-15 14:47:08][INFO] : [0x0000:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=2112, device_version=0, input_clusters=[0, 3, 25, 15, 1281], output_clusters=[2819, 0, 768, 4, 3, 8, 6, 5, 1794, 1280, 25, 513, 1025, 1024, 1030, 1027, 1029, 1026, 516, 1, 2821, 4096])
[2022-11-15 14:47:08][INFO] : [0x0000:11] Discovering endpoint information
[2022-11-15 14:47:08][INFO] : [0x0000:11] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=11, profile=260, device_type=2112, device_version=0, input_clusters=[0, 25], output_clusters=[0])
[2022-11-15 14:47:08][INFO] : [0x0000:10] Discovering endpoint information
[2022-11-15 14:47:08][INFO] : [0x0000:10] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=10, profile=260, device_type=2112, device_version=0, input_clusters=[0, 3, 516], output_clusters=[2819, 0, 768, 4, 3, 8, 6, 5, 257, 1794, 1280, 25, 513, 1025, 1024, 1030, 1027, 1029, 1026, 516, 1, 2821, 4096])
[2022-11-15 14:47:08][INFO] : [0x0000:110] Discovering endpoint information
[2022-11-15 14:47:08][INFO] : [0x0000:110] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=110, profile=260, device_type=2112, device_version=0, input_clusters=[0, 3, 516], output_clusters=[2819, 0, 768, 4, 3, 8, 6, 5, 257, 1794, 1280, 25, 513, 1025, 1024, 1030, 1027, 1029, 1026, 516, 1, 2821, 4096])
[2022-11-15 14:47:08][INFO] : [0x0000:21] Discovering endpoint information
[2022-11-15 14:47:08][INFO] : [0x0000:21] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=21, profile=260, device_type=0, device_version=0, input_clusters=[0, 3, 516], output_clusters=[2819, 0, 768, 4, 3, 8, 6, 5, 257, 1794, 1280, 25, 513, 1025, 1024, 1030, 1027, 1029, 1026, 516, 1, 2821, 4096])
[2022-11-15 14:47:08][INFO] : [0x0000:8] Discovering endpoint information
[2022-11-15 14:47:09][INFO] : [0x0000:8] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=8, profile=260, device_type=0, device_version=0, input_clusters=[], output_clusters=[1])
[2022-11-15 14:47:09][INFO] : [0x0000:3] Discovering endpoint information
[2022-11-15 14:47:09][INFO] : [0x0000:3] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=3, profile=260, device_type=0, device_version=0, input_clusters=[0, 25], output_clusters=[0])
[2022-11-15 14:47:09][INFO] : [0x0000] Already have model and manufacturer info
[2022-11-15 14:47:09][INFO] : [0x0000] Discovered basic device information for <ZiGateDevice model='ZiGate USB-TTL 3.21' manuf='ZiGate' nwk=0x0000 ieee=00:15:8d:00:01:ed:e8:0c is_initialized=True>
[2022-11-15 14:47:09][INFO] : [00:15:8d:00:01:ed:e8:0c][listener.device_initialized] new=False
[2022-11-15 14:47:09][INFO] : [00:15:8d:00:03:09:9a:eb][listener.device_initialized] new=False
[2022-11-15 14:47:09][INFO] : [54:ef:44:10:00:5d:87:ec][listener.device_initialized] new=False
[2022-11-15 14:47:09][INFO] : [start_zigbee] Init and start http server : {'database_path': '/var/www/html/plugins/zigbee/data/1/network_zigate.db', 'device': {'path': '/dev/ttyUSB0'}, 'network': {'channel': 15}, 'ota': {'otau_directory': '/var/www/html/plugins/zigbee/data/ota', 'ikea_provider': True, 'ikea_update_url': 'http://fw.ota.homesmart.ikea.net/feed/version_info.json', 'ledvance_provider': True}}
[2022-11-15 14:47:09][INFO] : [start_zigbee] Start zigbee network