[2020-10-29 12:43:16][DEBUG] : [ApplicationHandler.prepare] Json arg : {'duration': 180}
[2020-10-29 12:43:16][DEBUG] : Sending Zigbee broadcast with tsn 16 under 17 request id, data: b'10b400'
[2020-10-29 12:43:16][DEBUG] : Command Command.aps_data_request (17, 17, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'\x10\xb4\x00', 2, 0)
[2020-10-29 12:43:16][DEBUG] : Send: 0x12490018001100110001fcff0000360000030010b4000200
[2020-10-29 12:43:16][DEBUG] : Frame received: 0x124900090002002211
[2020-10-29 12:43:16][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 17]
[2020-10-29 12:43:16][DEBUG] : Frame received: 0x0e4a000700aa00
[2020-10-29 12:43:16][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:16][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:16][DEBUG] : Send: 0x174a000800010001
[2020-10-29 12:43:16][DEBUG] : Frame received: 0x174a0021001a002201fcff010200000000003600030010b40000afe89bcd010000
[2020-10-29 12:43:16][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0xfffc>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 54, b'\x10\xb4\x00', 0, 175, 232, 155, 205, 1, 0, 0]
[2020-10-29 12:43:16][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [180, <Bool.false: 0>]
[2020-10-29 12:43:16][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'10b400'
[2020-10-29 12:43:16][DEBUG] : Frame received: 0x0e4b000700a600
[2020-10-29 12:43:16][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:16][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:16][DEBUG] : Send: 0x044b0007000000
[2020-10-29 12:43:16][DEBUG] : Frame received: 0x044b0012000b00221101fcff000000000000
[2020-10-29 12:43:16][DEBUG] : APS data confirm response for request with id 17: 00
[2020-10-29 12:43:16][DEBUG] : Request id: 0x11 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00
[2020-10-29 12:43:16][DEBUG] : Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xb4')
[2020-10-29 12:43:16][DEBUG] : Send: 0x0b4c000900020021b4
[2020-10-29 12:43:16][DEBUG] : Frame received: 0x0b4c000800010021
[2020-10-29 12:43:16][DEBUG] : Write parameter permit_join: SUCCESS
[2020-10-29 12:43:16][INFO] : 200 PUT /application/include (127.0.0.1) 84.38ms
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x1c4d0012000b0003a914960301881700ffcc
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e4e000700aa00
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:19][DEBUG] : Send: 0x174d000800010001
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x174d002a0023002202fdff0002974800000013000c00009748a9149603018817008e00afff9bcd0100d3
[2020-10-29 12:43:19][DEBUG] : APS data indication response: [35, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 19, b'\x00\x97H\xa9\x14\x96\x03\x01\x88\x17\x00\x8e', 0, 175, 255, 155, 205, 1, 0, -45]
[2020-10-29 12:43:19][INFO] : New device joined: 0x4897, 00:17:88:01:03:96:14:a9
[2020-10-29 12:43:19][INFO] : Device 0x4897 (00:17:88:01:03:96:14:a9) joined the network
[2020-10-29 12:43:19][INFO] : [00:17:88:01:03:96:14:a9][listener.device_joined]
[2020-10-29 12:43:19][DEBUG] : Send to jeedom :  {'device_joined': '00:17:88:01:03:96:14:a9'}
[2020-10-29 12:43:19][DEBUG] : [0x4897:zdo] ZDO request ZDOCmd.Device_annce: [0x4897, 00:17:88:01:03:96:14:a9, 142]
[2020-10-29 12:43:19][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 12:43:19][INFO] : [0x4897] Requesting 'Node Descriptor'
[2020-10-29 12:43:19][DEBUG] : Tries remaining: 2
[2020-10-29 12:43:19][DEBUG] : [0x4897] Extending timeout for 0x12 request
[2020-10-29 12:43:19][DEBUG] : Sending Zigbee request with tsn 18 under 19 request id, data: b'129748'
[2020-10-29 12:43:19][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'009748a9149603018817008e'
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_request (18, 19, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'\x12\x97H', 2, 0)
[2020-10-29 12:43:19][DEBUG] : Send: 0x124e0019001200130002974800000002000003001297480200
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x124e00090002002213
[2020-10-29 12:43:19][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 19]
[2020-10-29 12:43:19][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e4f000700a600
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:19][DEBUG] : Send: 0x044f0007000000
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x044f0013000c00221302974800000000000000
[2020-10-29 12:43:19][DEBUG] : APS data confirm response for request with id 19: 00
[2020-10-29 12:43:19][DEBUG] : Request id: 0x13 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e50000700aa00
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:19][DEBUG] : Send: 0x1750000800010001
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x1750002f0028002202000000029748000000028011001200974801408e0b10472d0000002d000000afff9ecd0100d6
[2020-10-29 12:43:19][DEBUG] : APS data indication response: [40, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32770, b'\x12\x00\x97H\x01@\x8e\x0b\x10G-\x00\x00\x00-\x00\x00', 0, 175, 255, 158, 205, 1, 0, -42]
[2020-10-29 12:43:19][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8002, data: b'1200974801408e0b10472d0000002d0000'
[2020-10-29 12:43:19][INFO] : [0x4897] Node Descriptor: NodeDescriptor(byte1=1, byte2=64, mac_capability_flags=142, manufacturer_code=4107, maximum_buffer_size=71, maximum_incoming_transfer_size=45, server_mask=0, maximum_outgoing_transfer_size=45, descriptor_capability_field=0)
[2020-10-29 12:43:19][INFO] : [0x4897] Discovering endpoints
[2020-10-29 12:43:19][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:19][DEBUG] : Sending Zigbee request with tsn 20 under 21 request id, data: b'149748'
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_request (18, 21, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Active_EP_req: 0x0005>, 0, b'\x14\x97H', 2, 0)
[2020-10-29 12:43:19][DEBUG] : Send: 0x12510019001200150002974800000005000003001497480200
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x125100090002002215
[2020-10-29 12:43:19][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 21]
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e52000700a600
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:19][DEBUG] : Send: 0x04520007000000
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x04520013000c00221502974800000000000000
[2020-10-29 12:43:19][DEBUG] : APS data confirm response for request with id 21: 00
[2020-10-29 12:43:19][DEBUG] : Request id: 0x15 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e53000700aa00
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:19][DEBUG] : Send: 0x1753000800010001
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x17530025001e0022020000000297480000000580070014009748020bf200afff9ecd0100cc
[2020-10-29 12:43:19][DEBUG] : APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32773, b'\x14\x00\x97H\x02\x0b\xf2', 0, 175, 255, 158, 205, 1, 0, -52]
[2020-10-29 12:43:19][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8005, data: b'14009748020bf2'
[2020-10-29 12:43:19][INFO] : [0x4897] Discovered endpoints: [11, 242]
[2020-10-29 12:43:19][INFO] : [0x4897:11] Discovering endpoint information
[2020-10-29 12:43:19][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:19][DEBUG] : Sending Zigbee request with tsn 22 under 23 request id, data: b'1697480b'
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_request (19, 23, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, b'\x16\x97H\x0b', 2, 0)
[2020-10-29 12:43:19][DEBUG] : Send: 0x1254001a001300170002974800000004000004001697480b0200
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x125400090002002217
[2020-10-29 12:43:19][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 23]
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e55000700a600
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:19][DEBUG] : Send: 0x04550007000000
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x04550013000c00221702974800000000000000
[2020-10-29 12:43:19][DEBUG] : APS data confirm response for request with id 23: 00
[2020-10-29 12:43:19][DEBUG] : Request id: 0x17 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e56000700aa00
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:19][DEBUG] : Send: 0x1756000800010001
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x1756003d003600220200000002974800000004801f00160097481a0b5ec0100202080000030004000500060008000003001001190000afff9ecd0100cc
[2020-10-29 12:43:19][DEBUG] : APS data indication response: [54, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32772, b'\x16\x00\x97H\x1a\x0b^\xc0\x10\x02\x02\x08\x00\x00\x03\x00\x04\x00\x05\x00\x06\x00\x08\x00\x00\x03\x00\x10\x01\x19\x00', 0, 175, 255, 158, 205, 1, 0, -52]
[2020-10-29 12:43:19][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8004, data: b'160097481a0b5ec01002020800000300040005000600080000030010011900'
[2020-10-29 12:43:19][INFO] : [0x4897:11] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=11, profile=49246, device_type=528, device_version=2, input_clusters=[0, 3, 4, 5, 6, 8, 768, 4096], output_clusters=[25])
[2020-10-29 12:43:19][DEBUG] : Sending Zigbee request with tsn 24 under 25 request id, data: b'00180004000500'
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_request (22, 25, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 0, 1, b'\x00\x18\x00\x04\x00\x05\x00', 2, 0)
[2020-10-29 12:43:19][DEBUG] : Send: 0x1257001d00160019000297480b04010000010700001800040005000200
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x125700090002002219
[2020-10-29 12:43:19][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 25]
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e58000700a600
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:19][DEBUG] : Send: 0x04580007000000
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x04580013000c0022190297480b010000000000
[2020-10-29 12:43:19][DEBUG] : APS data confirm response for request with id 25: 00
[2020-10-29 12:43:19][DEBUG] : Request id: 0x19 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e59000700aa00
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:19][DEBUG] : Send: 0x1759000800010001
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x1759003800310022020000010297480b040100001a0018180104000042075068696c69707305000042064c435430303300afff9ecd0100cf
[2020-10-29 12:43:19][DEBUG] : APS data indication response: [49, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 11, 260, 0, b'\x18\x18\x01\x04\x00\x00B\x07Philips\x05\x00\x00B\x06LCT003', 0, 175, 255, 158, 205, 1, 0, -49]
[2020-10-29 12:43:19][DEBUG] : [0x4897:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=24 command_id=Command.Read_Attributes_rsp>
[2020-10-29 12:43:19][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'18180104000042075068696c69707305000042064c4354303033'
[2020-10-29 12:43:19][DEBUG] : [0x4897:11] Manufacturer: Philips
[2020-10-29 12:43:19][DEBUG] : [0x4897:11] Model: LCT003
[2020-10-29 12:43:19][INFO] : [0x4897:242] Discovering endpoint information
[2020-10-29 12:43:19][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:19][DEBUG] : Sending Zigbee request with tsn 26 under 27 request id, data: b'1a9748f2'
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_request (19, 27, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, b'\x1a\x97H\xf2', 2, 0)
[2020-10-29 12:43:19][DEBUG] : Send: 0x125a001a0013001b0002974800000004000004001a9748f20200
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x125a0009000200221b
[2020-10-29 12:43:19][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 27]
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x0e5b000700a600
[2020-10-29 12:43:19][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:19][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:19][DEBUG] : Send: 0x045b0007000000
[2020-10-29 12:43:19][DEBUG] : Frame received: 0x045b0013000c00221b02974800000000000000
[2020-10-29 12:43:19][DEBUG] : APS data confirm response for request with id 27: 00
[2020-10-29 12:43:19][DEBUG] : Request id: 0x1b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e5c000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x175c000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x175c002f0028002202000000029748000000048011001a0097480cf2e0a161000001210001210000afff9ecd0100cd
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [40, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32772, b'\x1a\x00\x97H\x0c\xf2\xe0\xa1a\x00\x00\x01!\x00\x01!\x00', 0, 175, 255, 158, 205, 1, 0, -51]
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8004, data: b'1a0097480cf2e0a1610000012100012100'
[2020-10-29 12:43:20][INFO] : [0x4897:242] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=242, profile=41440, device_type=97, device_version=0, input_clusters=[33], output_clusters=[33])
[2020-10-29 12:43:20][DEBUG] : Checking quirks for Philips LCT003 (00:17:88:01:03:96:14:a9)
[2020-10-29 12:43:20][DEBUG] : Considering <class 'zhaquirks.philips.zllextendedcolorlight.ZLLExtendedColorLight'>
[2020-10-29 12:43:20][DEBUG] : Found custom device replacement for 00:17:88:01:03:96:14:a9: <class 'zhaquirks.philips.zllextendedcolorlight.ZLLExtendedColorLight'>
[2020-10-29 12:43:20][INFO] : [00:17:88:01:03:96:14:a9][listener.device_initialized] new=True
[2020-10-29 12:43:20][DEBUG] : Send to jeedom :  {'device_initialized': '00:17:88:01:03:96:14:a9'}
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Begin device initialize
[2020-10-29 12:43:20][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Begin configuration of input cluster 'basic', is_server 'True'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bind input cluster 'basic'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 28 under 29 request id, data: b'1ca9149603018817000b00000380a105ffff2e210001'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (37, 29, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\x1c\xa9\x14\x96\x03\x01\x88\x17\x00\x0b\x00\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x125d002c0025001d0002974800000021000016001ca9149603018817000b00000380a105ffff2e2100010200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x125d0009000200221d
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 29]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e5e000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x045e0007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x045e0013000c00221d02974800000000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 29: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x1d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e5f000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x175f000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x175f00200019002202000000029748000000218002001c0000afe79ecd0100bf
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32801, b'\x1c\x00', 0, 175, 231, 158, 205, 1, 0, -65]
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'1c00'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bound 'basic' input cluster
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] End configuration of input cluster 'basic'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Begin configuration of input cluster 'scenes', is_server 'True'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bind input cluster 'scenes'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 30 under 31 request id, data: b'1ea9149603018817000b05000380a105ffff2e210001'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (37, 31, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\x1e\xa9\x14\x96\x03\x01\x88\x17\x00\x0b\x05\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1260002c0025001f0002974800000021000016001ea9149603018817000b05000380a105ffff2e2100010200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x12600009000200221f
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 31]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e61000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x04610007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x04610013000c00221f02974800000000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 31: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x1f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e62000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1762000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x176200200019002202000000029748000000218002001e8c00afff9ecd0100c8
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32801, b'\x1e\x8c', 0, 175, 255, 158, 205, 1, 0, -56]
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'1e8c'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bound 'scenes' input cluster
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] End configuration of input cluster 'scenes'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Begin configuration of input cluster 'on_off', is_server 'True'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bind input cluster 'on_off'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 32 under 33 request id, data: b'20a9149603018817000b06000380a105ffff2e210001'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (37, 33, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b' \xa9\x14\x96\x03\x01\x88\x17\x00\x0b\x06\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1263002c0025002100029748000000210000160020a9149603018817000b06000380a105ffff2e2100010200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x126300090002002221
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 33]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e64000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x04640007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x04640013000c00222102974800000000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 33: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x21 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e65000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1765000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x17650020001900220200000002974800000021800200208c00afff9ecd0100c9
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32801, b' \x8c', 0, 175, 255, 158, 205, 1, 0, -55]
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'208c'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bound 'on_off' input cluster
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] This input cluster have REPORT_CONFIG, we need to configure it
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Reporting 'on_off' attr on 'on_off' input cluster: 0/900/1: For: '00:17:88:01:03:96:14:a9'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 34 under 35 request id, data: b'0022060000001000008403'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (26, 35, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 6, 1, b'\x00"\x06\x00\x00\x00\x10\x00\x00\x84\x03', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x12660021001a0023000297480b04010600010b0000220600000010000084030200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x126600090002002223
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 35]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e67000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x04670007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x04670013000c0022230297480b010000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 35: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x23 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e68000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1768000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x17680025001e0022020000010297480b0401060007001822078c00000000afff9ecd0100cd
[2020-10-29 12:43:20][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=0x4897>, 11, 260, 6, b'\x18"\x07\x8c\x00\x00\x00', 0, 175, 255, 158, 205, 1, 0, -51]
[2020-10-29 12:43:20][DEBUG] : [0x4897:11:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=34 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0006, data: b'1822078c000000'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] End configuration of input cluster 'on_off'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Begin configuration of input cluster 'level', is_server 'True'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bind input cluster 'level'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 36 under 37 request id, data: b'24a9149603018817000b08000380a105ffff2e210001'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (37, 37, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'$\xa9\x14\x96\x03\x01\x88\x17\x00\x0b\x08\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1269002c0025002500029748000000210000160024a9149603018817000b08000380a105ffff2e2100010200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x126900090002002225
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 37]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e6a000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x046a0007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x046a0013000c00222502974800000000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 37: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x25 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e6b000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x176b000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x176b0020001900220200000002974800000021800200248c00afff9ecd0100cf
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32801, b'$\x8c', 0, 175, 255, 158, 205, 1, 0, -49]
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'248c'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bound 'level' input cluster
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] This input cluster have REPORT_CONFIG, we need to configure it
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Reporting 'current_level' attr on 'level' input cluster: 1/900/1: For: '00:17:88:01:03:96:14:a9'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 38 under 39 request id, data: b'002606000000200100840301'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (27, 39, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 8, 1, b'\x00&\x06\x00\x00\x00 \x01\x00\x84\x03\x01', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x126c0022001b0027000297480b04010800010c000026060000002001008403010200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x126c00090002002227
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 39]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e6d000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x046d0007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x046d0013000c0022270297480b010000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 39: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x27 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e6e000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x176e000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x176e0025001e0022020000010297480b0401080007001826078c00000000afff9ecd0100cf
[2020-10-29 12:43:20][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=0x4897>, 11, 260, 8, b'\x18&\x07\x8c\x00\x00\x00', 0, 175, 255, 158, 205, 1, 0, -49]
[2020-10-29 12:43:20][DEBUG] : [0x4897:11:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=38 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0008, data: b'1826078c000000'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] End configuration of input cluster 'level'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Begin configuration of input cluster 'lightlink', is_server 'True'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bind input cluster 'lightlink'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 40 under 41 request id, data: b'28a9149603018817000b00100380a105ffff2e210001'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (37, 41, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'(\xa9\x14\x96\x03\x01\x88\x17\x00\x0b\x00\x10\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x126f002c0025002900029748000000210000160028a9149603018817000b00100380a105ffff2e2100010200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x126f00090002002229
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 41]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e70000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x04700007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x04700013000c00222902974800000000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 41: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x29 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e71000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1771000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x17710020001900220200000002974800000021800200288c00afff9ecd0100cf
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32801, b'(\x8c', 0, 175, 255, 158, 205, 1, 0, -49]
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'288c'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bound 'lightlink' input cluster
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] End configuration of input cluster 'lightlink'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Begin configuration of input cluster 'light_color', is_server 'True'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bind input cluster 'light_color'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 42 under 43 request id, data: b'2aa9149603018817000b00030380a105ffff2e210001'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (37, 43, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'*\xa9\x14\x96\x03\x01\x88\x17\x00\x0b\x00\x03\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1272002c0025002b0002974800000021000016002aa9149603018817000b00030380a105ffff2e2100010200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x12720009000200222b
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 43]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e73000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x04730007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x04730013000c00222b02974800000000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 43: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x2b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=0>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e74000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1774000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x177400200019002202000000029748000000218002002a8c00afff9ecd0100cd
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 32801, b'*\x8c', 0, 175, 255, 158, 205, 1, 0, -51]
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'2a8c'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Bound 'light_color' input cluster
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] This input cluster have REPORT_CONFIG, we need to configure it
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Reporting 'current_x' attr on 'light_color' input cluster: 30/900/1: For: '00:17:88:01:03:96:14:a9'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 44 under 45 request id, data: b'002c06000300211e0084030100'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (28, 45, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 768, 1, b'\x00,\x06\x00\x03\x00!\x1e\x00\x84\x03\x01\x00', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x12750023001c002d000297480b04010003010d00002c06000300211e00840301000200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x12750009000200222d
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 45]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e76000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x04760007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x04760013000c00222d0297480b010000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 45: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x2d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e77000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1777000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x17770025001e0022020000010297480b040100030700182c078c00030000afff9ecd0100cc
[2020-10-29 12:43:20][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=0x4897>, 11, 260, 768, b'\x18,\x07\x8c\x00\x03\x00', 0, 175, 255, 158, 205, 1, 0, -52]
[2020-10-29 12:43:20][DEBUG] : [0x4897:11:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=44 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'182c078c000300'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Reporting 'current_y' attr on 'light_color' input cluster: 30/900/1: For: '00:17:88:01:03:96:14:a9'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 46 under 47 request id, data: b'002e06000400211e0084030100'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (28, 47, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 768, 1, b'\x00.\x06\x00\x04\x00!\x1e\x00\x84\x03\x01\x00', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x12780023001c002f000297480b04010003010d00002e06000400211e00840301000200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x12780009000200222f
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 47]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e79000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x04790007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x04790013000c00222f0297480b010000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 47: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x2f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e7a000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x177a000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x177a0025001e0022020000010297480b040100030700182e078c00040000afff9ecd0100c9
[2020-10-29 12:43:20][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=0x4897>, 11, 260, 768, b'\x18.\x07\x8c\x00\x04\x00', 0, 175, 255, 158, 205, 1, 0, -55]
[2020-10-29 12:43:20][DEBUG] : [0x4897:11:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=46 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'182e078c000400'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] Reporting 'color_temperature' attr on 'light_color' input cluster: 30/900/1: For: '00:17:88:01:03:96:14:a9'
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 48 under 49 request id, data: b'003006000700211e0084030100'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (28, 49, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 768, 1, b'\x000\x06\x00\x07\x00!\x1e\x00\x84\x03\x01\x00', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x127b0023001c0031000297480b04010003010d00003006000700211e00840301000200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x127b00090002002231
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 49]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e7c000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x047c0007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x047c0013000c0022310297480b010000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 49: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x31 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e7d000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x177d000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x177d0025001e0022020000010297480b0401000307001830078c00070000afff9ecd0100c6
[2020-10-29 12:43:20][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=0x4897>, 11, 260, 768, b'\x180\x07\x8c\x00\x07\x00', 0, 175, 255, 158, 205, 1, 0, -58]
[2020-10-29 12:43:20][DEBUG] : [0x4897:11:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=48 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'1830078c000700'
[2020-10-29 12:43:20][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] End configuration of input cluster 'light_color'
[2020-10-29 12:43:20][WARNING] : [00:17:88:01:03:96:14:a9][zdevices.get_basic_info] Begin get basic info from device
[2020-10-29 12:43:20][WARNING] : [00:17:88:01:03:96:14:a9][zdevices.get_basic_info] End point found
[2020-10-29 12:43:20][DEBUG] : Sending Zigbee request with tsn 50 under 51 request id, data: b'00320004000500'
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_request (22, 51, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 0, 1, b'\x002\x00\x04\x00\x05\x00', 2, 0)
[2020-10-29 12:43:20][DEBUG] : Send: 0x127e001d00160033000297480b04010000010700003200040005000200
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x127e00090002002233
[2020-10-29 12:43:20][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 51]
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e7f000700a600
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:20][DEBUG] : Send: 0x047f0007000000
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x047f0013000c0022330297480b010000000000
[2020-10-29 12:43:20][DEBUG] : APS data confirm response for request with id 51: 00
[2020-10-29 12:43:20][DEBUG] : Request id: 0x33 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x0e80000700aa00
[2020-10-29 12:43:20][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:20][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:20][DEBUG] : Send: 0x1780000800010001
[2020-10-29 12:43:20][DEBUG] : Frame received: 0x1780003800310022020000010297480b040100001a0018320104000042075068696c69707305000042064c435430303300afff9ecd0100c2
[2020-10-29 12:43:20][DEBUG] : APS data indication response: [49, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 11, 260, 0, b'\x182\x01\x04\x00\x00B\x07Philips\x05\x00\x00B\x06LCT003', 0, 175, 255, 158, 205, 1, 0, -62]
[2020-10-29 12:43:20][DEBUG] : [0x4897:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=50 command_id=Command.Read_Attributes_rsp>
[2020-10-29 12:43:20][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'18320104000042075068696c69707305000042064c4354303033'
[2020-10-29 12:43:20][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 4=Philips on cluster 0
[2020-10-29 12:43:20][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 5=LCT003 on cluster 0
[2020-10-29 12:43:20][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:03:96:14:a9': {'11': {'0': {'4': {'value': 'Philips', 'cluster_name': 'Basic'}, '5': {'value': 'LCT003', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 12:43:20][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 12:43:20][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 12:43:21][DEBUG] : Sending Zigbee request with tsn 52 under 53 request id, data: b'0034000000010002000300'
[2020-10-29 12:43:21][DEBUG] : Command Command.aps_data_request (26, 53, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 0, 1, b'\x004\x00\x00\x00\x01\x00\x02\x00\x03\x00', 2, 0)
[2020-10-29 12:43:21][DEBUG] : Send: 0x12810021001a0035000297480b04010000010b0000340000000100020003000200
[2020-10-29 12:43:21][DEBUG] : Frame received: 0x128100090002002235
[2020-10-29 12:43:21][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 53]
[2020-10-29 12:43:21][DEBUG] : Frame received: 0x0e82000700a600
[2020-10-29 12:43:21][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:21][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:21][DEBUG] : Send: 0x04820007000000
[2020-10-29 12:43:21][DEBUG] : Frame received: 0x04820013000c0022350297480b010000000000
[2020-10-29 12:43:21][DEBUG] : APS data confirm response for request with id 53: 00
[2020-10-29 12:43:21][DEBUG] : Request id: 0x35 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:21][DEBUG] : Frame received: 0x0e83000700aa00
[2020-10-29 12:43:21][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:21][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:21][DEBUG] : Send: 0x1783000800010001
[2020-10-29 12:43:21][DEBUG] : Frame received: 0x17830035002e0022020000010297480b040100001700183401000000200101000020020200002001030000200f00afff9ecd0100d8
[2020-10-29 12:43:21][DEBUG] : APS data indication response: [46, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 11, 260, 0, b'\x184\x01\x00\x00\x00 \x01\x01\x00\x00 \x02\x02\x00\x00 \x01\x03\x00\x00 \x0f', 0, 175, 255, 158, 205, 1, 0, -40]
[2020-10-29 12:43:21][DEBUG] : [0x4897:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=52 command_id=Command.Read_Attributes_rsp>
[2020-10-29 12:43:21][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'183401000000200101000020020200002001030000200f'
[2020-10-29 12:43:21][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 0=1 on cluster 0
[2020-10-29 12:43:21][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 1=2 on cluster 0
[2020-10-29 12:43:21][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 2=1 on cluster 0
[2020-10-29 12:43:21][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 3=15 on cluster 0
[2020-10-29 12:43:21][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:03:96:14:a9': {'11': {'0': {'0': {'value': '1', 'cluster_name': 'Basic'}, '1': {'value': '2', 'cluster_name': 'Basic'}, '2': {'value': '1', 'cluster_name': 'Basic'}, '3': {'value': '15', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 12:43:21][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 12:43:21][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x0e84000700aa00
[2020-10-29 12:43:22][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:22][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:22][DEBUG] : Send: 0x1784000800010001
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x17840026001f002202000001021ad10204010004080008bd0a000021a00b00afff9ecd0100d9
[2020-10-29 12:43: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=0xd11a>, 2, 260, 1024, b'\x08\xbd
\x00\x00!\xa0\x0b', 0, 175, 255, 158, 205, 1, 0, -39]
[2020-10-29 12:43:22][DEBUG] : [0xd11a:2:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=189 command_id=Command.Report_Attributes>
[2020-10-29 12:43:22][DEBUG] : [0xd11a:2:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=2976>)]]
[2020-10-29 12:43:22][INFO] : [00:17:88:01:02:00:5e:93][listener.general_command] Cluster: <zigpy.zcl.clusters.measurement.IlluminanceMeasurement object at 0x7f9195bfd0> ClusterId: 0x0400 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=189 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=2976>)]],)
[2020-10-29 12:43:22][DEBUG] : [0xd11a:2:0x0400] Attribute report received: measured_value=2976
[2020-10-29 12:43:22][INFO] : [00:17:88:01:02:00:5e:93][listener.attribute_updated] Received an attribute update 0=2976 on cluster 1024
[2020-10-29 12:43:22][DEBUG] : Sending Zigbee request with tsn 189 under 54 request id, data: b'18bd0b0a00'
[2020-10-29 12:43:22][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd11a>, ep: 2, profile: 0x0104, cluster_id: 0x0400, data: b'08bd0a000021a00b'
[2020-10-29 12:43:22][DEBUG] : Command Command.aps_data_request (20, 54, 0, <DeconzAddressEndpoint address_mode=2 address=0xD11A endpoint=2>, 260, 1024, 1, b'\x18\xbd\x0b
\x00', 2, 0)
[2020-10-29 12:43:22][DEBUG] : Send: 0x1285001b0014003600021ad1020401000401050018bd0b0a000200
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x128500090002002236
[2020-10-29 12:43:22][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 54]
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x0e86000700a600
[2020-10-29 12:43:22][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:22][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:22][DEBUG] : Send: 0x04860007000000
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x04860013000c002236021ad102010000000000
[2020-10-29 12:43:22][DEBUG] : APS data confirm response for request with id 54: 00
[2020-10-29 12:43:22][DEBUG] : Request id: 0x36 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xd11a endpoint=2>, status: 0x00
[2020-10-29 12:43:22][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:02:00:5e:93': {'2': {'1024': {'0': {'value': '2976', 'cluster_name': 'Illuminance Measurement'}}}}}}
[2020-10-29 12:43:22][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 12:43:22][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 12:43:22][DEBUG] : Sending Zigbee request with tsn 55 under 56 request id, data: b'0037000700'
[2020-10-29 12:43:22][DEBUG] : Command Command.aps_data_request (20, 56, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 0, 1, b'\x007\x00\x07\x00', 2, 0)
[2020-10-29 12:43:22][DEBUG] : Send: 0x1287001b00140038000297480b0401000001050000370007000200
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x128700090002002238
[2020-10-29 12:43:22][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 56]
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x0e88000700a600
[2020-10-29 12:43:22][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:22][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:22][DEBUG] : Send: 0x04880007000000
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x04880013000c0022380297480b010000000000
[2020-10-29 12:43:22][DEBUG] : APS data confirm response for request with id 56: 00
[2020-10-29 12:43:22][DEBUG] : Request id: 0x38 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x0e89000700aa00
[2020-10-29 12:43:22][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:22][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:22][DEBUG] : Send: 0x1789000800010001
[2020-10-29 12:43:22][DEBUG] : Frame received: 0x17890026001f0022020000010297480b040100000800183701070000300100afff9ecd0100db
[2020-10-29 12:43: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=0x4897>, 11, 260, 0, b'\x187\x01\x07\x00\x000\x01', 0, 175, 255, 158, 205, 1, 0, -37]
[2020-10-29 12:43:22][DEBUG] : [0x4897:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=55 command_id=Command.Read_Attributes_rsp>
[2020-10-29 12:43:22][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'1837010700003001'
[2020-10-29 12:43:22][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 7=PowerSource.Mains_single_phase on cluster 0
[2020-10-29 12:43:22][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:03:96:14:a9': {'11': {'0': {'7': {'value': 'PowerSource.Mains_single_phase', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 12:43:22][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 12:43:22][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 12:43:23][DEBUG] : Sending Zigbee request with tsn 57 under 58 request id, data: b'00390006000040'
[2020-10-29 12:43:23][DEBUG] : Command Command.aps_data_request (22, 58, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 0, 1, b'\x009\x00\x06\x00\x00@', 2, 0)
[2020-10-29 12:43:23][DEBUG] : Send: 0x128a001d0016003a000297480b04010000010700003900060000400200
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x128a0009000200223a
[2020-10-29 12:43:23][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 58]
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x0e8b000700a600
[2020-10-29 12:43:23][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:23][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:23][DEBUG] : Send: 0x048b0007000000
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x048b0013000c00223a0297480b010000000000
[2020-10-29 12:43:23][DEBUG] : APS data confirm response for request with id 58: 00
[2020-10-29 12:43:23][DEBUG] : Request id: 0x3a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x0e8c000700aa00
[2020-10-29 12:43:23][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:23][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:23][DEBUG] : Send: 0x178c000800010001
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x178c003f00380022020000010297480b04010000210018390106000042083230313630383130004000420c352e35302e312e313930383500afff9ecd0100db
[2020-10-29 12:43:23][DEBUG] : APS data indication response: [56, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 11, 260, 0, b'\x189\x01\x06\x00\x00B\x0820160810\x00@\x00B\x0c5.50.1.19085', 0, 175, 255, 158, 205, 1, 0, -37]
[2020-10-29 12:43:23][DEBUG] : [0x4897:11:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=57 command_id=Command.Read_Attributes_rsp>
[2020-10-29 12:43:23][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'18390106000042083230313630383130004000420c352e35302e312e3139303835'
[2020-10-29 12:43:23][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 6=20160810 on cluster 0
[2020-10-29 12:43:23][INFO] : [00:17:88:01:03:96:14:a9][listener.attribute_updated] Received an attribute update 16384=5.50.1.19085 on cluster 0
[2020-10-29 12:43:23][WARNING] : [00:17:88:01:03:96:14:a9][zdevices.get_basic_info] End get basic info from device
[2020-10-29 12:43:23][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] It's deconz key, force neightbors scan
[2020-10-29 12:43:23][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:23][DEBUG] : Sending Zigbee request with tsn 59 under 60 request id, data: b'3b00'
[2020-10-29 12:43:23][DEBUG] : Command Command.aps_data_request (17, 60, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b';\x00', 2, 0)
[2020-10-29 12:43:23][DEBUG] : Send: 0x128d00180011003c0002000000000031000002003b000200
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x128d0009000200223c
[2020-10-29 12:43:23][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 60]
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x0e8e000700ae00
[2020-10-29 12:43:23][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:23][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:23][DEBUG] : Send: 0x178e000800010001
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x178e0039003200260200000002000000000031801b003b0009000180a105ffff2e2100cd4772feff57b41453bf250101fc00afff9ecd0100db
[2020-10-29 12:43:23][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b';\x00	\x00\x01\x80\xa1\x05\xff\xff.!\x00\xcdGr\xfe\xffW\xb4\x14S\xbf%\x01\x01\xfc', 0, 175, 255, 158, 205, 1, 0, -37]
[2020-10-29 12:43:23][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'3b0009000180a105ffff2e2100cd4772feff57b41453bf250101fc'
[2020-10-29 12:43:23][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:23][DEBUG] : Send: 0x048f0007000000
[2020-10-29 12:43:23][DEBUG] : Frame received: 0x048f0013000c00223c02000000000000000000
[2020-10-29 12:43:23][DEBUG] : APS data confirm response for request with id 60: 00
[2020-10-29 12:43:23][DEBUG] : Request id: 0x3c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:23][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=0, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=14:b4:57:ff:fe:72:47:cd, nwk=0xBF53, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=252)])
[2020-10-29 12:43:23][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:03:96:14:a9': {'11': {'0': {'6': {'value': '20160810', 'cluster_name': 'Basic'}, '16384': {'value': '5.50.1.19085', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 12:43:23][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 12:43:23][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 12:43:24][DEBUG] : [0x0000] Querying next starting at 1
[2020-10-29 12:43:24][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:24][DEBUG] : Sending Zigbee request with tsn 61 under 62 request id, data: b'3d01'
[2020-10-29 12:43:24][DEBUG] : Command Command.aps_data_request (17, 62, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'=\x01', 2, 0)
[2020-10-29 12:43:24][DEBUG] : Send: 0x129000180011003e0002000000000031000002003d010200
[2020-10-29 12:43:24][DEBUG] : Frame received: 0x12900009000200223e
[2020-10-29 12:43:24][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 62]
[2020-10-29 12:43:24][DEBUG] : Frame received: 0x0e91000700ae00
[2020-10-29 12:43:24][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:24][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:24][DEBUG] : Send: 0x1791000800010001
[2020-10-29 12:43:24][DEBUG] : Frame received: 0x17910039003200260200000002000000000031801b003d0009010180a105ffff2e2100a9149603018817009748350101fc00af5e9ecd010001
[2020-10-29 12:43:24][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'=\x00	\x01\x01\x80\xa1\x05\xff\xff.!\x00\xa9\x14\x96\x03\x01\x88\x17\x00\x97H5\x01\x01\xfc', 0, 175, 94, 158, 205, 1, 0, 1]
[2020-10-29 12:43:24][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'3d0009010180a105ffff2e2100a9149603018817009748350101fc'
[2020-10-29 12:43:24][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:24][DEBUG] : Send: 0x04920007000000
[2020-10-29 12:43:24][DEBUG] : Frame received: 0x04920013000c00223e02000000000000000000
[2020-10-29 12:43:24][DEBUG] : APS data confirm response for request with id 62: 00
[2020-10-29 12:43:24][DEBUG] : Request id: 0x3e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:24][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=1, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=00:17:88:01:03:96:14:a9, nwk=0x4897, packed=53, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=252)])
[2020-10-29 12:43:26][DEBUG] : [0x0000] Querying next starting at 2
[2020-10-29 12:43:26][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:26][DEBUG] : Sending Zigbee request with tsn 63 under 64 request id, data: b'3f02'
[2020-10-29 12:43:26][DEBUG] : Command Command.aps_data_request (17, 64, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'?\x02', 2, 0)
[2020-10-29 12:43:26][DEBUG] : Send: 0x12930018001100400002000000000031000002003f020200
[2020-10-29 12:43:26][DEBUG] : Frame received: 0x129300090002002240
[2020-10-29 12:43:26][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 64]
[2020-10-29 12:43:26][DEBUG] : Frame received: 0x0e94000700ae00
[2020-10-29 12:43:26][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:26][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:26][DEBUG] : Send: 0x1794000800010001
[2020-10-29 12:43:26][DEBUG] : Frame received: 0x17940039003200260200000002000000000031801b003f0009020180a105ffff2e2100273510090188170019c6250101fc00af5e9ecd010001
[2020-10-29 12:43:26][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b"?\x00	\x02\x01\x80\xa1\x05\xff\xff.!\x00'5\x10	\x01\x88\x17\x00\x19\xc6%\x01\x01\xfc", 0, 175, 94, 158, 205, 1, 0, 1]
[2020-10-29 12:43:26][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'3f0009020180a105ffff2e2100273510090188170019c6250101fc'
[2020-10-29 12:43:26][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:26][DEBUG] : Send: 0x04950007000000
[2020-10-29 12:43:26][DEBUG] : Frame received: 0x04950013000c00224002000000000000000000
[2020-10-29 12:43:26][DEBUG] : APS data confirm response for request with id 64: 00
[2020-10-29 12:43:26][DEBUG] : Request id: 0x40 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:26][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=2, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=00:17:88:01:09:10:35:27, nwk=0xC619, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=252)])
[2020-10-29 12:43:27][DEBUG] : [0x0000] Querying next starting at 3
[2020-10-29 12:43:27][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:27][DEBUG] : Sending Zigbee request with tsn 65 under 66 request id, data: b'4103'
[2020-10-29 12:43:27][DEBUG] : Command Command.aps_data_request (17, 66, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'A\x03', 2, 0)
[2020-10-29 12:43:27][DEBUG] : Send: 0x129600180011004200020000000000310000020041030200
[2020-10-29 12:43:27][DEBUG] : Frame received: 0x129600090002002242
[2020-10-29 12:43:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 66]
[2020-10-29 12:43:27][DEBUG] : Frame received: 0x0e97000700ae00
[2020-10-29 12:43:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:27][DEBUG] : Send: 0x1797000800010001
[2020-10-29 12:43:27][DEBUG] : Frame received: 0x17970039003200260200000002000000000031801b00410009030180a105ffff2e210072773bfeffd76b0807f0250101fc00af5e9ecd010001
[2020-10-29 12:43:27][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'A\x00	\x03\x01\x80\xa1\x05\xff\xff.!\x00rw;\xfe\xff\xd7k\x08\x07\xf0%\x01\x01\xfc', 0, 175, 94, 158, 205, 1, 0, 1]
[2020-10-29 12:43:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'410009030180a105ffff2e210072773bfeffd76b0807f0250101fc'
[2020-10-29 12:43:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:27][DEBUG] : Send: 0x04980007000000
[2020-10-29 12:43:27][DEBUG] : Frame received: 0x04980013000c00224202000000000000000000
[2020-10-29 12:43:27][DEBUG] : APS data confirm response for request with id 66: 00
[2020-10-29 12:43:27][DEBUG] : Request id: 0x42 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:27][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=3, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=08:6b:d7:ff:fe:3b:77:72, nwk=0xF007, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=252)])
[2020-10-29 12:43:28][DEBUG] : [0x0000] Querying next starting at 4
[2020-10-29 12:43:28][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:28][DEBUG] : Sending Zigbee request with tsn 67 under 68 request id, data: b'4304'
[2020-10-29 12:43:28][DEBUG] : Command Command.aps_data_request (17, 68, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'C\x04', 2, 0)
[2020-10-29 12:43:28][DEBUG] : Send: 0x129900180011004400020000000000310000020043040200
[2020-10-29 12:43:28][DEBUG] : Frame received: 0x129900090002002244
[2020-10-29 12:43:28][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 68]
[2020-10-29 12:43:28][DEBUG] : Frame received: 0x0e9a000700ae00
[2020-10-29 12:43:28][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:28][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:28][DEBUG] : Send: 0x179a000800010001
[2020-10-29 12:43:28][DEBUG] : Frame received: 0x179a0039003200260200000002000000000031801b00430009040180a105ffff2e2100ed8ae6080188170098a5250101fc00afe89ecd010000
[2020-10-29 12:43:28][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'C\x00	\x04\x01\x80\xa1\x05\xff\xff.!\x00\xed\x8a\xe6\x08\x01\x88\x17\x00\x98\xa5%\x01\x01\xfc', 0, 175, 232, 158, 205, 1, 0, 0]
[2020-10-29 12:43:28][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'430009040180a105ffff2e2100ed8ae6080188170098a5250101fc'
[2020-10-29 12:43:28][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:28][DEBUG] : Send: 0x049b0007000000
[2020-10-29 12:43:28][DEBUG] : Frame received: 0x049b0013000c00224402000000000000000000
[2020-10-29 12:43:28][DEBUG] : APS data confirm response for request with id 68: 00
[2020-10-29 12:43:28][DEBUG] : Request id: 0x44 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:28][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=4, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=00:17:88:01:08:e6:8a:ed, nwk=0xA598, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=252)])
[2020-10-29 12:43:30][DEBUG] : [0x0000] Querying next starting at 5
[2020-10-29 12:43:30][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:30][DEBUG] : Sending Zigbee request with tsn 69 under 70 request id, data: b'4505'
[2020-10-29 12:43:30][DEBUG] : Command Command.aps_data_request (17, 70, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'E\x05', 2, 0)
[2020-10-29 12:43:30][DEBUG] : Send: 0x129c00180011004600020000000000310000020045050200
[2020-10-29 12:43:30][DEBUG] : Frame received: 0x129c00090002002246
[2020-10-29 12:43:30][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 70]
[2020-10-29 12:43:30][DEBUG] : Frame received: 0x0e9d000700ae00
[2020-10-29 12:43:30][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:30][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:30][DEBUG] : Send: 0x179d000800010001
[2020-10-29 12:43:30][DEBUG] : Frame received: 0x179d0039003200260200000002000000000031801b00450009050180a105ffff2e210093ca200201881700ff4d250101ff00afe89ecd010000
[2020-10-29 12:43:30][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'E\x00	\x05\x01\x80\xa1\x05\xff\xff.!\x00\x93\xca \x02\x01\x88\x17\x00\xffM%\x01\x01\xff', 0, 175, 232, 158, 205, 1, 0, 0]
[2020-10-29 12:43:30][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'450009050180a105ffff2e210093ca200201881700ff4d250101ff'
[2020-10-29 12:43:30][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:30][DEBUG] : Send: 0x049e0007000000
[2020-10-29 12:43:30][DEBUG] : Frame received: 0x049e0013000c00224602000000000000000000
[2020-10-29 12:43:30][DEBUG] : APS data confirm response for request with id 70: 00
[2020-10-29 12:43:30][DEBUG] : Request id: 0x46 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:30][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=5, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=00:17:88:01:02:20:ca:93, nwk=0x4DFF, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=255)])
[2020-10-29 12:43:31][DEBUG] : [0x0000] Querying next starting at 6
[2020-10-29 12:43:31][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:31][DEBUG] : Sending Zigbee request with tsn 71 under 72 request id, data: b'4706'
[2020-10-29 12:43:31][DEBUG] : Command Command.aps_data_request (17, 72, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'G\x06', 2, 0)
[2020-10-29 12:43:31][DEBUG] : Send: 0x129f00180011004800020000000000310000020047060200
[2020-10-29 12:43:31][DEBUG] : Frame received: 0x129f00090002002248
[2020-10-29 12:43:31][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 72]
[2020-10-29 12:43:31][DEBUG] : Frame received: 0x0ea0000700ae00
[2020-10-29 12:43:31][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:31][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:31][DEBUG] : Send: 0x17a0000800010001
[2020-10-29 12:43:31][DEBUG] : Frame received: 0x17a00039003200260200000002000000000031801b00470009060180a105ffff2e21001681c4feffe20a68a603120001ff00afe89ecd010000
[2020-10-29 12:43:31][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'G\x00	\x06\x01\x80\xa1\x05\xff\xff.!\x00\x16\x81\xc4\xfe\xff\xe2
h\xa6\x03\x12\x00\x01\xff', 0, 175, 232, 158, 205, 1, 0, 0]
[2020-10-29 12:43:31][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'470009060180a105ffff2e21001681c4feffe20a68a603120001ff'
[2020-10-29 12:43:31][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:31][DEBUG] : Send: 0x04a10007000000
[2020-10-29 12:43:31][DEBUG] : Frame received: 0x04a10013000c00224802000000000000000000
[2020-10-29 12:43:31][DEBUG] : APS data confirm response for request with id 72: 00
[2020-10-29 12:43:31][DEBUG] : Request id: 0x48 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:31][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=6, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=68:0a:e2:ff:fe:c4:81:16, nwk=0x03A6, packed=18, permit_joining=<PermitJoins.NotAccepting: 0>, depth=1, lqi=255)])
[2020-10-29 12:43:32][DEBUG] : [0x0000] Querying next starting at 7
[2020-10-29 12:43:32][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:32][DEBUG] : Sending Zigbee request with tsn 73 under 74 request id, data: b'4907'
[2020-10-29 12:43:32][DEBUG] : Command Command.aps_data_request (17, 74, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'I\x07', 2, 0)
[2020-10-29 12:43:32][DEBUG] : Send: 0x12a200180011004a00020000000000310000020049070200
[2020-10-29 12:43:32][DEBUG] : Frame received: 0x12a20009000200224a
[2020-10-29 12:43:32][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 74]
[2020-10-29 12:43:32][DEBUG] : Frame received: 0x0ea3000700ae00
[2020-10-29 12:43:32][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:32][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:32][DEBUG] : Send: 0x17a3000800010001
[2020-10-29 12:43:32][DEBUG] : Frame received: 0x17a30039003200260200000002000000000031801b00490009070180a105ffff2e210050aece04018817000786250101ff00afe89ecd010000
[2020-10-29 12:43:32][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'I\x00	\x07\x01\x80\xa1\x05\xff\xff.!\x00P\xae\xce\x04\x01\x88\x17\x00\x07\x86%\x01\x01\xff', 0, 175, 232, 158, 205, 1, 0, 0]
[2020-10-29 12:43:32][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'490009070180a105ffff2e210050aece04018817000786250101ff'
[2020-10-29 12:43:32][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:32][DEBUG] : Send: 0x04a40007000000
[2020-10-29 12:43:32][DEBUG] : Frame received: 0x04a40013000c00224a02000000000000000000
[2020-10-29 12:43:32][DEBUG] : APS data confirm response for request with id 74: 00
[2020-10-29 12:43:32][DEBUG] : Request id: 0x4a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:32][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=7, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=00:17:88:01:04:ce:ae:50, nwk=0x8607, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=255)])
[2020-10-29 12:43:34][DEBUG] : [0x0000] Querying next starting at 8
[2020-10-29 12:43:34][DEBUG] : Tries remaining: 3
[2020-10-29 12:43:34][DEBUG] : Sending Zigbee request with tsn 75 under 76 request id, data: b'4b08'
[2020-10-29 12:43:34][DEBUG] : Command Command.aps_data_request (17, 76, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'K\x08', 2, 0)
[2020-10-29 12:43:34][DEBUG] : Send: 0x12a500180011004c0002000000000031000002004b080200
[2020-10-29 12:43:34][DEBUG] : Frame received: 0x12a50009000200224c
[2020-10-29 12:43:34][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 76]
[2020-10-29 12:43:34][DEBUG] : Frame received: 0x0ea6000700ae00
[2020-10-29 12:43:34][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 12:43:34][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:34][DEBUG] : Send: 0x17a6000800010001
[2020-10-29 12:43:34][DEBUG] : Frame received: 0x17a60039003200260200000002000000000031801b004b0009080180a105ffff2e2100e6cd2d0801881700074c250101fc00afe89ecd010000
[2020-10-29 12:43:34][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'K\x00	\x08\x01\x80\xa1\x05\xff\xff.!\x00\xe6\xcd-\x08\x01\x88\x17\x00\x07L%\x01\x01\xfc', 0, 175, 232, 158, 205, 1, 0, 0]
[2020-10-29 12:43:34][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'4b0009080180a105ffff2e2100e6cd2d0801881700074c250101fc'
[2020-10-29 12:43:34][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:34][DEBUG] : Send: 0x04a70007000000
[2020-10-29 12:43:34][DEBUG] : Frame received: 0x04a70013000c00224c02000000000000000000
[2020-10-29 12:43:34][DEBUG] : APS data confirm response for request with id 76: 00
[2020-10-29 12:43:34][DEBUG] : Request id: 0x4c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 12:43:34][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=9, start_index=8, neighbor_table_list=[Neighbor(extended_pan_id=00:21:2e:ff:ff:05:a1:80, ieee=00:17:88:01:08:2d:cd:e6, nwk=0x4C07, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=252)])
[2020-10-29 12:43:34][DEBUG] : [0x0000] Done scanning. Total 9 neighbours
[2020-10-29 12:43:34][DEBUG] : [00:17:88:01:03:96:14:a9][zdevices.initialize] End device initialize
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x1ca80012000b0003a914960301881700ffdb
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x0ea9000700aa00
[2020-10-29 12:43:35][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:35][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:35][DEBUG] : Send: 0x17a8000800010001
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x17a8002a0023002202fdff0002974800000013000c00009748a9149603018817008e00afffbccd0100da
[2020-10-29 12:43:35][DEBUG] : APS data indication response: [35, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 0, 0, 19, b'\x00\x97H\xa9\x14\x96\x03\x01\x88\x17\x00\x8e', 0, 175, 255, 188, 205, 1, 0, -38]
[2020-10-29 12:43:35][INFO] : New device joined: 0x4897, 00:17:88:01:03:96:14:a9
[2020-10-29 12:43:35][INFO] : Device 0x4897 (00:17:88:01:03:96:14:a9) joined the network
[2020-10-29 12:43:35][DEBUG] : Skip initialization for existing device 00:17:88:01:03:96:14:a9
[2020-10-29 12:43:35][DEBUG] : [0x4897:zdo] ZDO request ZDOCmd.Device_annce: [0x4897, 00:17:88:01:03:96:14:a9, 142]
[2020-10-29 12:43:35][DEBUG] : Sending Zigbee request with tsn 77 under 78 request id, data: b'014d0200'
[2020-10-29 12:43:35][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'009748a9149603018817008e'
[2020-10-29 12:43:35][DEBUG] : Command Command.aps_data_request (19, 78, 0, <DeconzAddressEndpoint address_mode=2 address=0x4897 endpoint=11>, 260, 4, 1, b'\x01M\x02\x00', 2, 0)
[2020-10-29 12:43:35][DEBUG] : Send: 0x12a9001a0013004e000297480b04010400010400014d02000200
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x12a90009000200224e
[2020-10-29 12:43:35][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 78]
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x0eaa000700a600
[2020-10-29 12:43:35][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:43:35][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:43:35][DEBUG] : Send: 0x04aa0007000000
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x04aa0013000c00224e0297480b010000000000
[2020-10-29 12:43:35][DEBUG] : APS data confirm response for request with id 78: 00
[2020-10-29 12:43:35][DEBUG] : Request id: 0x4e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x4897 endpoint=11>, status: 0x00
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x0eab000700aa00
[2020-10-29 12:43:35][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:43:35][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:43:35][DEBUG] : Send: 0x17ab000800010001
[2020-10-29 12:43:35][DEBUG] : Frame received: 0x17ab0023001c0022020000010297480b040104000500194d02190000afffb7cd0100d8
[2020-10-29 12:43:35][DEBUG] : APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, 11, 260, 4, b'\x19M\x02\x19\x00', 0, 175, 255, 183, 205, 1, 0, -40]
[2020-10-29 12:43:35][DEBUG] : [0x4897:11:0x0004] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=77 command_id=2>
[2020-10-29 12:43:35][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x4897>, ep: 11, profile: 0x0104, cluster_id: 0x0004, data: b'194d021900'
[2020-10-29 12:44:20][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-10-29 12:44:20][INFO] : 200 GET /device/all (127.0.0.1) 65.54ms
[2020-10-29 12:44:20][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 12:44:23][DEBUG] : Frame received: 0x0eac000700aa00
[2020-10-29 12:44:23][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 12:44:23][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 12:44:23][DEBUG] : Send: 0x17ac000800010001
[2020-10-29 12:44:23][DEBUG] : Frame received: 0x17ac002a00230022020000010219c60b040119000c00011e01000b1017010007000100afffc3cd0100db
[2020-10-29 12:44:23][DEBUG] : APS data indication response: [35, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xc619>, 11, 260, 25, b'\x01\x1e\x01\x00\x0b\x10\x17\x01\x00\x07\x00\x01', 0, 175, 255, 195, 205, 1, 0, -37]
[2020-10-29 12:44:23][DEBUG] : [0xc619:11:0x0019] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=30 command_id=1>
[2020-10-29 12:44:23][DEBUG] : [0xc619:11:0x0019] ZCL request 0x0001: [0, 4107, 279, 16779008, None]
[2020-10-29 12:44:23][INFO] : [00:17:88:01:09:10:35:27][listener.cluster_command] Cluster: <zigpy.zcl.clusters.general.Ota object at 0x7f91980358> ClusterId: 0x0019 command_id: 30 args: (1, [0, 4107, 279, 16779008, None])
[2020-10-29 12:44:23][INFO] : [00:17:88:01:09:10:35:27][listener.cluster_command] Use specific decode funtion
[2020-10-29 12:44:23][DEBUG] : [0xc619:11:0x0019] OTA query_next_image handler for 'Philips LCL001': field_control=0, manufacture_id=4107, image_type=279, current_file_version=16779008, hardware_version=None
[2020-10-29 12:44:23][DEBUG] : [0xc619:11:0x0019] No OTA image is available
[2020-10-29 12:44:23][DEBUG] : Sending Zigbee request with tsn 30 under 79 request id, data: b'191e0298'
[2020-10-29 12:44:23][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xc619>, ep: 11, profile: 0x0104, cluster_id: 0x0019, data: b'011e01000b10170100070001'
[2020-10-29 12:44:23][DEBUG] : Command Command.aps_data_request (19, 79, 0, <DeconzAddressEndpoint address_mode=2 address=0xC619 endpoint=11>, 260, 25, 1, b'\x19\x1e\x02\x98', 2, 0)
[2020-10-29 12:44:23][DEBUG] : Send: 0x12ad001a0013004f000219c60b04011900010400191e02980200
[2020-10-29 12:44:23][DEBUG] : Frame received: 0x12ad0009000200224f
[2020-10-29 12:44:23][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 79]
[2020-10-29 12:44:23][DEBUG] : Frame received: 0x0eae000700a600
[2020-10-29 12:44:23][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 12:44:23][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 12:44:23][DEBUG] : Send: 0x04ae0007000000
[2020-10-29 12:44:23][DEBUG] : Frame received: 0x04ae0013000c00224f0219c60b010000000000
[2020-10-29 12:44:23][DEBUG] : APS data confirm response for request with id 79: 00
[2020-10-29 12:44:23][DEBUG] : Request id: 0x4f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xc619 endpoint=11>, status: 0x00
[2020-10-29 12:44:50][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2020-10-29 12:44:50][DEBUG] : Send: 0x0baf000c0005002658020000
[2020-10-29 12:44:50][DEBUG] : Frame received: 0x0baf000800010026
[2020-10-29 12:44:50][DEBUG] : Write parameter watchdog_ttl: SUCCESS