[2020-10-29 11:22:35][DEBUG] : [ApplicationHandler.prepare] Json arg : {'duration': 180}
[2020-10-29 11:22:35][DEBUG] : Sending Zigbee broadcast with tsn 81 under 82 request id, data: b'51b400'
[2020-10-29 11:22:35][DEBUG] : Command Command.aps_data_request (17, 82, 0, <DeconzAddressEndpoint address_mode=1 address=65532 endpoint=None>, 0, 54, 0, b'Q\xb4\x00', 2, 0)
[2020-10-29 11:22:35][DEBUG] : Send: 0x12aa0018001100520001fcff0000360000030051b4000200
[2020-10-29 11:22:35][DEBUG] : Frame received: 0x12aa00090002002252
[2020-10-29 11:22:35][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 82]
[2020-10-29 11:22:35][DEBUG] : Frame received: 0x0eab000700aa00
[2020-10-29 11:22:35][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:35][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:35][DEBUG] : Send: 0x17ab000800010001
[2020-10-29 11:22:35][DEBUG] : Frame received: 0x17ab0021001a002201fcff010200000000003600030051b40000afdfd0cb010000
[2020-10-29 11:22:35][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'Q\xb4\x00', 0, 175, 223, 208, 203, 1, 0, 0]
[2020-10-29 11:22:35][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [180, <Bool.false: 0>]
[2020-10-29 11:22:35][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0036, data: b'51b400'
[2020-10-29 11:22:35][DEBUG] : Frame received: 0x0eac000700a600
[2020-10-29 11:22:35][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:35][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:35][DEBUG] : Send: 0x04ac0007000000
[2020-10-29 11:22:35][DEBUG] : Frame received: 0x04ac0012000b00225201fcff000000000000
[2020-10-29 11:22:35][DEBUG] : APS data confirm response for request with id 82: 00
[2020-10-29 11:22:35][DEBUG] : Request id: 0x52 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.GROUP address=0xfffc endpoint=None>, status: 0x00
[2020-10-29 11:22:35][DEBUG] : Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xb4')
[2020-10-29 11:22:35][DEBUG] : Send: 0x0bad000900020021b4
[2020-10-29 11:22:35][DEBUG] : Frame received: 0x0bad000800010021
[2020-10-29 11:22:35][DEBUG] : Write parameter permit_join: SUCCESS
[2020-10-29 11:22:35][INFO] : 200 PUT /application/include (127.0.0.1) 69.89ms
[2020-10-29 11:22:38][DEBUG] : Frame received: 0x0eae000700aa00
[2020-10-29 11:22:38][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:38][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:38][DEBUG] : Send: 0x17ae000800010001
[2020-10-29 11:22:38][DEBUG] : Frame received: 0x17ae0024001d0022015a230002e30b010401050006000118055a230000afffd0cb0100d8
[2020-10-29 11:22:38][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0x235a>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, 1, 260, 5, b'\x01\x18\x05Z#\x00', 0, 175, 255, 208, 203, 1, 0, -40]
[2020-10-29 11:22:38][DEBUG] : [0x0be3:1:0x0005] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=24 command_id=5>
[2020-10-29 11:22:38][DEBUG] : [0x0be3:1:0x0005] ZCL request 0x0005: [9050, 0]
[2020-10-29 11:22:38][DEBUG] : [0x0be3:1:0x0005] No handler for cluster command 5
[2020-10-29 11:22:38][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Cluster: <zigpy.zcl.clusters.general.Scenes object at 0x7fab107390> ClusterId: 0x0005 command_id: 24 args: (5, [9050, 0])
[2020-10-29 11:22:38][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Use specific decode funtion
[2020-10-29 11:22:38][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, ep: 1, profile: 0x0104, cluster_id: 0x0005, data: b'0118055a2300'
[2020-10-29 11:22:38][DEBUG] : Frame received: 0x0eaf000700aa00
[2020-10-29 11:22:38][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:38][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:38][DEBUG] : Send: 0x17af000800010001
[2020-10-29 11:22:38][DEBUG] : Frame received: 0x17af002b002400220200000102e30b02040100fc0d001d0b101700010000300021000000afffbdcb0100d5
[2020-10-29 11:22:38][DEBUG] : APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, 2, 260, 64512, b'\x1d\x0b\x10\x17\x00\x01\x00\x000\x00!\x00\x00', 0, 175, 255, 189, 203, 1, 0, -43]
[2020-10-29 11:22:38][DEBUG] : [0x0be3:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=23 command_id=0>
[2020-10-29 11:22:38][DEBUG] : [0x0be3:2:0xfc00] ZCL request 0x0000: [1, 3145728, 0, 33, 0, 0]
[2020-10-29 11:22:38][DEBUG] : PhilipsRemoteCluster - handle_cluster_request tsn: [23] command id: 0 - args: [[1, 3145728, 0, 33, 0, 0]]
[2020-10-29 11:22:38][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Cluster: <zhaquirks.philips.PhilipsRemoteCluster object at 0x7fab1075c0> ClusterId: 0xfc00 command_id: 23 args: (0, [1, 3145728, 0, 33, 0, 0])
[2020-10-29 11:22:38][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b1017000100003000210000'
[2020-10-29 11:22:38][DEBUG] : Frame received: 0x0eb0000700aa00
[2020-10-29 11:22:38][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:38][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:38][DEBUG] : Send: 0x17b0000800010001
[2020-10-29 11:22:38][DEBUG] : Frame received: 0x17b0002b002400220200000102e30b02040100fc0d001d0b101900010000300221000000afffd2cb0100d5
[2020-10-29 11:22:38][DEBUG] : APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, 2, 260, 64512, b'\x1d\x0b\x10\x19\x00\x01\x00\x000\x02!\x00\x00', 0, 175, 255, 210, 203, 1, 0, -43]
[2020-10-29 11:22:38][DEBUG] : [0x0be3:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=25 command_id=0>
[2020-10-29 11:22:38][DEBUG] : [0x0be3:2:0xfc00] ZCL request 0x0000: [1, 3145728, 2, 33, 0, 0]
[2020-10-29 11:22:38][DEBUG] : PhilipsRemoteCluster - handle_cluster_request tsn: [25] command id: 0 - args: [[1, 3145728, 2, 33, 0, 0]]
[2020-10-29 11:22:38][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Cluster: <zhaquirks.philips.PhilipsRemoteCluster object at 0x7fab1075c0> ClusterId: 0xfc00 command_id: 25 args: (0, [1, 3145728, 2, 33, 0, 0])
[2020-10-29 11:22:38][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b1019000100003002210000'
[2020-10-29 11:22:38][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:04:ac:62:94': {'1': {'5': {'cmd': {'5': {'value': 90500, 'cluster_name': 'Scenes'}}}}, '2': {'64512': {'cmd': {'0': {'value': '0', 'cluster_name': 'PhilipsRemoteCluster'}, '1': {'value': '1', 'cluster_name': 'PhilipsRemoteCluster'}}}}}}}
[2020-10-29 11:22:38][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:38][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:38][DEBUG] : PhilipsRemoteCluster - send_press_event click_count: [1]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x1cb10012000b000350aece0401881700ffe5
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eb2000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17b1000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17b1002a0023002202fdff0002078600000013000c0000078650aece04018817008e00afffd2cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 19, b'\x00\x07\x86P\xae\xce\x04\x01\x88\x17\x00\x8e', 0, 175, 255, 210, 203, 1, 0, -27]
[2020-10-29 11:22:40][INFO] : New device joined: 0x8607, 00:17:88:01:04:ce:ae:50
[2020-10-29 11:22:40][INFO] : Device 0x8607 (00:17:88:01:04:ce:ae:50) joined the network
[2020-10-29 11:22:40][INFO] : [00:17:88:01:04:ce:ae:50][listener.device_joined]
[2020-10-29 11:22:40][DEBUG] : Send to jeedom :  {'device_joined': '00:17:88:01:04:ce:ae:50'}
[2020-10-29 11:22:40][DEBUG] : [0x8607:zdo] ZDO request ZDOCmd.Device_annce: [0x8607, 00:17:88:01:04:ce:ae:50, 142]
[2020-10-29 11:22:40][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:40][INFO] : [0x8607] Requesting 'Node Descriptor'
[2020-10-29 11:22:40][DEBUG] : Tries remaining: 2
[2020-10-29 11:22:40][DEBUG] : [0x8607] Extending timeout for 0x53 request
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 83 under 84 request id, data: b'530786'
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'00078650aece04018817008e'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (18, 84, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'S\x07\x86', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12b20019001200540002078600000002000003005307860200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12b200090002002254
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 84]
[2020-10-29 11:22:40][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eb3000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04b30007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04b30013000c00225402078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 84: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x54 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eb4000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17b4000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17b4002f0028002202000000020786000000028011005300078601408e0b10472d0000002d000000afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32770, b'S\x00\x07\x86\x01@\x8e\x0b\x10G-\x00\x00\x00-\x00\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8002, data: b'5300078601408e0b10472d0000002d0000'
[2020-10-29 11:22:40][INFO] : [0x8607] 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 11:22:40][INFO] : [0x8607] Discovering endpoints
[2020-10-29 11:22:40][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 85 under 86 request id, data: b'550786'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (18, 86, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Active_EP_req: 0x0005>, 0, b'U\x07\x86', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12b50019001200560002078600000005000003005507860200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12b500090002002256
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 86]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eb6000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04b60007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04b60013000c00225602078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 86: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x56 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eb7000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17b7000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17b70025001e0022020000000207860000000580070055000786020bf200afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32773, b'U\x00\x07\x86\x02\x0b\xf2', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8005, data: b'55000786020bf2'
[2020-10-29 11:22:40][INFO] : [0x8607] Discovered endpoints: [11, 242]
[2020-10-29 11:22:40][INFO] : [0x8607:11] Discovering endpoint information
[2020-10-29 11:22:40][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 87 under 88 request id, data: b'5707860b'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (19, 88, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, b'W\x07\x86\x0b', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12b8001a001300580002078600000004000004005707860b0200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12b800090002002258
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 88]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eb9000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04b90007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04b90013000c00225802078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 88: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x58 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eba000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17ba000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17ba003f003800220200000002078600000004802100570007861c0b5ec0100202090000030004000500060008000003001001fc01190000afffd4cb0100e5
[2020-10-29 11:22:40][DEBUG] : APS data indication response: [56, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, 0, 0, 32772, b'W\x00\x07\x86\x1c\x0b^\xc0\x10\x02\x02	\x00\x00\x03\x00\x04\x00\x05\x00\x06\x00\x08\x00\x00\x03\x00\x10\x01\xfc\x01\x19\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8004, data: b'570007861c0b5ec0100202090000030004000500060008000003001001fc011900'
[2020-10-29 11:22:40][INFO] : [0x8607: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, 64513], output_clusters=[25])
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 89 under 90 request id, data: b'00590004000500'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (22, 90, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 0, 1, b'\x00Y\x00\x04\x00\x05\x00', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12bb001d0016005a000207860b04010000010700005900040005000200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12bb0009000200225a
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 90]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ebc000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04bc0007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04bc0013000c00225a0207860b010000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 90: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x5a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ebd000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17bd000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17bd003800310022020000010207860b040100001a0018590104000042075068696c69707305000042064c435430313500afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 11, 260, 0, b'\x18Y\x01\x04\x00\x00B\x07Philips\x05\x00\x00B\x06LCT015', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : [0x8607: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=89 command_id=Command.Read_Attributes_rsp>
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'18590104000042075068696c69707305000042064c4354303135'
[2020-10-29 11:22:40][DEBUG] : [0x8607:11] Manufacturer: Philips
[2020-10-29 11:22:40][DEBUG] : [0x8607:11] Model: LCT015
[2020-10-29 11:22:40][INFO] : [0x8607:242] Discovering endpoint information
[2020-10-29 11:22:40][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 91 under 92 request id, data: b'5b0786f2'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (19, 92, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, b'[\x07\x86\xf2', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12be001a0013005c0002078600000004000004005b0786f20200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12be0009000200225c
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 92]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ebf000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04bf0007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04bf0013000c00225c02078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 92: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x5c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ec0000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17c0000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17c0002f0028002202000000020786000000048011005b0007860cf2e0a161000001210001210000afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32772, b'[\x00\x07\x86\x0c\xf2\xe0\xa1a\x00\x00\x01!\x00\x01!\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8004, data: b'5b0007860cf2e0a1610000012100012100'
[2020-10-29 11:22:40][INFO] : [0x8607:242] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=242, profile=41440, device_type=97, device_version=0, input_clusters=[33], output_clusters=[33])
[2020-10-29 11:22:40][DEBUG] : Checking quirks for Philips LCT015 (00:17:88:01:04:ce:ae:50)
[2020-10-29 11:22:40][DEBUG] : Considering <class 'zhaquirks.philips.zllextendedcolorlight.ZLLExtendedColorLight'>
[2020-10-29 11:22:40][DEBUG] : Found custom device replacement for 00:17:88:01:04:ce:ae:50: <class 'zhaquirks.philips.zllextendedcolorlight.ZLLExtendedColorLight'>
[2020-10-29 11:22:40][INFO] : [00:17:88:01:04:ce:ae:50][listener.device_initialized] new=True
[2020-10-29 11:22:40][DEBUG] : Send to jeedom :  {'device_initialized': '00:17:88:01:04:ce:ae:50'}
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin device initialize
[2020-10-29 11:22:40][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin configuration of input cluster 'basic', is_server 'True'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bind input cluster 'basic'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 93 under 94 request id, data: b'5d50aece04018817000b00000380a105ffff2e210001'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (37, 94, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b']P\xae\xce\x04\x01\x88\x17\x00\x0b\x00\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12c1002c0025005e0002078600000021000016005d50aece04018817000b00000380a105ffff2e2100010200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12c10009000200225e
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 94]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ec2000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04c20007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04c20013000c00225e02078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 94: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x5e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ec3000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17c3000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17c300200019002202000000020786000000218002005d0000afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32801, b']\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'5d00'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bound 'basic' input cluster
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End configuration of input cluster 'basic'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin configuration of input cluster 'scenes', is_server 'True'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bind input cluster 'scenes'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 95 under 96 request id, data: b'5f50aece04018817000b05000380a105ffff2e210001'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (37, 96, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'_P\xae\xce\x04\x01\x88\x17\x00\x0b\x05\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12c4002c002500600002078600000021000016005f50aece04018817000b05000380a105ffff2e2100010200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12c400090002002260
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 96]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ec5000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04c50007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04c50013000c00226002078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 96: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x60 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ec6000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17c6000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17c600200019002202000000020786000000218002005f8c00afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32801, b'_\x8c', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'5f8c'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bound 'scenes' input cluster
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End configuration of input cluster 'scenes'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin configuration of input cluster 'on_off', is_server 'True'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bind input cluster 'on_off'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 97 under 98 request id, data: b'6150aece04018817000b06000380a105ffff2e210001'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (37, 98, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'aP\xae\xce\x04\x01\x88\x17\x00\x0b\x06\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12c7002c002500620002078600000021000016006150aece04018817000b06000380a105ffff2e2100010200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12c700090002002262
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 98]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ec8000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04c80007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04c80013000c00226202078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 98: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x62 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ec9000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17c9000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17c90020001900220200000002078600000021800200618c00afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32801, b'a\x8c', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'618c'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bound 'on_off' input cluster
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] This input cluster have REPORT_CONFIG, we need to configure it
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Reporting 'on_off' attr on 'on_off' input cluster: 0/900/1: For: '00:17:88:01:04:ce:ae:50'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 99 under 100 request id, data: b'0063060000001000008403'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (26, 100, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 6, 1, b'\x00c\x06\x00\x00\x00\x10\x00\x00\x84\x03', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12ca0021001a0064000207860b04010600010b0000630600000010000084030200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12ca00090002002264
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 100]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ecb000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04cb0007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04cb0013000c0022640207860b010000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 100: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x64 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ecc000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17cc000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17cc0022001b0022020000010207860b0401060004001863070000afffd4cb0100e5
[2020-10-29 11:22:40][DEBUG] : APS data indication response: [27, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, 11, 260, 6, b'\x18c\x07\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : [0x8607: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=99 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0006, data: b'18630700'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End configuration of input cluster 'on_off'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin configuration of input cluster 'level', is_server 'True'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bind input cluster 'level'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 101 under 102 request id, data: b'6550aece04018817000b08000380a105ffff2e210001'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (37, 102, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'eP\xae\xce\x04\x01\x88\x17\x00\x0b\x08\x00\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12cd002c002500660002078600000021000016006550aece04018817000b08000380a105ffff2e2100010200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12cd00090002002266
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 102]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ece000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04ce0007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04ce0013000c00226602078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 102: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x66 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ecf000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17cf000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17cf0020001900220200000002078600000021800200658c00afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32801, b'e\x8c', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'658c'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bound 'level' input cluster
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] This input cluster have REPORT_CONFIG, we need to configure it
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Reporting 'current_level' attr on 'level' input cluster: 1/900/1: For: '00:17:88:01:04:ce:ae:50'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 103 under 104 request id, data: b'006706000000200100840301'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (27, 104, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 8, 1, b'\x00g\x06\x00\x00\x00 \x01\x00\x84\x03\x01', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12d00022001b0068000207860b04010800010c000067060000002001008403010200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12d000090002002268
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 104]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ed1000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04d10007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04d10013000c0022680207860b010000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 104: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x68 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ed2000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17d2000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17d20025001e0022020000010207860b0401080007001867078c00000000afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 11, 260, 8, b'\x18g\x07\x8c\x00\x00\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : [0x8607: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=103 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0008, data: b'1867078c000000'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End configuration of input cluster 'level'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin configuration of input cluster 'lightlink', is_server 'True'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bind input cluster 'lightlink'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 105 under 106 request id, data: b'6950aece04018817000b00100380a105ffff2e210001'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (37, 106, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'iP\xae\xce\x04\x01\x88\x17\x00\x0b\x00\x10\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12d3002c0025006a0002078600000021000016006950aece04018817000b00100380a105ffff2e2100010200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12d30009000200226a
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 106]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ed4000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04d40007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04d40013000c00226a02078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 106: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x6a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ed5000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17d5000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17d50020001900220200000002078600000021800200698c00afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32801, b'i\x8c', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'698c'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bound 'lightlink' input cluster
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End configuration of input cluster 'lightlink'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin configuration of input cluster 'light_color', is_server 'True'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bind input cluster 'light_color'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 107 under 108 request id, data: b'6b50aece04018817000b00030380a105ffff2e210001'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (37, 108, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'kP\xae\xce\x04\x01\x88\x17\x00\x0b\x00\x03\x03\x80\xa1\x05\xff\xff.!\x00\x01', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12d6002c0025006c0002078600000021000016006b50aece04018817000b00030380a105ffff2e2100010200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12d60009000200226c
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 108]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ed7000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04d70007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04d70013000c00226c02078600000000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 108: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x6c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=0>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ed8000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17d8000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17d800200019002202000000020786000000218002006b8c00afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 0, 0, 32801, b'k\x8c', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'6b8c'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Bound 'light_color' input cluster
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] This input cluster have REPORT_CONFIG, we need to configure it
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Reporting 'current_x' attr on 'light_color' input cluster: 30/900/1: For: '00:17:88:01:04:ce:ae:50'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 109 under 110 request id, data: b'006d06000300211e0084030100'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (28, 110, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 768, 1, b'\x00m\x06\x00\x03\x00!\x1e\x00\x84\x03\x01\x00', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12d90023001c006e000207860b04010003010d00006d06000300211e00840301000200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12d90009000200226e
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 110]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0eda000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04da0007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04da0013000c00226e0207860b010000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 110: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x6e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0edb000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17db000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17db0025001e0022020000010207860b040100030700186d078c00030000afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 11, 260, 768, b'\x18m\x07\x8c\x00\x03\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : [0x8607: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=109 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'186d078c000300'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Reporting 'current_y' attr on 'light_color' input cluster: 30/900/1: For: '00:17:88:01:04:ce:ae:50'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 111 under 112 request id, data: b'006f06000400211e0084030100'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (28, 112, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 768, 1, b'\x00o\x06\x00\x04\x00!\x1e\x00\x84\x03\x01\x00', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12dc0023001c0070000207860b04010003010d00006f06000400211e00840301000200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12dc00090002002270
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 112]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0edd000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04dd0007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04dd0013000c0022700207860b010000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 112: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x70 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ede000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17de000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17de0025001e0022020000010207860b040100030700186f078c00040000afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 11, 260, 768, b'\x18o\x07\x8c\x00\x04\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : [0x8607: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=111 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'186f078c000400'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Reporting 'color_temperature' attr on 'light_color' input cluster: 30/900/1: For: '00:17:88:01:04:ce:ae:50'
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 113 under 114 request id, data: b'007106000700211e0084030100'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (28, 114, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 768, 1, b'\x00q\x06\x00\x07\x00!\x1e\x00\x84\x03\x01\x00', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12df0023001c0072000207860b04010003010d00007106000700211e00840301000200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12df00090002002272
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 114]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ee0000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04e00007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04e00013000c0022720207860b010000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 114: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x72 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ee1000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17e1000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17e10025001e0022020000010207860b0401000307001871078c00070000afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 11, 260, 768, b'\x18q\x07\x8c\x00\x07\x00', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : [0x8607: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=113 command_id=Command.Configure_Reporting_rsp>
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'1871078c000700'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End configuration of input cluster 'light_color'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] Begin configuration of input cluster 'manufacturer_specific', is_server 'True'
[2020-10-29 11:22:40][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End configuration of input cluster 'manufacturer_specific'
[2020-10-29 11:22:40][WARNING] : [00:17:88:01:04:ce:ae:50][zdevices.get_basic_info] Begin get basic info from device
[2020-10-29 11:22:40][WARNING] : [00:17:88:01:04:ce:ae:50][zdevices.get_basic_info] End point found
[2020-10-29 11:22:40][DEBUG] : Sending Zigbee request with tsn 115 under 116 request id, data: b'00730004000500'
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_request (22, 116, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 0, 1, b'\x00s\x00\x04\x00\x05\x00', 2, 0)
[2020-10-29 11:22:40][DEBUG] : Send: 0x12e2001d00160074000207860b04010000010700007300040005000200
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x12e200090002002274
[2020-10-29 11:22:40][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 116]
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ee3000700a600
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:40][DEBUG] : Send: 0x04e30007000000
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x04e30013000c0022740207860b010000000000
[2020-10-29 11:22:40][DEBUG] : APS data confirm response for request with id 116: 00
[2020-10-29 11:22:40][DEBUG] : Request id: 0x74 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x0ee4000700aa00
[2020-10-29 11:22:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:40][DEBUG] : Send: 0x17e4000800010001
[2020-10-29 11:22:40][DEBUG] : Frame received: 0x17e4003800310022020000010207860b040100001a0018730104000042075068696c69707305000042064c435430313500afffd4cb0100e5
[2020-10-29 11:22:40][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=0x8607>, 11, 260, 0, b'\x18s\x01\x04\x00\x00B\x07Philips\x05\x00\x00B\x06LCT015', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:40][DEBUG] : [0x8607: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=115 command_id=Command.Read_Attributes_rsp>
[2020-10-29 11:22:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'18730104000042075068696c69707305000042064c4354303135'
[2020-10-29 11:22:40][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 4=Philips on cluster 0
[2020-10-29 11:22:41][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 5=LCT015 on cluster 0
[2020-10-29 11:22:41][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:04:ce:ae:50': {'11': {'0': {'4': {'value': 'Philips', 'cluster_name': 'Basic'}, '5': {'value': 'LCT015', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 11:22:41][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:41][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:41][DEBUG] : Frame received: 0x1ce5000c00050002a603ffd0
[2020-10-29 11:22:42][DEBUG] : Sending Zigbee request with tsn 117 under 118 request id, data: b'0075000000010002000300'
[2020-10-29 11:22:42][DEBUG] : Command Command.aps_data_request (26, 118, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 0, 1, b'\x00u\x00\x00\x00\x01\x00\x02\x00\x03\x00', 2, 0)
[2020-10-29 11:22:42][DEBUG] : Send: 0x12e50021001a0076000207860b04010000010b0000750000000100020003000200
[2020-10-29 11:22:42][DEBUG] : Frame received: 0x12e500090002002276
[2020-10-29 11:22:42][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 118]
[2020-10-29 11:22:42][DEBUG] : Frame received: 0x0ee6000700a600
[2020-10-29 11:22:42][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:42][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:42][DEBUG] : Send: 0x04e60007000000
[2020-10-29 11:22:42][DEBUG] : Frame received: 0x04e60013000c0022760207860b010000000000
[2020-10-29 11:22:42][DEBUG] : APS data confirm response for request with id 118: 00
[2020-10-29 11:22:42][DEBUG] : Request id: 0x76 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:42][DEBUG] : Frame received: 0x0ee7000700aa00
[2020-10-29 11:22:42][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:42][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:42][DEBUG] : Send: 0x17e7000800010001
[2020-10-29 11:22:42][DEBUG] : Frame received: 0x17e70035002e0022020000010207860b040100001700187501000000200101000020020200002001030000200100afffd4cb0100e5
[2020-10-29 11:22:42][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=0x8607>, 11, 260, 0, b'\x18u\x01\x00\x00\x00 \x01\x01\x00\x00 \x02\x02\x00\x00 \x01\x03\x00\x00 \x01', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:42][DEBUG] : [0x8607: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=117 command_id=Command.Read_Attributes_rsp>
[2020-10-29 11:22:42][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'1875010000002001010000200202000020010300002001'
[2020-10-29 11:22:42][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 0=1 on cluster 0
[2020-10-29 11:22:42][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 1=2 on cluster 0
[2020-10-29 11:22:42][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 2=1 on cluster 0
[2020-10-29 11:22:42][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 3=1 on cluster 0
[2020-10-29 11:22:42][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:04:ce:ae:50': {'11': {'0': {'0': {'value': '1', 'cluster_name': 'Basic'}, '1': {'value': '2', 'cluster_name': 'Basic'}, '2': {'value': '1', 'cluster_name': 'Basic'}, '3': {'value': '1', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 11:22:42][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:42][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:43][DEBUG] : Sending Zigbee request with tsn 119 under 120 request id, data: b'0077000700'
[2020-10-29 11:22:43][DEBUG] : Command Command.aps_data_request (20, 120, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 0, 1, b'\x00w\x00\x07\x00', 2, 0)
[2020-10-29 11:22:43][DEBUG] : Send: 0x12e8001b00140078000207860b0401000001050000770007000200
[2020-10-29 11:22:43][DEBUG] : Frame received: 0x12e800090002002278
[2020-10-29 11:22:43][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 120]
[2020-10-29 11:22:43][DEBUG] : Frame received: 0x0ee9000700a600
[2020-10-29 11:22:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:43][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:43][DEBUG] : Send: 0x04e90007000000
[2020-10-29 11:22:43][DEBUG] : Frame received: 0x04e90013000c0022780207860b010000000000
[2020-10-29 11:22:43][DEBUG] : APS data confirm response for request with id 120: 00
[2020-10-29 11:22:43][DEBUG] : Request id: 0x78 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:43][DEBUG] : Frame received: 0x0eea000700aa00
[2020-10-29 11:22:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:43][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:43][DEBUG] : Send: 0x17ea000800010001
[2020-10-29 11:22:43][DEBUG] : Frame received: 0x17ea0026001f0022020000010207860b040100000800187701070000300100afffd4cb0100e5
[2020-10-29 11:22:43][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=0x8607>, 11, 260, 0, b'\x18w\x01\x07\x00\x000\x01', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:43][DEBUG] : [0x8607: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=119 command_id=Command.Read_Attributes_rsp>
[2020-10-29 11:22:43][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'1877010700003001'
[2020-10-29 11:22:43][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 7=PowerSource.Mains_single_phase on cluster 0
[2020-10-29 11:22:43][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:04:ce:ae:50': {'11': {'0': {'7': {'value': 'PowerSource.Mains_single_phase', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 11:22:43][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:43][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:44][DEBUG] : Sending Zigbee request with tsn 121 under 122 request id, data: b'00790006000040'
[2020-10-29 11:22:44][DEBUG] : Command Command.aps_data_request (22, 122, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 0, 1, b'\x00y\x00\x06\x00\x00@', 2, 0)
[2020-10-29 11:22:44][DEBUG] : Send: 0x12eb001d0016007a000207860b04010000010700007900060000400200
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x12eb0009000200227a
[2020-10-29 11:22:44][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 122]
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x0eec000700a600
[2020-10-29 11:22:44][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:44][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:44][DEBUG] : Send: 0x04ec0007000000
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x04ec0013000c00227a0207860b010000000000
[2020-10-29 11:22:44][DEBUG] : APS data confirm response for request with id 122: 00
[2020-10-29 11:22:44][DEBUG] : Request id: 0x7a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x0eed000700aa00
[2020-10-29 11:22:44][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:44][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:44][DEBUG] : Send: 0x17ed000800010001
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x17ed004000390022020000010207860b04010000220018790106000042083230313730393038004000420d312e32392e305f72323131363900afffd4cb0100e5
[2020-10-29 11:22:44][DEBUG] : APS data indication response: [57, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, 11, 260, 0, b'\x18y\x01\x06\x00\x00B\x0820170908\x00@\x00B
1.29.0_r21169', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:44][DEBUG] : [0x8607: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=121 command_id=Command.Read_Attributes_rsp>
[2020-10-29 11:22:44][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0000, data: b'18790106000042083230313730393038004000420d312e32392e305f723231313639'
[2020-10-29 11:22:44][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 6=20170908 on cluster 0
[2020-10-29 11:22:44][INFO] : [00:17:88:01:04:ce:ae:50][listener.attribute_updated] Received an attribute update 16384=1.29.0_r21169 on cluster 0
[2020-10-29 11:22:44][WARNING] : [00:17:88:01:04:ce:ae:50][zdevices.get_basic_info] End get basic info from device
[2020-10-29 11:22:44][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] It's deconz key, force neightbors scan
[2020-10-29 11:22:44][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:44][DEBUG] : Sending Zigbee request with tsn 123 under 124 request id, data: b'7b00'
[2020-10-29 11:22:44][DEBUG] : Command Command.aps_data_request (17, 124, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'{\x00', 2, 0)
[2020-10-29 11:22:44][DEBUG] : Send: 0x12ee00180011007c0002000000000031000002007b000200
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x12ee0009000200227c
[2020-10-29 11:22:44][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 124]
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x0eef000700ae00
[2020-10-29 11:22:44][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:44][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:44][DEBUG] : Send: 0x17ef000800010001
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x17ef0039003200260200000002000000000031801b007b0009000180a105ffff2e2100cd4772feff57b41453bf250101fc00afffd4cb0100e5
[2020-10-29 11:22:44][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, 212, 203, 1, 0, -27]
[2020-10-29 11:22:44][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'7b0009000180a105ffff2e2100cd4772feff57b41453bf250101fc'
[2020-10-29 11:22:44][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:44][DEBUG] : Send: 0x04f00007000000
[2020-10-29 11:22:44][DEBUG] : Frame received: 0x04f00013000c00227c02000000000000000000
[2020-10-29 11:22:44][DEBUG] : APS data confirm response for request with id 124: 00
[2020-10-29 11:22:44][DEBUG] : Request id: 0x7c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:44][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 11:22:44][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:04:ce:ae:50': {'11': {'0': {'6': {'value': '20170908', 'cluster_name': 'Basic'}, '16384': {'value': '1.29.0_r21169', 'cluster_name': 'Basic'}}}}}}
[2020-10-29 11:22:44][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:44][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:45][DEBUG] : [0x0000] Querying next starting at 1
[2020-10-29 11:22:45][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:45][DEBUG] : Sending Zigbee request with tsn 125 under 126 request id, data: b'7d01'
[2020-10-29 11:22:45][DEBUG] : Command Command.aps_data_request (17, 126, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'}\x01', 2, 0)
[2020-10-29 11:22:45][DEBUG] : Send: 0x12f100180011007e0002000000000031000002007d010200
[2020-10-29 11:22:45][DEBUG] : Frame received: 0x12f10009000200227e
[2020-10-29 11:22:45][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 126]
[2020-10-29 11:22:45][DEBUG] : Frame received: 0x0ef2000700ae00
[2020-10-29 11:22:45][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:45][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:45][DEBUG] : Send: 0x17f2000800010001
[2020-10-29 11:22:45][DEBUG] : Frame received: 0x17f20039003200260200000002000000000031801b007d0009010180a105ffff2e2100a914960301881700f7e2250101ff00afffd4cb0100e5
[2020-10-29 11:22:45][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\xf7\xe2%\x01\x01\xff', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:45][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'7d0009010180a105ffff2e2100a914960301881700f7e2250101ff'
[2020-10-29 11:22:45][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:45][DEBUG] : Send: 0x04f30007000000
[2020-10-29 11:22:45][DEBUG] : Frame received: 0x04f30013000c00227e02000000000000000000
[2020-10-29 11:22:45][DEBUG] : APS data confirm response for request with id 126: 00
[2020-10-29 11:22:45][DEBUG] : Request id: 0x7e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:45][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=0xE2F7, packed=37, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=255)])
[2020-10-29 11:22:46][DEBUG] : [0x0000] Querying next starting at 2
[2020-10-29 11:22:46][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:46][DEBUG] : Sending Zigbee request with tsn 127 under 128 request id, data: b'7f02'
[2020-10-29 11:22:46][DEBUG] : Command Command.aps_data_request (17, 128, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x7f\x02', 2, 0)
[2020-10-29 11:22:46][DEBUG] : Send: 0x12f40018001100800002000000000031000002007f020200
[2020-10-29 11:22:46][DEBUG] : Frame received: 0x12f400090002002280
[2020-10-29 11:22:46][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 128]
[2020-10-29 11:22:46][DEBUG] : Frame received: 0x0ef5000700ae00
[2020-10-29 11:22:46][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:46][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:46][DEBUG] : Send: 0x17f5000800010001
[2020-10-29 11:22:46][DEBUG] : Frame received: 0x17f50039003200260200000002000000000031801b007f0009020180a105ffff2e2100273510090188170019c6250101fc00afffd4cb0100e5
[2020-10-29 11:22:46][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"\x7f\x00	\x02\x01\x80\xa1\x05\xff\xff.!\x00'5\x10	\x01\x88\x17\x00\x19\xc6%\x01\x01\xfc", 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:46][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'7f0009020180a105ffff2e2100273510090188170019c6250101fc'
[2020-10-29 11:22:46][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:46][DEBUG] : Send: 0x04f60007000000
[2020-10-29 11:22:46][DEBUG] : Frame received: 0x04f60013000c00228002000000000000000000
[2020-10-29 11:22:46][DEBUG] : APS data confirm response for request with id 128: 00
[2020-10-29 11:22:46][DEBUG] : Request id: 0x80 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:46][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 11:22:47][DEBUG] : Frame received: 0x0ef7000700aa00
[2020-10-29 11:22:47][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:47][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:47][DEBUG] : Send: 0x17f7000800010001
[2020-10-29 11:22:47][DEBUG] : Frame received: 0x17f70025001e002202000001021ad10204010604070008a60a0000180000afffd4cb0100d5
[2020-10-29 11:22:47][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=0xd11a>, 2, 260, 1030, b'\x08\xa6
\x00\x00\x18\x00', 0, 175, 255, 212, 203, 1, 0, -43]
[2020-10-29 11:22:47][DEBUG] : [0xd11a:2:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=166 command_id=Command.Report_Attributes>
[2020-10-29 11:22:47][DEBUG] : [0xd11a:2:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.0>)]]
[2020-10-29 11:22:47][INFO] : [00:17:88:01:02:00:5e:93][listener.general_command] Cluster: <zigpy.zcl.clusters.measurement.OccupancySensing object at 0x7fab13be80> ClusterId: 0x0406 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=166 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.0>)]],)
[2020-10-29 11:22:47][DEBUG] : [0xd11a:2:0x0406] Attribute report received: occupancy=0
[2020-10-29 11:22:47][INFO] : [00:17:88:01:02:00:5e:93][listener.attribute_updated] Received an attribute update 0=bitmap8.0 on cluster 1030
[2020-10-29 11:22:47][DEBUG] : Sending Zigbee request with tsn 166 under 129 request id, data: b'18a60b0a00'
[2020-10-29 11:22:47][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd11a>, ep: 2, profile: 0x0104, cluster_id: 0x0406, data: b'08a60a00001800'
[2020-10-29 11:22:47][DEBUG] : Command Command.aps_data_request (20, 129, 0, <DeconzAddressEndpoint address_mode=2 address=0xD11A endpoint=2>, 260, 1030, 1, b'\x18\xa6\x0b
\x00', 2, 0)
[2020-10-29 11:22:47][DEBUG] : Send: 0x12f8001b0014008100021ad1020401060401050018a60b0a000200
[2020-10-29 11:22:47][DEBUG] : Frame received: 0x12f800090002002281
[2020-10-29 11:22:47][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 129]
[2020-10-29 11:22:47][DEBUG] : Frame received: 0x0ef9000700a600
[2020-10-29 11:22:47][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:47][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:47][DEBUG] : Send: 0x04f90007000000
[2020-10-29 11:22:47][DEBUG] : Frame received: 0x04f90013000c002281021ad102010000000000
[2020-10-29 11:22:47][DEBUG] : APS data confirm response for request with id 129: 00
[2020-10-29 11:22:47][DEBUG] : Request id: 0x81 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xd11a endpoint=2>, status: 0x00
[2020-10-29 11:22:48][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:02:00:5e:93': {'2': {'1030': {'0': {'value': 'bitmap8.0', 'cluster_name': 'Occupancy Sensing'}}}}}}
[2020-10-29 11:22:48][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:48][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:48][DEBUG] : [0x0000] Querying next starting at 3
[2020-10-29 11:22:48][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:48][DEBUG] : Sending Zigbee request with tsn 130 under 131 request id, data: b'8203'
[2020-10-29 11:22:48][DEBUG] : Command Command.aps_data_request (17, 131, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x82\x03', 2, 0)
[2020-10-29 11:22:48][DEBUG] : Send: 0x12fa00180011008300020000000000310000020082030200
[2020-10-29 11:22:48][DEBUG] : Frame received: 0x12fa00090002002283
[2020-10-29 11:22:48][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 131]
[2020-10-29 11:22:48][DEBUG] : Frame received: 0x0efb000700ae00
[2020-10-29 11:22:48][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:48][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:48][DEBUG] : Send: 0x17fb000800010001
[2020-10-29 11:22:48][DEBUG] : Frame received: 0x17fb0039003200260200000002000000000031801b00820009030180a105ffff2e210072773bfeffd76b0807f0250101fc00afffd4cb0100d5
[2020-10-29 11:22:48][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'\x82\x00	\x03\x01\x80\xa1\x05\xff\xff.!\x00rw;\xfe\xff\xd7k\x08\x07\xf0%\x01\x01\xfc', 0, 175, 255, 212, 203, 1, 0, -43]
[2020-10-29 11:22:48][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'820009030180a105ffff2e210072773bfeffd76b0807f0250101fc'
[2020-10-29 11:22:48][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:48][DEBUG] : Send: 0x04fc0007000000
[2020-10-29 11:22:48][DEBUG] : Frame received: 0x04fc0013000c00228302000000000000000000
[2020-10-29 11:22:48][DEBUG] : APS data confirm response for request with id 131: 00
[2020-10-29 11:22:48][DEBUG] : Request id: 0x83 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:48][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 11:22:49][DEBUG] : [0x0000] Querying next starting at 4
[2020-10-29 11:22:49][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:49][DEBUG] : Sending Zigbee request with tsn 132 under 133 request id, data: b'8404'
[2020-10-29 11:22:49][DEBUG] : Command Command.aps_data_request (17, 133, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x84\x04', 2, 0)
[2020-10-29 11:22:49][DEBUG] : Send: 0x12fd00180011008500020000000000310000020084040200
[2020-10-29 11:22:49][DEBUG] : Frame received: 0x12fd00090002002285
[2020-10-29 11:22:49][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 133]
[2020-10-29 11:22:49][DEBUG] : Frame received: 0x0efe000700ae00
[2020-10-29 11:22:49][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:49][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:49][DEBUG] : Send: 0x17fe000800010001
[2020-10-29 11:22:49][DEBUG] : Frame received: 0x17fe0039003200260200000002000000000031801b00840009040180a105ffff2e2100ed8ae6080188170098a5250101fc00afdfd4cb010000
[2020-10-29 11:22:49][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'\x84\x00	\x04\x01\x80\xa1\x05\xff\xff.!\x00\xed\x8a\xe6\x08\x01\x88\x17\x00\x98\xa5%\x01\x01\xfc', 0, 175, 223, 212, 203, 1, 0, 0]
[2020-10-29 11:22:49][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'840009040180a105ffff2e2100ed8ae6080188170098a5250101fc'
[2020-10-29 11:22:49][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:49][DEBUG] : Send: 0x04ff0007000000
[2020-10-29 11:22:49][DEBUG] : Frame received: 0x04ff0013000c00228502000000000000000000
[2020-10-29 11:22:49][DEBUG] : APS data confirm response for request with id 133: 00
[2020-10-29 11:22:49][DEBUG] : Request id: 0x85 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:49][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 11:22:50][DEBUG] : [0x0000] Querying next starting at 5
[2020-10-29 11:22:50][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:50][DEBUG] : Sending Zigbee request with tsn 134 under 135 request id, data: b'8605'
[2020-10-29 11:22:50][DEBUG] : Command Command.aps_data_request (17, 135, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x86\x05', 2, 0)
[2020-10-29 11:22:50][DEBUG] : Send: 0x120100180011008700020000000000310000020086050200
[2020-10-29 11:22:50][DEBUG] : Frame received: 0x120100090002002287
[2020-10-29 11:22:50][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 135]
[2020-10-29 11:22:50][DEBUG] : Frame received: 0x0e02000700ae00
[2020-10-29 11:22:50][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:50][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:50][DEBUG] : Send: 0x1702000800010001
[2020-10-29 11:22:50][DEBUG] : Frame received: 0x17020039003200260200000002000000000031801b00860009050180a105ffff2e210093ca200201881700ff4d250101ff00af5ad4cb010000
[2020-10-29 11:22:50][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'\x86\x00	\x05\x01\x80\xa1\x05\xff\xff.!\x00\x93\xca \x02\x01\x88\x17\x00\xffM%\x01\x01\xff', 0, 175, 90, 212, 203, 1, 0, 0]
[2020-10-29 11:22:50][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'860009050180a105ffff2e210093ca200201881700ff4d250101ff'
[2020-10-29 11:22:50][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:50][DEBUG] : Send: 0x04030007000000
[2020-10-29 11:22:50][DEBUG] : Frame received: 0x04030013000c00228702000000000000000000
[2020-10-29 11:22:50][DEBUG] : APS data confirm response for request with id 135: 00
[2020-10-29 11:22:50][DEBUG] : Request id: 0x87 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:50][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 11:22:51][DEBUG] : [0x0000] Querying next starting at 6
[2020-10-29 11:22:51][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:51][DEBUG] : Sending Zigbee request with tsn 136 under 137 request id, data: b'8806'
[2020-10-29 11:22:51][DEBUG] : Command Command.aps_data_request (17, 137, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x88\x06', 2, 0)
[2020-10-29 11:22:51][DEBUG] : Send: 0x120400180011008900020000000000310000020088060200
[2020-10-29 11:22:51][DEBUG] : Frame received: 0x120400090002002289
[2020-10-29 11:22:51][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 137]
[2020-10-29 11:22:51][DEBUG] : Frame received: 0x0e05000700ae00
[2020-10-29 11:22:51][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:51][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:51][DEBUG] : Send: 0x1705000800010001
[2020-10-29 11:22:51][DEBUG] : Frame received: 0x17050039003200260200000002000000000031801b00880009060180a105ffff2e21001681c4feffe20a68a603120001ff00af5ad4cb010000
[2020-10-29 11:22:51][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'\x88\x00	\x06\x01\x80\xa1\x05\xff\xff.!\x00\x16\x81\xc4\xfe\xff\xe2
h\xa6\x03\x12\x00\x01\xff', 0, 175, 90, 212, 203, 1, 0, 0]
[2020-10-29 11:22:51][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'880009060180a105ffff2e21001681c4feffe20a68a603120001ff'
[2020-10-29 11:22:51][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:51][DEBUG] : Send: 0x04060007000000
[2020-10-29 11:22:51][DEBUG] : Frame received: 0x04060013000c00228902000000000000000000
[2020-10-29 11:22:51][DEBUG] : APS data confirm response for request with id 137: 00
[2020-10-29 11:22:51][DEBUG] : Request id: 0x89 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:51][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 11:22:52][DEBUG] : Frame received: 0x1c070012000b000350aece0401881700ffe5
[2020-10-29 11:22:52][DEBUG] : Frame received: 0x0e08000700aa00
[2020-10-29 11:22:52][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:52][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:52][DEBUG] : Send: 0x1707000800010001
[2020-10-29 11:22:52][DEBUG] : Frame received: 0x1707002a0023002202fdff0002078600000013000c0000078650aece04018817008e00afffd4cb0100e5
[2020-10-29 11:22:52][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=0x8607>, 0, 0, 19, b'\x00\x07\x86P\xae\xce\x04\x01\x88\x17\x00\x8e', 0, 175, 255, 212, 203, 1, 0, -27]
[2020-10-29 11:22:52][INFO] : New device joined: 0x8607, 00:17:88:01:04:ce:ae:50
[2020-10-29 11:22:52][INFO] : Device 0x8607 (00:17:88:01:04:ce:ae:50) joined the network
[2020-10-29 11:22:52][DEBUG] : Skip initialization for existing device 00:17:88:01:04:ce:ae:50
[2020-10-29 11:22:52][DEBUG] : [0x8607:zdo] ZDO request ZDOCmd.Device_annce: [0x8607, 00:17:88:01:04:ce:ae:50, 142]
[2020-10-29 11:22:52][DEBUG] : Sending Zigbee request with tsn 138 under 139 request id, data: b'018a0200'
[2020-10-29 11:22:52][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'00078650aece04018817008e'
[2020-10-29 11:22:52][DEBUG] : Command Command.aps_data_request (19, 139, 0, <DeconzAddressEndpoint address_mode=2 address=0x8607 endpoint=11>, 260, 4, 1, b'\x01\x8a\x02\x00', 2, 0)
[2020-10-29 11:22:52][DEBUG] : Send: 0x1208001a0013008b000207860b04010400010400018a02000200
[2020-10-29 11:22:52][DEBUG] : Frame received: 0x12080009000200228b
[2020-10-29 11:22:52][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 139]
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x0e09000700a600
[2020-10-29 11:22:53][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:53][DEBUG] : Send: 0x04090007000000
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x04090013000c00228b0207860b010000000000
[2020-10-29 11:22:53][DEBUG] : APS data confirm response for request with id 139: 00
[2020-10-29 11:22:53][DEBUG] : Request id: 0x8b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8607 endpoint=11>, status: 0x00
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x0e0a000700aa00
[2020-10-29 11:22:53][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:53][DEBUG] : Send: 0x170a000800010001
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x170a0023001c0022020000010207860b040104000500198a02190000afffedcb0100e5
[2020-10-29 11:22:53][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=0x8607>, 11, 260, 4, b'\x19\x8a\x02\x19\x00', 0, 175, 255, 237, 203, 1, 0, -27]
[2020-10-29 11:22:53][DEBUG] : [0x8607: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=138 command_id=2>
[2020-10-29 11:22:53][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8607>, ep: 11, profile: 0x0104, cluster_id: 0x0004, data: b'198a021900'
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x0e0b000700aa00
[2020-10-29 11:22:53][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:53][DEBUG] : Send: 0x170b000800010001
[2020-10-29 11:22:53][DEBUG] : [0x0000] Querying next starting at 7
[2020-10-29 11:22:53][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:53][DEBUG] : Sending Zigbee request with tsn 140 under 141 request id, data: b'8c07'
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x170b0024001d0022015a230002e30b01040105000600011b055a230000afffd4cb0100d8
[2020-10-29 11:22:53][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0x235a>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, 1, 260, 5, b'\x01\x1b\x05Z#\x00', 0, 175, 255, 212, 203, 1, 0, -40]
[2020-10-29 11:22:53][DEBUG] : [0x0be3:1:0x0005] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=27 command_id=5>
[2020-10-29 11:22:53][DEBUG] : [0x0be3:1:0x0005] ZCL request 0x0005: [9050, 0]
[2020-10-29 11:22:53][DEBUG] : [0x0be3:1:0x0005] No handler for cluster command 5
[2020-10-29 11:22:53][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Cluster: <zigpy.zcl.clusters.general.Scenes object at 0x7fab107390> ClusterId: 0x0005 command_id: 27 args: (5, [9050, 0])
[2020-10-29 11:22:53][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Use specific decode funtion
[2020-10-29 11:22:53][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, ep: 1, profile: 0x0104, cluster_id: 0x0005, data: b'011b055a2300'
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_request (17, 141, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x8c\x07', 2, 0)
[2020-10-29 11:22:53][DEBUG] : Send: 0x120c00180011008d0002000000000031000002008c070200
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x120c0009000200228d
[2020-10-29 11:22:53][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 141]
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x0e0d000700ae00
[2020-10-29 11:22:53][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:53][DEBUG] : Send: 0x170d000800010001
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x170d0039003200260200000002000000000031801b008c0009070180a105ffff2e210050aece04018817000786350101ff00afe6f3cb010000
[2020-10-29 11:22:53][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'\x8c\x00	\x07\x01\x80\xa1\x05\xff\xff.!\x00P\xae\xce\x04\x01\x88\x17\x00\x07\x865\x01\x01\xff', 0, 175, 230, 243, 203, 1, 0, 0]
[2020-10-29 11:22:53][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'8c0009070180a105ffff2e210050aece04018817000786350101ff'
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:53][DEBUG] : Send: 0x040e0007000000
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x040e0013000c00228d02000000000000000000
[2020-10-29 11:22:53][DEBUG] : APS data confirm response for request with id 141: 00
[2020-10-29 11:22:53][DEBUG] : Request id: 0x8d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:53][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=53, permit_joining=<PermitJoins.Accepting: 1>, depth=1, lqi=255)])
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x0e0f000700aa00
[2020-10-29 11:22:53][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:53][DEBUG] : Send: 0x170f000800010001
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x170f002b002400220200000102e30b02040100fc0d001d0b101a00010000300021000000affff3cb0100d3
[2020-10-29 11:22:53][DEBUG] : APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, 2, 260, 64512, b'\x1d\x0b\x10\x1a\x00\x01\x00\x000\x00!\x00\x00', 0, 175, 255, 243, 203, 1, 0, -45]
[2020-10-29 11:22:53][DEBUG] : [0x0be3:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=26 command_id=0>
[2020-10-29 11:22:53][DEBUG] : [0x0be3:2:0xfc00] ZCL request 0x0000: [1, 3145728, 0, 33, 0, 0]
[2020-10-29 11:22:53][DEBUG] : PhilipsRemoteCluster - handle_cluster_request tsn: [26] command id: 0 - args: [[1, 3145728, 0, 33, 0, 0]]
[2020-10-29 11:22:53][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Cluster: <zhaquirks.philips.PhilipsRemoteCluster object at 0x7fab1075c0> ClusterId: 0xfc00 command_id: 26 args: (0, [1, 3145728, 0, 33, 0, 0])
[2020-10-29 11:22:53][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b101a000100003000210000'
[2020-10-29 11:22:53][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:04:ac:62:94': {'1': {'5': {'cmd': {'5': {'value': 90500, 'cluster_name': 'Scenes'}}}}, '2': {'64512': {'cmd': {'0': {'value': '0', 'cluster_name': 'PhilipsRemoteCluster'}, '1': {'value': '1', 'cluster_name': 'PhilipsRemoteCluster'}}}}}}}
[2020-10-29 11:22:53][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x0e10000700aa00
[2020-10-29 11:22:53][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:22:53][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:53][DEBUG] : Send: 0x1710000800010001
[2020-10-29 11:22:53][DEBUG] : Frame received: 0x1710002b002400220200000102e30b02040100fc0d001d0b101c00010000300221000000affff4cb0100d3
[2020-10-29 11:22:53][DEBUG] : APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, 2, 260, 64512, b'\x1d\x0b\x10\x1c\x00\x01\x00\x000\x02!\x00\x00', 0, 175, 255, 244, 203, 1, 0, -45]
[2020-10-29 11:22:53][DEBUG] : [0x0be3:2:0xfc00] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4107 tsn=28 command_id=0>
[2020-10-29 11:22:53][DEBUG] : [0x0be3:2:0xfc00] ZCL request 0x0000: [1, 3145728, 2, 33, 0, 0]
[2020-10-29 11:22:53][DEBUG] : PhilipsRemoteCluster - handle_cluster_request tsn: [28] command id: 0 - args: [[1, 3145728, 2, 33, 0, 0]]
[2020-10-29 11:22:53][INFO] : [00:17:88:01:04:ac:62:94][listener.cluster_command] Cluster: <zhaquirks.philips.PhilipsRemoteCluster object at 0x7fab1075c0> ClusterId: 0xfc00 command_id: 28 args: (0, [1, 3145728, 2, 33, 0, 0])
[2020-10-29 11:22:53][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0be3>, ep: 2, profile: 0x0104, cluster_id: 0xfc00, data: b'1d0b101c000100003002210000'
[2020-10-29 11:22:53][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:53][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:04:ac:62:94': {'2': {'64512': {'cmd': {'0': {'value': '0', 'cluster_name': 'PhilipsRemoteCluster'}, '1': {'value': '1', 'cluster_name': 'PhilipsRemoteCluster'}}}}}}}
[2020-10-29 11:22:53][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:22:53][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:22:53][DEBUG] : PhilipsRemoteCluster - send_press_event click_count: [1]
[2020-10-29 11:22:54][DEBUG] : [0x0000] Querying next starting at 8
[2020-10-29 11:22:54][DEBUG] : Tries remaining: 3
[2020-10-29 11:22:54][DEBUG] : Sending Zigbee request with tsn 142 under 143 request id, data: b'8e08'
[2020-10-29 11:22:54][DEBUG] : Command Command.aps_data_request (17, 143, 0, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'\x8e\x08', 2, 0)
[2020-10-29 11:22:54][DEBUG] : Send: 0x121100180011008f0002000000000031000002008e080200
[2020-10-29 11:22:54][DEBUG] : Frame received: 0x12110009000200228f
[2020-10-29 11:22:54][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 143]
[2020-10-29 11:22:54][DEBUG] : Frame received: 0x0e12000700ae00
[2020-10-29 11:22:54][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2020-10-29 11:22:54][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:22:54][DEBUG] : Send: 0x1712000800010001
[2020-10-29 11:22:54][DEBUG] : Frame received: 0x17120039003200260200000002000000000031801b008e0009080180a105ffff2e2100e6cd2d0801881700074c250101fc00af5af4cb010000
[2020-10-29 11:22:54][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'\x8e\x00	\x08\x01\x80\xa1\x05\xff\xff.!\x00\xe6\xcd-\x08\x01\x88\x17\x00\x07L%\x01\x01\xfc', 0, 175, 90, 244, 203, 1, 0, 0]
[2020-10-29 11:22:54][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'8e0009080180a105ffff2e2100e6cd2d0801881700074c250101fc'
[2020-10-29 11:22:54][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:22:54][DEBUG] : Send: 0x04130007000000
[2020-10-29 11:22:54][DEBUG] : Frame received: 0x04130013000c00228f02000000000000000000
[2020-10-29 11:22:54][DEBUG] : APS data confirm response for request with id 143: 00
[2020-10-29 11:22:54][DEBUG] : Request id: 0x8f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2020-10-29 11:22:54][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 11:22:54][DEBUG] : [0x0000] Done scanning. Total 9 neighbours
[2020-10-29 11:22:54][DEBUG] : [00:17:88:01:04:ce:ae:50][zdevices.initialize] End device initialize
[2020-10-29 11:23:07][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2020-10-29 11:23:07][DEBUG] : Send: 0x0b14000c0005002658020000
[2020-10-29 11:23:07][DEBUG] : Frame received: 0x0b14000800010026
[2020-10-29 11:23:07][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2020-10-29 11:23:26][DEBUG] : Frame received: 0x0e15000700aa00
[2020-10-29 11:23:26][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:23:26][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:23:26][DEBUG] : Send: 0x1715000800010001
[2020-10-29 11:23:26][DEBUG] : Frame received: 0x171500290022002202fcff00021ad100000000000b003f80a105ffff2e2100000000affff6cb0100db
[2020-10-29 11:23:26][DEBUG] : APS data indication response: [34, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffc>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd11a>, 0, 0, 0, b'?\x80\xa1\x05\xff\xff.!\x00\x00\x00', 0, 175, 255, 246, 203, 1, 0, -37]
[2020-10-29 11:23:26][DEBUG] : [0xd11a:zdo] ZDO request ZDOCmd.NWK_addr_req: [00:21:2e:ff:ff:05:a1:80, 0, 0]
[2020-10-29 11:23:26][DEBUG] : Sending Zigbee request with tsn 63 under 144 request id, data: b'3f0080a105ffff2e210000000000'
[2020-10-29 11:23:26][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd11a>, ep: 0, profile: 0x0000, cluster_id: 0x0000, data: b'3f80a105ffff2e21000000'
[2020-10-29 11:23:26][DEBUG] : Command Command.aps_data_request (29, 144, 0, <DeconzAddressEndpoint address_mode=2 address=0xD11A endpoint=0>, 0, <ZDOCmd.NWK_addr_rsp: 0x8000>, 0, b'?\x00\x80\xa1\x05\xff\xff.!\x00\x00\x00\x00\x00', 2, 0)
[2020-10-29 11:23:26][DEBUG] : Send: 0x12160024001d009000021ad10000000080000e003f0080a105ffff2e2100000000000200
[2020-10-29 11:23:26][DEBUG] : Frame received: 0x121600090002002290
[2020-10-29 11:23:26][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 144]
[2020-10-29 11:23:26][DEBUG] : Frame received: 0x0e17000700a600
[2020-10-29 11:23:26][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:23:26][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:23:26][DEBUG] : Send: 0x04170007000000
[2020-10-29 11:23:26][DEBUG] : Frame received: 0x04170013000c002290021ad100000000000000
[2020-10-29 11:23:26][DEBUG] : APS data confirm response for request with id 144: 00
[2020-10-29 11:23:26][DEBUG] : Request id: 0x90 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xd11a endpoint=0>, status: 0x00
[2020-10-29 11:23:28][DEBUG] : Frame received: 0x0e18000700aa00
[2020-10-29 11:23:28][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2020-10-29 11:23:28][DEBUG] : Command Command.aps_data_indication (1, 1)
[2020-10-29 11:23:28][DEBUG] : Send: 0x1718000800010001
[2020-10-29 11:23:28][DEBUG] : Frame received: 0x17180026001f002202000001021ad10204010004080008a70a0000215c0700affff6cb0100da
[2020-10-29 11:23:28][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\xa7
\x00\x00!\\x07', 0, 175, 255, 246, 203, 1, 0, -38]
[2020-10-29 11:23:28][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=167 command_id=Command.Report_Attributes>
[2020-10-29 11:23:28][DEBUG] : [0xd11a:2:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=1884>)]]
[2020-10-29 11:23:28][INFO] : [00:17:88:01:02:00:5e:93][listener.general_command] Cluster: <zigpy.zcl.clusters.measurement.IlluminanceMeasurement object at 0x7fab13bef0> 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=167 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=1884>)]],)
[2020-10-29 11:23:28][DEBUG] : [0xd11a:2:0x0400] Attribute report received: measured_value=1884
[2020-10-29 11:23:28][INFO] : [00:17:88:01:02:00:5e:93][listener.attribute_updated] Received an attribute update 0=1884 on cluster 1024
[2020-10-29 11:23:28][DEBUG] : Sending Zigbee request with tsn 167 under 145 request id, data: b'18a70b0a00'
[2020-10-29 11:23:28][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd11a>, ep: 2, profile: 0x0104, cluster_id: 0x0400, data: b'08a70a0000215c07'
[2020-10-29 11:23:28][DEBUG] : Command Command.aps_data_request (20, 145, 0, <DeconzAddressEndpoint address_mode=2 address=0xD11A endpoint=2>, 260, 1024, 1, b'\x18\xa7\x0b
\x00', 2, 0)
[2020-10-29 11:23:28][DEBUG] : Send: 0x1219001b0014009100021ad1020401000401050018a70b0a000200
[2020-10-29 11:23:28][DEBUG] : Frame received: 0x121900090002002291
[2020-10-29 11:23:28][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 145]
[2020-10-29 11:23:28][DEBUG] : Frame received: 0x0e1a000700a600
[2020-10-29 11:23:28][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2020-10-29 11:23:28][DEBUG] : Command Command.aps_data_confirm (0,)
[2020-10-29 11:23:28][DEBUG] : Send: 0x041a0007000000
[2020-10-29 11:23:28][DEBUG] : Frame received: 0x041a0013000c002291021ad102010000000000
[2020-10-29 11:23:28][DEBUG] : APS data confirm response for request with id 145: 00
[2020-10-29 11:23:28][DEBUG] : Request id: 0x91 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xd11a endpoint=2>, status: 0x00
[2020-10-29 11:23:29][DEBUG] : Send to jeedom : {'devices': {'00:17:88:01:02:00:5e:93': {'2': {'1024': {'0': {'value': '1884', 'cluster_name': 'Illuminance Measurement'}}}}}}
[2020-10-29 11:23:29][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-10-29 11:23:29][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0
[2020-10-29 11:23:40][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-10-29 11:23:40][INFO] : 200 GET /device/all (127.0.0.1) 66.62ms
[2020-10-29 11:23:40][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=PVuv7hdPKHpcVt1JtdbYTgQS023xmWQd HTTP/1.1" 200 0