[2021-04-18 08:10:07][DEBUG] : Read parameter nwk_update_id response: [3]
[2021-04-18 08:10:07][DEBUG] : device: 0x8dfb - _TZ3000_wamqdr3f TS011F, FFD=True, Rx_on_when_idle=True
[2021-04-18 08:10:07][DEBUG] : device: 0x8ebf - _TYZB01_qezuin6k TS110F, FFD=True, Rx_on_when_idle=True
[2021-04-18 08:10:07][INFO] : [00:21:2e:ff:ff:06:bc:5a][listener.device_initialized] new=False
[2021-04-18 08:10:07][INFO] : [bc:33:ac:ff:fe:a8:60:7c][listener.device_initialized] new=False
[2021-04-18 08:10:07][INFO] : [5c:02:72:ff:fe:2c:44:d4][listener.device_initialized] new=False
[2021-04-18 08:10:07][INFO] : [04:cf:8c:df:3c:78:c0:b6][listener.device_initialized] new=False
[2021-04-18 08:10:07][INFO] : [00:12:4b:00:22:34:46:64][listener.device_initialized] new=False
[2021-04-18 08:10:07][INFO] : [5c:02:72:ff:fe:83:c4:c0][listener.device_initialized] new=False
[2021-04-18 08:10:08][INFO] : [00:12:4b:00:22:31:2b:02][listener.device_initialized] new=False
[2021-04-18 08:10:08][INFO] : [00:12:4b:00:22:ec:59:00][listener.device_initialized] new=False
[2021-04-18 08:10:08][INFO] : [84:71:27:ff:fe:19:cf:89][listener.device_initialized] new=False
[2021-04-18 08:10:08][DEBUG] : [start_zigbee] Init and start http server : {'database_path': '/var/www/html/plugins/zigbee/data/1/network_deconz.db', 'device': {'path': '/dev/ttyACM0'}, 'network': {'channel': 11}}
[2021-04-18 08:10:08][DEBUG] : [start_zigbee] Start zigbee network
[2021-04-18 08:10:24][DEBUG] : Frame received: 0x0e16000700aa00
[2021-04-18 08:10:24][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-04-18 08:10:24][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:10:24][DEBUG] : Send: 0x1716000800010001
[2021-04-18 08:10:24][DEBUG] : Frame received: 0x17160025001e002202000001029b130104010100070018bc0a2000201f00afff7c622200df
[2021-04-18 08:10:24][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=0x139b>, 1, 260, 1, b'\x18\xbc
 \x00 \x1f', 0, 175, 255, 124, 98, 34, 0, -33]
[2021-04-18 08:10:24][DEBUG] : [0x139b:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=188 command_id=Command.Report_Attributes>
[2021-04-18 08:10:24][DEBUG] : [0x139b:1:0x0001] ZCL request 0x000a: [[Attribute(attrid=32, value=<TypeValue type=uint8_t, value=31>)]]
[2021-04-18 08:10:24][INFO] : [04:cf:8c:df:3c:78:c0:b6][listener.general_command] Cluster: <zhaquirks.PowerConfigurationCluster object at 0x7fac4c2940> ClusterId: 0x0001 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=188 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=32, value=<TypeValue type=uint8_t, value=31>)]],)
[2021-04-18 08:10:24][DEBUG] : [0x139b:1:0x0001] Attribute report received: battery_voltage=31
[2021-04-18 08:10:24][INFO] : [04:cf:8c:df:3c:78:c0:b6][listener.attribute_updated] Received an attribute update 32=31 on cluster 1
[2021-04-18 08:10:24][DEBUG] : [0x139b:1:0x0001] Voltage [RAW]:31 [Max]:2.8 [Min]:1.5, Battery Percent: 100.0
[2021-04-18 08:10:24][INFO] : [04:cf:8c:df:3c:78:c0:b6][listener.attribute_updated] Received an attribute update 33=200 on cluster 1
[2021-04-18 08:10:24][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x139b>, ep: 1, profile: 0x0104, cluster_id: 0x0001, data: b'18bc0a2000201f'
[2021-04-18 08:10:24][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (04:cf:8c:df:3c:78:c0:b6, 1, 1, 32, 31))
[2021-04-18 08:10:24][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (04:cf:8c:df:3c:78:c0:b6, 1, 1, 32, 31)) completed
[2021-04-18 08:10:24][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>)
[2021-04-18 08:10:24][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>) completed
[2021-04-18 08:10:24][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (04:cf:8c:df:3c:78:c0:b6, 1, 1, 33, 200))
[2021-04-18 08:10:24][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (04:cf:8c:df:3c:78:c0:b6, 1, 1, 33, 200)) completed
[2021-04-18 08:10:24][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>)
[2021-04-18 08:10:24][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>) completed
[2021-04-18 08:10:24][DEBUG] : Send to jeedom : {'devices': {'04:cf:8c:df:3c:78:c0:b6': {'1': {'1': {'32': {'value': '31', 'cluster_name': 'Power Configuration'}, '33': {'value': '200', 'cluster_name': 'Power Configuration'}}}}}}
[2021-04-18 08:10:24][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-04-18 08:10:24][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=05GuuscvB7miqS1AugbTCRdpBaulydwK HTTP/1.1" 200 0
[2021-04-18 08:12:25][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': 'bc:33:ac:ff:fe:a8:60:7c', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'on', 'await': 1}], 'allowQueue': False}
[2021-04-18 08:12:25][DEBUG] : Sending Zigbee request with tsn 1 under 2 request id, data: b'010101'
[2021-04-18 08:12:25][DEBUG] : Command Command.aps_data_request (18, 2, 0, <DeconzAddressEndpoint address_mode=2 address=0x99A4 endpoint=1>, 260, 6, 1, b'\x01\x01\x01', 2, 0)
[2021-04-18 08:12:25][DEBUG] : Send: 0x12170019001200020002a49901040106000103000101010200
[2021-04-18 08:12:25][DEBUG] : Frame received: 0x121700090002002202
[2021-04-18 08:12:25][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 2]
[2021-04-18 08:12:25][DEBUG] : Frame received: 0x0e18000700a600
[2021-04-18 08:12:25][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:12:25][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:12:25][DEBUG] : Send: 0x04180007000000
[2021-04-18 08:12:25][DEBUG] : Frame received: 0x04180013000c00220202a4990101e100000000
[2021-04-18 08:12:25][DEBUG] : APS data confirm response for request with id 2: e1
[2021-04-18 08:12:25][DEBUG] : Request id: 0x02 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x99a4 endpoint=1>, status: 0xe1
[2021-04-18 08:12:25][DEBUG] : Error while sending 2 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:12:25][DEBUG] : [0x99a4] Delivery error for seq # 0x01, on endpoint id 1 cluster 0x0006: message send failure
[2021-04-18 08:12:25][DEBUG] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 240, in put
    await zdevices.command(self.json_args)
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zdevices.py", line 69, in command
    await command()
  File "/usr/local/lib/python3.7/dist-packages/zigpy/device.py", line 218, in request
    self.nwk, dst_ep, cluster
zigpy.exceptions.DeliveryError: [0x99a4:1:0x0006]: Message send failure

[2021-04-18 08:12:25][INFO] : 200 PUT /device/command (127.0.0.1) 240.05ms
[2021-04-18 08:12:28][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': 'bc:33:ac:ff:fe:a8:60:7c', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'off', 'await': 1}], 'allowQueue': False}
[2021-04-18 08:12:28][DEBUG] : Sending Zigbee request with tsn 3 under 4 request id, data: b'010300'
[2021-04-18 08:12:28][DEBUG] : Command Command.aps_data_request (18, 4, 0, <DeconzAddressEndpoint address_mode=2 address=0x99A4 endpoint=1>, 260, 6, 1, b'\x01\x03\x00', 2, 0)
[2021-04-18 08:12:28][DEBUG] : Send: 0x12190019001200040002a49901040106000103000103000200
[2021-04-18 08:12:28][DEBUG] : Frame received: 0x121900090002002204
[2021-04-18 08:12:28][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 4]
[2021-04-18 08:12:29][DEBUG] : Frame received: 0x0e1a000700a600
[2021-04-18 08:12:29][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:12:29][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:12:29][DEBUG] : Send: 0x041a0007000000
[2021-04-18 08:12:29][DEBUG] : Frame received: 0x041a0013000c00220402a4990101e100000000
[2021-04-18 08:12:29][DEBUG] : APS data confirm response for request with id 4: e1
[2021-04-18 08:12:29][DEBUG] : Request id: 0x04 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x99a4 endpoint=1>, status: 0xe1
[2021-04-18 08:12:29][DEBUG] : Error while sending 4 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:12:29][DEBUG] : [0x99a4] Delivery error for seq # 0x03, on endpoint id 1 cluster 0x0006: message send failure
[2021-04-18 08:12:29][DEBUG] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 240, in put
    await zdevices.command(self.json_args)
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zdevices.py", line 69, in command
    await command()
  File "/usr/local/lib/python3.7/dist-packages/zigpy/device.py", line 218, in request
    self.nwk, dst_ep, cluster
zigpy.exceptions.DeliveryError: [0x99a4:1:0x0006]: Message send failure

[2021-04-18 08:12:29][INFO] : 200 PUT /device/command (127.0.0.1) 148.18ms
[2021-04-18 08:12:34][DEBUG] : [ApplicationHandler.prepare] Json arg : None
[2021-04-18 08:12:34][INFO] : 200 GET /application/info (127.0.0.1) 6.68ms
[2021-04-18 08:12:34][DEBUG] : [NetworkHandler.prepare] Json arg : None
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 2 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:34][INFO] : 200 GET /network/map (127.0.0.1) 11.46ms
[2021-04-18 08:12:34][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2021-04-18 08:12:34][DEBUG] : [0x99a4:1] Manufacturer: _TZ3000_dbou1ap4
[2021-04-18 08:12:34][DEBUG] : [0x99a4:1] Model: TS0505A
[2021-04-18 08:12:34][DEBUG] : [0x8dfb:11] Manufacturer: _TZ3000_wamqdr3f
[2021-04-18 08:12:34][DEBUG] : [0x8dfb:11] Model: TS011F
[2021-04-18 08:12:34][DEBUG] : [0x139b:1] Manufacturer: LUMI
[2021-04-18 08:12:34][DEBUG] : [0x139b:1] Model: lumi.sen_ill.mgl01
[2021-04-18 08:12:34][DEBUG] : [0x00d3:1] Manufacturer: eWeLight
[2021-04-18 08:12:34][DEBUG] : [0x00d3:1] Model: ZB-CL01
[2021-04-18 08:12:34][DEBUG] : [0x1e6f:1] Manufacturer: _TYZB01_bngwdjsr
[2021-04-18 08:12:34][DEBUG] : [0x1e6f:1] Model: TS1001
[2021-04-18 08:12:34][DEBUG] : [0x82b7:1] Manufacturer: eWeLight
[2021-04-18 08:12:34][DEBUG] : [0x82b7:1] Model: ZB-CL01
[2021-04-18 08:12:34][DEBUG] : [0xcd83:1] Manufacturer: eWeLink
[2021-04-18 08:12:34][DEBUG] : [0xcd83:1] Model: MS01
[2021-04-18 08:12:34][DEBUG] : [0x8ebf:1] Manufacturer: _TYZB01_qezuin6k
[2021-04-18 08:12:34][DEBUG] : [0x8ebf:1] Model: TS110F
[2021-04-18 08:12:34][INFO] : 200 GET /device/all?with_attributes=0 (127.0.0.1) 32.26ms
[2021-04-18 08:12:37][DEBUG] : [NetworkHandler.prepare] Json arg : None
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 2 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-04-18 08:12:37][INFO] : 200 GET /network/map (127.0.0.1) 12.86ms
[2021-04-18 08:12:52][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:21:2e:ff:ff:06:bc:5a'}
[2021-04-18 08:12:52][WARNING] : [00:21:2e:ff:ff:06:bc:5a][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:12:52][WARNING] : [00:21:2e:ff:ff:06:bc:5a][zdevices.get_basic_info] End point found
[2021-04-18 08:12:52][DEBUG] : Sending Zigbee request with tsn 5 under 6 request id, data: b'00050004000500'
[2021-04-18 08:12:52][DEBUG] : Command Command.aps_data_request (22, 6, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=1>, 260, 0, 1, b'\x00\x05\x00\x04\x00\x05\x00', 2, 0)
[2021-04-18 08:12:52][DEBUG] : Send: 0x121b001d00160006000200000104010000010700000500040005000200
[2021-04-18 08:12:52][DEBUG] : Frame received: 0x121b00090002002206
[2021-04-18 08:12:52][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 6]
[2021-04-18 08:12:52][DEBUG] : Frame received: 0x0e1c000700ae00
[2021-04-18 08:12:52][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2021-04-18 08:12:52][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:12:52][DEBUG] : Send: 0x171c000800010001
[2021-04-18 08:12:52][DEBUG] : Frame received: 0x171c0025001e002602000001020000010401000007000005000400050000af23896222000c
[2021-04-18 08:12:52][DEBUG] : APS data indication response: [30, <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=0x0000>, 1, 260, 0, b'\x00\x05\x00\x04\x00\x05\x00', 0, 175, 35, 137, 98, 34, 0, 12]
[2021-04-18 08:12:52][DEBUG] : [0x0000:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=5 command_id=Command.Read_Attributes>
[2021-04-18 08:12:52][DEBUG] : [0x0000:1:0x0000] ZCL request 0x0000: [[4, 5]]
[2021-04-18 08:12:52][INFO] : [00:21:2e:ff:ff:06:bc:5a][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x7fac4b2710> ClusterId: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=5 command_id=Command.Read_Attributes> args: ([[4, 5]],)
[2021-04-18 08:12:52][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'00050004000500'
[2021-04-18 08:12:52][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:12:52][DEBUG] : Send: 0x041d0007000000
[2021-04-18 08:12:52][DEBUG] : Frame received: 0x041d0013000c00220602000001010000000000
[2021-04-18 08:12:52][DEBUG] : APS data confirm response for request with id 6: 00
[2021-04-18 08:12:52][DEBUG] : Request id: 0x06 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=1>, status: 0x00
[2021-04-18 08:12:53][DEBUG] : Frame received: 0x0e1e000700aa00
[2021-04-18 08:12:53][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-04-18 08:12:53][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:12:53][DEBUG] : Send: 0x171e000800010001
[2021-04-18 08:12:53][DEBUG] : Frame received: 0x171e0026001f002202000001028cee0104010504080018130a000021540e00afd789622200bd
[2021-04-18 08:12:53][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=0xee8c>, 1, 260, 1029, b'\x18\x13
\x00\x00!T\x0e', 0, 175, 215, 137, 98, 34, 0, -67]
[2021-04-18 08:12:53][DEBUG] : Received frame from unknown device: 0xee8c
[2021-04-18 08:12:53][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xee8c>, ep: 1, profile: 0x0104, cluster_id: 0x0405, data: b'18130a000021540e'
[2021-04-18 08:12:57][WARNING] : [00:21:2e:ff:ff:06:bc:5a][zdevices.get_basic_info] Error on read attribute level 1 : 
[2021-04-18 08:12:57][DEBUG] : Sending Zigbee request with tsn 7 under 8 request id, data: b'0007000000010002000300'
[2021-04-18 08:12:57][DEBUG] : Command Command.aps_data_request (26, 8, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=1>, 260, 0, 1, b'\x00\x07\x00\x00\x00\x01\x00\x02\x00\x03\x00', 2, 0)
[2021-04-18 08:12:57][DEBUG] : Send: 0x121f0021001a0008000200000104010000010b0000070000000100020003000200
[2021-04-18 08:12:57][DEBUG] : Frame received: 0x121f00090002002208
[2021-04-18 08:12:57][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 8]
[2021-04-18 08:12:57][DEBUG] : Frame received: 0x0e20000700ae00
[2021-04-18 08:12:57][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2021-04-18 08:12:57][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:12:57][DEBUG] : Send: 0x1720000800010001
[2021-04-18 08:12:57][DEBUG] : Frame received: 0x17200029002200260200000102000001040100000b00000700000001000200030000af408a6222000f
[2021-04-18 08:12:57][DEBUG] : APS data indication response: [34, <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=0x0000>, 1, 260, 0, b'\x00\x07\x00\x00\x00\x01\x00\x02\x00\x03\x00', 0, 175, 64, 138, 98, 34, 0, 15]
[2021-04-18 08:12:57][DEBUG] : [0x0000:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=7 command_id=Command.Read_Attributes>
[2021-04-18 08:12:57][DEBUG] : [0x0000:1:0x0000] ZCL request 0x0000: [[0, 1, 2, 3]]
[2021-04-18 08:12:57][INFO] : [00:21:2e:ff:ff:06:bc:5a][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x7fac4b2710> ClusterId: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=7 command_id=Command.Read_Attributes> args: ([[0, 1, 2, 3]],)
[2021-04-18 08:12:57][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'0007000000010002000300'
[2021-04-18 08:12:57][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:12:57][DEBUG] : Send: 0x04210007000000
[2021-04-18 08:12:57][DEBUG] : Frame received: 0x04210013000c00220802000001010000000000
[2021-04-18 08:12:57][DEBUG] : APS data confirm response for request with id 8: 00
[2021-04-18 08:12:57][DEBUG] : Request id: 0x08 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=1>, status: 0x00
[2021-04-18 08:13:02][WARNING] : [00:21:2e:ff:ff:06:bc:5a][zdevices.get_basic_info] Error on read attribute level 2 : 
[2021-04-18 08:13:02][DEBUG] : Sending Zigbee request with tsn 9 under 10 request id, data: b'0009000700'
[2021-04-18 08:13:02][DEBUG] : Command Command.aps_data_request (20, 10, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=1>, 260, 0, 1, b'\x00	\x00\x07\x00', 2, 0)
[2021-04-18 08:13:02][DEBUG] : Send: 0x1222001b0014000a00020000010401000001050000090007000200
[2021-04-18 08:13:02][DEBUG] : Frame received: 0x12220009000200220a
[2021-04-18 08:13:02][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 10]
[2021-04-18 08:13:02][DEBUG] : Frame received: 0x0e23000700ae00
[2021-04-18 08:13:02][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2021-04-18 08:13:02][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:13:02][DEBUG] : Send: 0x1723000800010001
[2021-04-18 08:13:02][DEBUG] : Frame received: 0x17230023001c00260200000102000001040100000500000900070000af238a6222000c
[2021-04-18 08:13:02][DEBUG] : APS data indication response: [28, <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=0x0000>, 1, 260, 0, b'\x00	\x00\x07\x00', 0, 175, 35, 138, 98, 34, 0, 12]
[2021-04-18 08:13:02][DEBUG] : [0x0000:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=9 command_id=Command.Read_Attributes>
[2021-04-18 08:13:02][DEBUG] : [0x0000:1:0x0000] ZCL request 0x0000: [[7]]
[2021-04-18 08:13:02][INFO] : [00:21:2e:ff:ff:06:bc:5a][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x7fac4b2710> ClusterId: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=9 command_id=Command.Read_Attributes> args: ([[7]],)
[2021-04-18 08:13:02][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'0009000700'
[2021-04-18 08:13:02][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:02][DEBUG] : Send: 0x04240007000000
[2021-04-18 08:13:02][DEBUG] : Frame received: 0x04240013000c00220a02000001010000000000
[2021-04-18 08:13:02][DEBUG] : APS data confirm response for request with id 10: 00
[2021-04-18 08:13:02][DEBUG] : Request id: 0x0a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=1>, status: 0x00
[2021-04-18 08:13:07][WARNING] : [00:21:2e:ff:ff:06:bc:5a][zdevices.get_basic_info] Error on read attribute level 3 : 
[2021-04-18 08:13:07][DEBUG] : Sending Zigbee request with tsn 11 under 12 request id, data: b'000b0006000040'
[2021-04-18 08:13:07][DEBUG] : Command Command.aps_data_request (22, 12, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=1>, 260, 0, 1, b'\x00\x0b\x00\x06\x00\x00@', 2, 0)
[2021-04-18 08:13:07][DEBUG] : Send: 0x1225001d0016000c000200000104010000010700000b00060000400200
[2021-04-18 08:13:07][DEBUG] : Frame received: 0x12250009000200220c
[2021-04-18 08:13:07][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 12]
[2021-04-18 08:13:07][DEBUG] : Frame received: 0x0e26000700ae00
[2021-04-18 08:13:07][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2021-04-18 08:13:07][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:13:07][DEBUG] : Send: 0x1726000800010001
[2021-04-18 08:13:07][DEBUG] : Frame received: 0x17260025001e00260200000102000001040100000700000b000600004000af238a6222000c
[2021-04-18 08:13:07][DEBUG] : APS data indication response: [30, <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=0x0000>, 1, 260, 0, b'\x00\x0b\x00\x06\x00\x00@', 0, 175, 35, 138, 98, 34, 0, 12]
[2021-04-18 08:13:07][DEBUG] : [0x0000:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=11 command_id=Command.Read_Attributes>
[2021-04-18 08:13:07][DEBUG] : [0x0000:1:0x0000] ZCL request 0x0000: [[6, 16384]]
[2021-04-18 08:13:07][INFO] : [00:21:2e:ff:ff:06:bc:5a][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x7fac4b2710> ClusterId: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=11 command_id=Command.Read_Attributes> args: ([[6, 16384]],)
[2021-04-18 08:13:07][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'000b0006000040'
[2021-04-18 08:13:07][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:07][DEBUG] : Send: 0x04270007000000
[2021-04-18 08:13:07][DEBUG] : Frame received: 0x04270013000c00220c02000001010000000000
[2021-04-18 08:13:07][DEBUG] : APS data confirm response for request with id 12: 00
[2021-04-18 08:13:07][DEBUG] : Request id: 0x0c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=1>, status: 0x00
[2021-04-18 08:13:12][WARNING] : [00:21:2e:ff:ff:06:bc:5a][zdevices.get_basic_info] Error on read attribute level 4 : 
[2021-04-18 08:13:12][WARNING] : [00:21:2e:ff:ff:06:bc:5a][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:12][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 20121.32ms
[2021-04-18 08:13:14][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '84:71:27:ff:fe:19:cf:89'}
[2021-04-18 08:13:14][WARNING] : [84:71:27:ff:fe:19:cf:89][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:13:14][WARNING] : [84:71:27:ff:fe:19:cf:89][zdevices.get_basic_info] End point found
[2021-04-18 08:13:17][DEBUG] : Sending Zigbee request with tsn 13 under 14 request id, data: b'000d000040'
[2021-04-18 08:13:17][DEBUG] : Command Command.aps_data_request (20, 14, 0, <DeconzAddressEndpoint address_mode=2 address=0x8EBF endpoint=1>, 260, 0, 1, b'\x00
\x00\x00@', 2, 0)
[2021-04-18 08:13:17][DEBUG] : Send: 0x1228001b0014000e0002bf8e0104010000010500000d0000400200
[2021-04-18 08:13:17][DEBUG] : Frame received: 0x12280009000200220e
[2021-04-18 08:13:17][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 14]
[2021-04-18 08:13:17][DEBUG] : Frame received: 0x0e29000700a600
[2021-04-18 08:13:17][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:17][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:17][DEBUG] : Send: 0x04290007000000
[2021-04-18 08:13:17][DEBUG] : Frame received: 0x04290013000c00220e02bf8e0101e100000000
[2021-04-18 08:13:17][DEBUG] : APS data confirm response for request with id 14: e1
[2021-04-18 08:13:17][DEBUG] : Request id: 0x0e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8ebf endpoint=1>, status: 0xe1
[2021-04-18 08:13:17][DEBUG] : Error while sending 14 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:17][DEBUG] : [0x8ebf] Delivery error for seq # 0x0d, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:17][WARNING] : [84:71:27:ff:fe:19:cf:89][zdevices.get_basic_info] Error on read attribute level 4 : [0x8ebf:1:0x0000]: Message send failure
[2021-04-18 08:13:17][WARNING] : [84:71:27:ff:fe:19:cf:89][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:17][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 3111.77ms
[2021-04-18 08:13:18][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:12:4b:00:22:ec:59:00'}
[2021-04-18 08:13:18][WARNING] : [00:12:4b:00:22:ec:59:00][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:13:18][WARNING] : [00:12:4b:00:22:ec:59:00][zdevices.get_basic_info] End point found
[2021-04-18 08:13:19][DEBUG] : [0xcd83] Extending timeout for 0x0f request
[2021-04-18 08:13:19][DEBUG] : Sending Zigbee request with tsn 15 under 16 request id, data: b'000f000200'
[2021-04-18 08:13:19][DEBUG] : Command Command.aps_data_request (20, 16, 0, <DeconzAddressEndpoint address_mode=2 address=0xCD83 endpoint=1>, 260, 0, 1, b'\x00\x0f\x00\x02\x00', 2, 0)
[2021-04-18 08:13:19][DEBUG] : Send: 0x122a001b00140010000283cd0104010000010500000f0002000200
[2021-04-18 08:13:19][DEBUG] : Frame received: 0x122a00090002002210
[2021-04-18 08:13:19][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 16]
[2021-04-18 08:13:20][DEBUG] : Frame received: 0x0e2b000700a600
[2021-04-18 08:13:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:20][DEBUG] : Send: 0x042b0007000000
[2021-04-18 08:13:20][DEBUG] : Frame received: 0x042b0013000c0022100283cd0101e100000000
[2021-04-18 08:13:20][DEBUG] : APS data confirm response for request with id 16: e1
[2021-04-18 08:13:20][DEBUG] : Request id: 0x10 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xcd83 endpoint=1>, status: 0xe1
[2021-04-18 08:13:20][DEBUG] : Error while sending 16 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:20][DEBUG] : [0xcd83] Delivery error for seq # 0x0f, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:20][WARNING] : [00:12:4b:00:22:ec:59:00][zdevices.get_basic_info] Error on read attribute level 2 : [0xcd83:1:0x0000]: Message send failure
[2021-04-18 08:13:21][DEBUG] : [0xcd83] Extending timeout for 0x11 request
[2021-04-18 08:13:21][DEBUG] : Sending Zigbee request with tsn 17 under 18 request id, data: b'0011000040'
[2021-04-18 08:13:21][DEBUG] : Command Command.aps_data_request (20, 18, 0, <DeconzAddressEndpoint address_mode=2 address=0xCD83 endpoint=1>, 260, 0, 1, b'\x00\x11\x00\x00@', 2, 0)
[2021-04-18 08:13:21][DEBUG] : Send: 0x122c001b00140012000283cd010401000001050000110000400200
[2021-04-18 08:13:21][DEBUG] : Frame received: 0x122c00090002002212
[2021-04-18 08:13:21][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 18]
[2021-04-18 08:13:22][DEBUG] : Frame received: 0x0e2d000700a600
[2021-04-18 08:13:22][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:22][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:22][DEBUG] : Send: 0x042d0007000000
[2021-04-18 08:13:22][DEBUG] : Frame received: 0x042d0013000c0022120283cd0101e100000000
[2021-04-18 08:13:22][DEBUG] : APS data confirm response for request with id 18: e1
[2021-04-18 08:13:22][DEBUG] : Request id: 0x12 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xcd83 endpoint=1>, status: 0xe1
[2021-04-18 08:13:22][DEBUG] : Error while sending 18 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:22][DEBUG] : [0xcd83] Delivery error for seq # 0x11, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:22][WARNING] : [00:12:4b:00:22:ec:59:00][zdevices.get_basic_info] Error on read attribute level 4 : [0xcd83:1:0x0000]: Message send failure
[2021-04-18 08:13:22][WARNING] : [00:12:4b:00:22:ec:59:00][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:22][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 4148.17ms
[2021-04-18 08:13:23][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:12:4b:00:22:31:2b:02'}
[2021-04-18 08:13:23][WARNING] : [00:12:4b:00:22:31:2b:02][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:13:23][WARNING] : [00:12:4b:00:22:31:2b:02][zdevices.get_basic_info] End point found
[2021-04-18 08:13:26][WARNING] : [00:12:4b:00:22:31:2b:02][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:26][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 3010.39ms
[2021-04-18 08:13:27][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '5c:02:72:ff:fe:83:c4:c0'}
[2021-04-18 08:13:27][WARNING] : [5c:02:72:ff:fe:83:c4:c0][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:13:27][WARNING] : [5c:02:72:ff:fe:83:c4:c0][zdevices.get_basic_info] End point found
[2021-04-18 08:13:28][DEBUG] : [0x1e6f] Extending timeout for 0x13 request
[2021-04-18 08:13:28][DEBUG] : Sending Zigbee request with tsn 19 under 20 request id, data: b'001300000002000300'
[2021-04-18 08:13:28][DEBUG] : Command Command.aps_data_request (24, 20, 0, <DeconzAddressEndpoint address_mode=2 address=0x1E6F endpoint=1>, 260, 0, 1, b'\x00\x13\x00\x00\x00\x02\x00\x03\x00', 2, 0)
[2021-04-18 08:13:28][DEBUG] : Send: 0x122e001f0018001400026f1e01040100000109000013000000020003000200
[2021-04-18 08:13:28][DEBUG] : Frame received: 0x122e00090002002214
[2021-04-18 08:13:28][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 20]
[2021-04-18 08:13:29][DEBUG] : Frame received: 0x0e2f000700a600
[2021-04-18 08:13:29][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:29][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:29][DEBUG] : Send: 0x042f0007000000
[2021-04-18 08:13:29][DEBUG] : Frame received: 0x042f0013000c002214026f1e0101e100000000
[2021-04-18 08:13:29][DEBUG] : APS data confirm response for request with id 20: e1
[2021-04-18 08:13:29][DEBUG] : Request id: 0x14 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1e6f endpoint=1>, status: 0xe1
[2021-04-18 08:13:29][DEBUG] : Error while sending 20 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:29][DEBUG] : [0x1e6f] Delivery error for seq # 0x13, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:29][WARNING] : [5c:02:72:ff:fe:83:c4:c0][zdevices.get_basic_info] Error on read attribute level 2 : [0x1e6f:1:0x0000]: Message send failure
[2021-04-18 08:13:29][DEBUG] : [0x1e6f] Extending timeout for 0x15 request
[2021-04-18 08:13:29][DEBUG] : Sending Zigbee request with tsn 21 under 22 request id, data: b'0015000700'
[2021-04-18 08:13:29][DEBUG] : Command Command.aps_data_request (20, 22, 0, <DeconzAddressEndpoint address_mode=2 address=0x1E6F endpoint=1>, 260, 0, 1, b'\x00\x15\x00\x07\x00', 2, 0)
[2021-04-18 08:13:29][DEBUG] : Send: 0x1230001b0014001600026f1e010401000001050000150007000200
[2021-04-18 08:13:29][DEBUG] : Frame received: 0x123000090002002216
[2021-04-18 08:13:29][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 22]
[2021-04-18 08:13:30][DEBUG] : Frame received: 0x0e31000700a600
[2021-04-18 08:13:30][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:30][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:30][DEBUG] : Send: 0x04310007000000
[2021-04-18 08:13:30][DEBUG] : Frame received: 0x04310013000c002216026f1e0101e100000000
[2021-04-18 08:13:30][DEBUG] : APS data confirm response for request with id 22: e1
[2021-04-18 08:13:30][DEBUG] : Request id: 0x16 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1e6f endpoint=1>, status: 0xe1
[2021-04-18 08:13:30][DEBUG] : Error while sending 22 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:30][DEBUG] : [0x1e6f] Delivery error for seq # 0x15, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:30][WARNING] : [5c:02:72:ff:fe:83:c4:c0][zdevices.get_basic_info] Error on read attribute level 3 : [0x1e6f:1:0x0000]: Message send failure
[2021-04-18 08:13:30][DEBUG] : [0x1e6f] Extending timeout for 0x17 request
[2021-04-18 08:13:30][DEBUG] : Sending Zigbee request with tsn 23 under 24 request id, data: b'00170006000040'
[2021-04-18 08:13:30][DEBUG] : Command Command.aps_data_request (22, 24, 0, <DeconzAddressEndpoint address_mode=2 address=0x1E6F endpoint=1>, 260, 0, 1, b'\x00\x17\x00\x06\x00\x00@', 2, 0)
[2021-04-18 08:13:30][DEBUG] : Send: 0x1232001d0016001800026f1e0104010000010700001700060000400200
[2021-04-18 08:13:30][DEBUG] : Frame received: 0x123200090002002218
[2021-04-18 08:13:30][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 24]
[2021-04-18 08:13:31][DEBUG] : Frame received: 0x0e33000700a600
[2021-04-18 08:13:31][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:31][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:31][DEBUG] : Send: 0x04330007000000
[2021-04-18 08:13:31][DEBUG] : Frame received: 0x04330013000c002218026f1e0101e100000000
[2021-04-18 08:13:31][DEBUG] : APS data confirm response for request with id 24: e1
[2021-04-18 08:13:31][DEBUG] : Request id: 0x18 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1e6f endpoint=1>, status: 0xe1
[2021-04-18 08:13:31][DEBUG] : Error while sending 24 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:31][DEBUG] : [0x1e6f] Delivery error for seq # 0x17, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:31][WARNING] : [5c:02:72:ff:fe:83:c4:c0][zdevices.get_basic_info] Error on read attribute level 4 : [0x1e6f:1:0x0000]: Message send failure
[2021-04-18 08:13:31][WARNING] : [5c:02:72:ff:fe:83:c4:c0][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:31][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 4234.50ms
[2021-04-18 08:13:32][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:12:4b:00:22:34:46:64'}
[2021-04-18 08:13:32][WARNING] : [00:12:4b:00:22:34:46:64][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:13:32][WARNING] : [00:12:4b:00:22:34:46:64][zdevices.get_basic_info] End point found
[2021-04-18 08:13:34][DEBUG] : Frame received: 0x0e34000700aa00
[2021-04-18 08:13:34][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-04-18 08:13:34][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:13:34][DEBUG] : Send: 0x1734000800010001
[2021-04-18 08:13:34][DEBUG] : Frame received: 0x17340026001f002202000001028cee0104010204080018140a000029260800afd7a2622200bd
[2021-04-18 08:13:34][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=0xee8c>, 1, 260, 1026, b'\x18\x14
\x00\x00)&\x08', 0, 175, 215, 162, 98, 34, 0, -67]
[2021-04-18 08:13:34][DEBUG] : Received frame from unknown device: 0xee8c
[2021-04-18 08:13:34][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xee8c>, ep: 1, profile: 0x0104, cluster_id: 0x0402, data: b'18140a0000292608'
[2021-04-18 08:13:35][WARNING] : [00:12:4b:00:22:34:46:64][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:35][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 3006.41ms
[2021-04-18 08:13:36][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '5c:02:72:ff:fe:2c:44:d4'}
[2021-04-18 08:13:36][WARNING] : [5c:02:72:ff:fe:2c:44:d4][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:13:36][WARNING] : [5c:02:72:ff:fe:2c:44:d4][zdevices.get_basic_info] End point found
[2021-04-18 08:13:39][DEBUG] : Sending Zigbee request with tsn 25 under 26 request id, data: b'0019000040'
[2021-04-18 08:13:39][DEBUG] : Command Command.aps_data_request (20, 26, 0, <DeconzAddressEndpoint address_mode=2 address=0x8DFB endpoint=11>, 260, 0, 1, b'\x00\x19\x00\x00@', 2, 0)
[2021-04-18 08:13:39][DEBUG] : Send: 0x1235001b0014001a0002fb8d0b0401000001050000190000400200
[2021-04-18 08:13:39][DEBUG] : Frame received: 0x12350009000200221a
[2021-04-18 08:13:39][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 26]
[2021-04-18 08:13:39][DEBUG] : Frame received: 0x0e36000700a600
[2021-04-18 08:13:39][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:39][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:39][DEBUG] : Send: 0x04360007000000
[2021-04-18 08:13:39][DEBUG] : Frame received: 0x04360013000c00221a02fb8d0b01e100000000
[2021-04-18 08:13:39][DEBUG] : APS data confirm response for request with id 26: e1
[2021-04-18 08:13:39][DEBUG] : Request id: 0x1a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8dfb endpoint=11>, status: 0xe1
[2021-04-18 08:13:39][DEBUG] : Error while sending 26 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:39][DEBUG] : [0x8dfb] Delivery error for seq # 0x19, on endpoint id 11 cluster 0x0000: message send failure
[2021-04-18 08:13:39][WARNING] : [5c:02:72:ff:fe:2c:44:d4][zdevices.get_basic_info] Error on read attribute level 4 : [0x8dfb:11:0x0000]: Message send failure
[2021-04-18 08:13:39][WARNING] : [5c:02:72:ff:fe:2c:44:d4][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:39][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 3135.94ms
[2021-04-18 08:13:39][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': 'bc:33:ac:ff:fe:a8:60:7c'}
[2021-04-18 08:13:39][WARNING] : [bc:33:ac:ff:fe:a8:60:7c][zdevices.get_basic_info] Begin get basic info from device
[2021-04-18 08:13:39][WARNING] : [bc:33:ac:ff:fe:a8:60:7c][zdevices.get_basic_info] End point found
[2021-04-18 08:13:41][DEBUG] : Sending Zigbee request with tsn 27 under 28 request id, data: b'001b000700'
[2021-04-18 08:13:41][DEBUG] : Command Command.aps_data_request (20, 28, 0, <DeconzAddressEndpoint address_mode=2 address=0x99A4 endpoint=1>, 260, 0, 1, b'\x00\x1b\x00\x07\x00', 2, 0)
[2021-04-18 08:13:41][DEBUG] : Send: 0x1237001b0014001c0002a4990104010000010500001b0007000200
[2021-04-18 08:13:41][DEBUG] : Frame received: 0x12370009000200221c
[2021-04-18 08:13:41][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 28]
[2021-04-18 08:13:41][DEBUG] : Frame received: 0x0e38000700a600
[2021-04-18 08:13:41][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:41][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:41][DEBUG] : Send: 0x04380007000000
[2021-04-18 08:13:41][DEBUG] : Frame received: 0x04380013000c00221c02a4990101e100000000
[2021-04-18 08:13:41][DEBUG] : APS data confirm response for request with id 28: e1
[2021-04-18 08:13:41][DEBUG] : Request id: 0x1c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x99a4 endpoint=1>, status: 0xe1
[2021-04-18 08:13:41][DEBUG] : Error while sending 28 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:41][DEBUG] : [0x99a4] Delivery error for seq # 0x1b, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:41][WARNING] : [bc:33:ac:ff:fe:a8:60:7c][zdevices.get_basic_info] Error on read attribute level 3 : [0x99a4:1:0x0000]: Message send failure
[2021-04-18 08:13:41][DEBUG] : Sending Zigbee request with tsn 29 under 30 request id, data: b'001d000040'
[2021-04-18 08:13:41][DEBUG] : Command Command.aps_data_request (20, 30, 0, <DeconzAddressEndpoint address_mode=2 address=0x99A4 endpoint=1>, 260, 0, 1, b'\x00\x1d\x00\x00@', 2, 0)
[2021-04-18 08:13:41][DEBUG] : Send: 0x1239001b0014001e0002a4990104010000010500001d0000400200
[2021-04-18 08:13:41][DEBUG] : Frame received: 0x12390009000200221e
[2021-04-18 08:13:41][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 30]
[2021-04-18 08:13:41][DEBUG] : Frame received: 0x0e3a000700a600
[2021-04-18 08:13:41][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-04-18 08:13:41][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:41][DEBUG] : Send: 0x043a0007000000
[2021-04-18 08:13:41][DEBUG] : Frame received: 0x043a0013000c00221e02a4990101e100000000
[2021-04-18 08:13:41][DEBUG] : APS data confirm response for request with id 30: e1
[2021-04-18 08:13:41][DEBUG] : Request id: 0x1e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x99a4 endpoint=1>, status: 0xe1
[2021-04-18 08:13:41][DEBUG] : Error while sending 30 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2021-04-18 08:13:41][DEBUG] : [0x99a4] Delivery error for seq # 0x1d, on endpoint id 1 cluster 0x0000: message send failure
[2021-04-18 08:13:41][WARNING] : [bc:33:ac:ff:fe:a8:60:7c][zdevices.get_basic_info] Error on read attribute level 4 : [0x99a4:1:0x0000]: Message send failure
[2021-04-18 08:13:41][WARNING] : [bc:33:ac:ff:fe:a8:60:7c][zdevices.get_basic_info] End get basic info from device
[2021-04-18 08:13:41][INFO] : 200 PUT /device/get_basic_info (127.0.0.1) 2280.53ms
[2021-04-18 08:13:56][DEBUG] : [ApplicationHandler.prepare] Json arg : []
[2021-04-18 08:13:56][DEBUG] : Tries remaining: 3
[2021-04-18 08:13:56][DEBUG] : Sending Zigbee request with tsn 31 under 32 request id, data: b'1f00'
[2021-04-18 08:13:56][DEBUG] : Command Command.aps_data_request (17, 32, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x1f\x00', 2, 0)
[2021-04-18 08:13:56][DEBUG] : Send: 0x123b0018001100200002000000000031000002001f000200
[2021-04-18 08:13:56][DEBUG] : Frame received: 0x123b00090002002220
[2021-04-18 08:13:56][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 32]
[2021-04-18 08:13:56][DEBUG] : Frame received: 0x0e3c000700ae00
[2021-04-18 08:13:56][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2021-04-18 08:13:56][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:13:56][DEBUG] : Send: 0x173c000800010001
[2021-04-18 08:13:56][DEBUG] : Frame received: 0x173c004f0048002602000000020000000000318031001f000400025abc06ffff2e210089cf19feff277184bf8e250101d35abc06ffff2e21007c60a8feffac33bca499250101ff00af11aa62220015
[2021-04-18 08:13:56][DEBUG] : APS data indication response: [72, <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"\x1f\x00\x04\x00\x02Z\xbc\x06\xff\xff.!\x00\x89\xcf\x19\xfe\xff'q\x84\xbf\x8e%\x01\x01\xd3Z\xbc\x06\xff\xff.!\x00|`\xa8\xfe\xff\xac3\xbc\xa4\x99%\x01\x01\xff", 0, 175, 17, 170, 98, 34, 0, 21]
[2021-04-18 08:13:56][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'1f000400025abc06ffff2e210089cf19feff277184bf8e250101d35abc06ffff2e21007c60a8feffac33bca499250101ff'
[2021-04-18 08:13:56][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:56][DEBUG] : Send: 0x043d0007000000
[2021-04-18 08:13:56][DEBUG] : Frame received: 0x043d0013000c00222002000000000000000000
[2021-04-18 08:13:56][DEBUG] : APS data confirm response for request with id 32: 00
[2021-04-18 08:13:56][DEBUG] : Request id: 0x20 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2021-04-18 08:13:56][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=4, start_index=0, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:06:bc:5a, ieee=84:71:27:ff:fe:19:cf:89, nwk=0x8EBF, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=211, *device_type=<DeviceType.Router: 1>, *relationship=<Relationship.Sibling: 2>, *rx_on_when_idle=<RxOnWhenIdle.On: 1>), Neighbor(extended_pan_id=00:21:2e:ff:ff:06:bc:5a, ieee=bc:33:ac:ff:fe:a8:60:7c, nwk=0x99A4, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=255, *device_type=<DeviceType.Router: 1>, *relationship=<Relationship.Sibling: 2>, *rx_on_when_idle=<RxOnWhenIdle.On: 1>)])
[2021-04-18 08:13:58][DEBUG] : [0x0000] Querying next starting at 2
[2021-04-18 08:13:58][DEBUG] : Tries remaining: 3
[2021-04-18 08:13:58][DEBUG] : Sending Zigbee request with tsn 33 under 34 request id, data: b'2102'
[2021-04-18 08:13:58][DEBUG] : Command Command.aps_data_request (17, 34, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'!\x02', 2, 0)
[2021-04-18 08:13:58][DEBUG] : Send: 0x123e00180011002200020000000000310000020021020200
[2021-04-18 08:13:58][DEBUG] : Frame received: 0x123e00090002002222
[2021-04-18 08:13:58][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 34]
[2021-04-18 08:13:58][DEBUG] : Frame received: 0x0e3f000700ae00
[2021-04-18 08:13:58][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2021-04-18 08:13:58][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:13:58][DEBUG] : Send: 0x173f000800010001
[2021-04-18 08:13:58][DEBUG] : Frame received: 0x173f004f00480026020000000200000000003180310021000402025abc06ffff2e210070eb52feff23a4603fb5250101ff5abc06ffff2e2100d4442cfeff72025cfb8d2501015400af11aa62220015
[2021-04-18 08:13:58][DEBUG] : APS data indication response: [72, <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\x04\x02\x02Z\xbc\x06\xff\xff.!\x00p\xebR\xfe\xff#\xa4`?\xb5%\x01\x01\xffZ\xbc\x06\xff\xff.!\x00\xd4D,\xfe\xffr\x02\xfb\x8d%\x01\x01T', 0, 175, 17, 170, 98, 34, 0, 21]
[2021-04-18 08:13:58][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'21000402025abc06ffff2e210070eb52feff23a4603fb5250101ff5abc06ffff2e2100d4442cfeff72025cfb8d25010154'
[2021-04-18 08:13:58][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-04-18 08:13:58][DEBUG] : Send: 0x04400007000000
[2021-04-18 08:13:58][DEBUG] : Frame received: 0x04400013000c00222202000000000000000000
[2021-04-18 08:13:58][DEBUG] : APS data confirm response for request with id 34: 00
[2021-04-18 08:13:58][DEBUG] : Request id: 0x22 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2021-04-18 08:13:58][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=4, start_index=2, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:06:bc:5a, ieee=60:a4:23:ff:fe:52:eb:70, nwk=0xB53F, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=255, *device_type=<DeviceType.Router: 1>, *relationship=<Relationship.Sibling: 2>, *rx_on_when_idle=<RxOnWhenIdle.On: 1>), Neighbor(extended_pan_id=00:21:2e:ff:ff:06:bc:5a, ieee=5c:02:72:ff:fe:2c:44:d4, nwk=0x8DFB, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=84, *device_type=<DeviceType.Router: 1>, *relationship=<Relationship.Sibling: 2>, *rx_on_when_idle=<RxOnWhenIdle.On: 1>)])
[2021-04-18 08:13:58][DEBUG] : [0x0000] Done scanning. Total 4 neighbours
[2021-04-18 08:13:58][INFO] : 200 PUT /application/neighbors_scan (127.0.0.1) 1271.80ms
[2021-04-18 08:13:58][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'DELETE FROM neighbors WHERE device_ieee = ?', (00:21:2e:ff:ff:06:bc:5a,))
[2021-04-18 08:13:58][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'DELETE FROM neighbors WHERE device_ieee = ?', (00:21:2e:ff:ff:06:bc:5a,)) completed
[2021-04-18 08:13:58][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7facd67e30>, 'INSERT INTO neighbors VALUES (?, ?, ?, ?, ?, ?, ?, ?)', [(00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, 84:71:27:ff:fe:19:cf:89, 0x8EBF, 37, <PermitJoins.Accepting: 1>, 1, 211), (00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, bc:33:ac:ff:fe:a8:60:7c, 0x99A4, 37, <PermitJoins.Accepting: 1>, 1, 255), (00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, 60:a4:23:ff:fe:52:eb:70, 0xB53F, 37, <PermitJoins.Accepting: 1>, 1, 255), (00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, 5c:02:72:ff:fe:2c:44:d4, 0x8DFB, 37, <PermitJoins.Accepting: 1>, 1, 84)])
[2021-04-18 08:13:58][DEBUG] : operation functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7facd67e30>, 'INSERT INTO neighbors VALUES (?, ?, ?, ?, ?, ?, ?, ?)', [(00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, 84:71:27:ff:fe:19:cf:89, 0x8EBF, 37, <PermitJoins.Accepting: 1>, 1, 211), (00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, bc:33:ac:ff:fe:a8:60:7c, 0x99A4, 37, <PermitJoins.Accepting: 1>, 1, 255), (00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, 60:a4:23:ff:fe:52:eb:70, 0xB53F, 37, <PermitJoins.Accepting: 1>, 1, 255), (00:21:2e:ff:ff:06:bc:5a, 00:21:2e:ff:ff:06:bc:5a, 5c:02:72:ff:fe:2c:44:d4, 0x8DFB, 37, <PermitJoins.Accepting: 1>, 1, 84)]) completed
[2021-04-18 08:13:58][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>)
[2021-04-18 08:13:58][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>) completed
[2021-04-18 08:15:22][DEBUG] : Frame received: 0x0e41000700aa00
[2021-04-18 08:15:22][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-04-18 08:15:22][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:15:22][DEBUG] : Send: 0x1741000800010001
[2021-04-18 08:15:22][DEBUG] : Frame received: 0x17410026001f002202000001029b1301040100040800180d0a000021523b00afffaf622200df
[2021-04-18 08:15:22][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=0x139b>, 1, 260, 1024, b'\x18
\x00\x00!R;', 0, 175, 255, 175, 98, 34, 0, -33]
[2021-04-18 08:15:22][DEBUG] : [0x139b:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=13 command_id=Command.Report_Attributes>
[2021-04-18 08:15:22][DEBUG] : [0x139b:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=15186>)]]
[2021-04-18 08:15:22][INFO] : [04:cf:8c:df:3c:78:c0:b6][listener.general_command] Cluster: <zigpy.zcl.clusters.measurement.IlluminanceMeasurement object at 0x7fac4c2198> ClusterId: 0x0400 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=13 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=15186>)]],)
[2021-04-18 08:15:22][DEBUG] : [0x139b:1:0x0400] Attribute report received: measured_value=15186
[2021-04-18 08:15:22][INFO] : [04:cf:8c:df:3c:78:c0:b6][listener.attribute_updated] Received an attribute update 0=15186 on cluster 1024
[2021-04-18 08:15:22][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x139b>, ep: 1, profile: 0x0104, cluster_id: 0x0400, data: b'180d0a000021523b'
[2021-04-18 08:15:22][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (04:cf:8c:df:3c:78:c0:b6, 1, 1024, 0, 15186))
[2021-04-18 08:15:22][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7facd67e30>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (04:cf:8c:df:3c:78:c0:b6, 1, 1024, 0, 15186)) completed
[2021-04-18 08:15:22][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>)
[2021-04-18 08:15:22][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7facd67e30>) completed
[2021-04-18 08:15:22][DEBUG] : Send to jeedom : {'devices': {'04:cf:8c:df:3c:78:c0:b6': {'1': {'1024': {'0': {'value': '15186', 'cluster_name': 'Illuminance Measurement'}}}}}}
[2021-04-18 08:15:22][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-04-18 08:15:22][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=05GuuscvB7miqS1AugbTCRdpBaulydwK HTTP/1.1" 200 0
[2021-04-18 08:15:44][DEBUG] : Frame received: 0x0e42000700aa00
[2021-04-18 08:15:44][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-04-18 08:15:44][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:15:44][DEBUG] : Send: 0x1742000800010001
[2021-04-18 08:15:44][DEBUG] : Frame received: 0x17420026001f002202000001028cee0104010504080018150a000021cc0e00afd7b1622200bd
[2021-04-18 08:15:44][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=0xee8c>, 1, 260, 1029, b'\x18\x15
\x00\x00!\xcc\x0e', 0, 175, 215, 177, 98, 34, 0, -67]
[2021-04-18 08:15:44][DEBUG] : Received frame from unknown device: 0xee8c
[2021-04-18 08:15:44][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xee8c>, ep: 1, profile: 0x0104, cluster_id: 0x0405, data: b'18150a000021cc0e'
[2021-04-18 08:16:04][DEBUG] : Frame received: 0x0e43000700aa00
[2021-04-18 08:16:04][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-04-18 08:16:04][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-04-18 08:16:04][DEBUG] : Send: 0x1743000800010001
[2021-04-18 08:16:04][DEBUG] : Frame received: 0x17430026001f002202000001028cee0104010504080018160a000021490e00afd7b2622200bd
[2021-04-18 08:16:04][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=0xee8c>, 1, 260, 1029, b'\x18\x16
\x00\x00!I\x0e', 0, 175, 215, 178, 98, 34, 0, -67]
[2021-04-18 08:16:04][DEBUG] : Received frame from unknown device: 0xee8c
[2021-04-18 08:16:04][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xee8c>, ep: 1, profile: 0x0104, cluster_id: 0x0405, data: b'18160a000021490e'