[2021-02-01 09:25:54][DEBUG] : APS data confirm response for request with id 8: d0 [2021-02-01 09:25:54][DEBUG] : Request id: 0x08 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:25:54][DEBUG] : Error while sending 8 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:25:54][DEBUG] : [0xa72a] Delivery error for seq # 0x07, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:25:54][DEBUG] : [0xa72a] Extending timeout for 0x09 request [2021-02-01 09:25:54][DEBUG] : Sending Zigbee request with tsn 9 under 10 request id, data: b'0009000500' [2021-02-01 09:25:54][DEBUG] : Command Command.aps_data_request (20, 10, 0, , 260, 0, 1, b'\x00 \x00\x05\x00', 2, 0) [2021-02-01 09:25:54][DEBUG] : Send: 0x1255001b0014000a00022aa7010401000001050000090005000200 [2021-02-01 09:25:54][DEBUG] : Frame received: 0x12550009000200220a [2021-02-01 09:25:54][DEBUG] : APS data request response: [2, , 10] [2021-02-01 09:25:57][DEBUG] : Frame received: 0x1c56000c00050002949effd0 [2021-02-01 09:26:01][DEBUG] : Frame received: 0x1c57000c00050002f584ffcf [2021-02-01 09:26:04][DEBUG] : Frame received: 0x0e58000700a600 [2021-02-01 09:26:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:04][DEBUG] : Send: 0x04560007000000 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x04560013000c00220a022aa70101d000000000 [2021-02-01 09:26:04][DEBUG] : APS data confirm response for request with id 10: d0 [2021-02-01 09:26:04][DEBUG] : Request id: 0x0a 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:26:04][DEBUG] : Error while sending 10 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:26:04][DEBUG] : [0xa72a] Delivery error for seq # 0x09, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:26:04][DEBUG] : [0xa72a:1] Manufacturer: None [2021-02-01 09:26:04][DEBUG] : [0xa72a:1] Model: None [2021-02-01 09:26:04][DEBUG] : Sending Zigbee request with tsn 11 under 12 request id, data: b'000b0004000500' [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_request (22, 12, 0, , 260, 0, 1, b'\x00\x0b\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:26:04][DEBUG] : Send: 0x1257001d0016000c0002739b0104010000010700000b00040005000200 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x12570009000200220c [2021-02-01 09:26:04][DEBUG] : APS data request response: [2, , 12] [2021-02-01 09:26:04][DEBUG] : Frame received: 0x0e58000700a600 [2021-02-01 09:26:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:04][DEBUG] : Send: 0x04580007000000 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x04580013000c00220c02739b01010000000000 [2021-02-01 09:26:04][DEBUG] : APS data confirm response for request with id 12: 00 [2021-02-01 09:26:04][DEBUG] : Request id: 0x0c 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x0e59000700aa00 [2021-02-01 09:26:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:04][DEBUG] : Send: 0x1759000800010001 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x17590027002000220200000102739b01040100000900180b0104008605008600afd765f60000bd [2021-02-01 09:26:04][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\x0b\x01\x04\x00\x86\x05\x00\x86', 0, 175, 215, 101, 246, 0, 0, -67] [2021-02-01 09:26:04][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=11 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:04][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'180b01040086050086' [2021-02-01 09:26:04][DEBUG] : Sending Zigbee request with tsn 13 under 14 request id, data: b'000d000400' [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_request (20, 14, 0, , 260, 0, 1, b'\x00 \x00\x04\x00', 2, 0) [2021-02-01 09:26:04][DEBUG] : Send: 0x125a001b0014000e0002739b0104010000010500000d0004000200 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x125a0009000200220e [2021-02-01 09:26:04][DEBUG] : APS data request response: [2, , 14] [2021-02-01 09:26:04][DEBUG] : Frame received: 0x0e5b000700a600 [2021-02-01 09:26:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:04][DEBUG] : Send: 0x045b0007000000 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x045b0013000c00220e02739b01010000000000 [2021-02-01 09:26:04][DEBUG] : APS data confirm response for request with id 14: 00 [2021-02-01 09:26:04][DEBUG] : Request id: 0x0e 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x0e5c000700aa00 [2021-02-01 09:26:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:04][DEBUG] : Send: 0x175c000800010001 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x175c0024001d00220200000102739b01040100000600180d0104008600afd765f60000bd [2021-02-01 09:26:04][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18 \x01\x04\x00\x86', 0, 175, 215, 101, 246, 0, 0, -67] [2021-02-01 09:26:04][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=13 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:04][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'180d01040086' [2021-02-01 09:26:04][DEBUG] : Sending Zigbee request with tsn 15 under 16 request id, data: b'000f000500' [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_request (20, 16, 0, , 260, 0, 1, b'\x00\x0f\x00\x05\x00', 2, 0) [2021-02-01 09:26:04][DEBUG] : Send: 0x125d001b001400100002739b0104010000010500000f0005000200 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x125d00090002002210 [2021-02-01 09:26:04][DEBUG] : APS data request response: [2, , 16] [2021-02-01 09:26:04][DEBUG] : Frame received: 0x0e5e000700a600 [2021-02-01 09:26:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:04][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:04][DEBUG] : Send: 0x045e0007000000 [2021-02-01 09:26:04][DEBUG] : Frame received: 0x045e0013000c00221002739b01010000000000 [2021-02-01 09:26:04][DEBUG] : APS data confirm response for request with id 16: 00 [2021-02-01 09:26:04][DEBUG] : Request id: 0x10 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:05][DEBUG] : Frame received: 0x0e5f000700aa00 [2021-02-01 09:26:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:05][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:05][DEBUG] : Send: 0x175f000800010001 [2021-02-01 09:26:05][DEBUG] : Frame received: 0x175f0024001d00220200000102739b01040100000600180f0105008600afd765f60000bd [2021-02-01 09:26:05][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\x0f\x01\x05\x00\x86', 0, 175, 215, 101, 246, 0, 0, -67] [2021-02-01 09:26:05][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=15 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:05][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'180f01050086' [2021-02-01 09:26:05][DEBUG] : [0x9b73:1] Manufacturer: None [2021-02-01 09:26:05][DEBUG] : [0x9b73:1] Model: None [2021-02-01 09:26:05][DEBUG] : [0x346d] Extending timeout for 0x11 request [2021-02-01 09:26:05][DEBUG] : Sending Zigbee request with tsn 17 under 18 request id, data: b'00110004000500' [2021-02-01 09:26:05][DEBUG] : Command Command.aps_data_request (22, 18, 0, , 260, 0, 1, b'\x00\x11\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:26:05][DEBUG] : Send: 0x1260001d0016001200026d340104010000010700001100040005000200 [2021-02-01 09:26:05][DEBUG] : Frame received: 0x126000090002002212 [2021-02-01 09:26:05][DEBUG] : APS data request response: [2, , 18] [2021-02-01 09:26:05][DEBUG] : Frame received: 0x0e61000700a600 [2021-02-01 09:26:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:05][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:05][DEBUG] : Send: 0x04610007000000 [2021-02-01 09:26:05][DEBUG] : Frame received: 0x04610013000c002212026d3401010000000000 [2021-02-01 09:26:05][DEBUG] : APS data confirm response for request with id 18: 00 [2021-02-01 09:26:05][DEBUG] : Request id: 0x12 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:07][DEBUG] : [ApplicationHandler.prepare] Json arg : None [2021-02-01 09:26:07][INFO] : 200 GET /application/info (127.0.0.1) 1.64ms [2021-02-01 09:26:07][DEBUG] : [DeviceHandler.prepare] Json arg : None [2021-02-01 09:26:07][DEBUG] : Sending Zigbee request with tsn 19 under 20 request id, data: b'00130004000500' [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_request (22, 20, 0, , 260, 0, 1, b'\x00\x13\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:26:07][DEBUG] : Send: 0x1262001d00160014000217350104010000010700001300040005000200 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x126200090002002214 [2021-02-01 09:26:07][DEBUG] : APS data request response: [2, , 20] [2021-02-01 09:26:07][DEBUG] : [NetworkHandler.prepare] Json arg : None [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 16 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 10 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 11 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 9 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 11 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 14 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 14 neighbours [2021-02-01 09:26:07][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:26:07][INFO] : 200 GET /network/map (127.0.0.1) 25.91ms [2021-02-01 09:26:07][DEBUG] : Frame received: 0x0e63000700a600 [2021-02-01 09:26:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:07][DEBUG] : Send: 0x04630007000000 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x04630013000c00221402173501010000000000 [2021-02-01 09:26:07][DEBUG] : APS data confirm response for request with id 20: 00 [2021-02-01 09:26:07][DEBUG] : Request id: 0x14 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x0e64000700aa00 [2021-02-01 09:26:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:07][DEBUG] : Send: 0x1764000800010001 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x1764002700200022020000010217350104010000090018130104008605008600afd765f60000bd [2021-02-01 09:26:07][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\x13\x01\x04\x00\x86\x05\x00\x86', 0, 175, 215, 101, 246, 0, 0, -67] [2021-02-01 09:26:07][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=19 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:07][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181301040086050086' [2021-02-01 09:26:07][DEBUG] : Sending Zigbee request with tsn 21 under 22 request id, data: b'0015000400' [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_request (20, 22, 0, , 260, 0, 1, b'\x00\x15\x00\x04\x00', 2, 0) [2021-02-01 09:26:07][DEBUG] : Send: 0x1265001b0014001600021735010401000001050000150004000200 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x126500090002002216 [2021-02-01 09:26:07][DEBUG] : APS data request response: [2, , 22] [2021-02-01 09:26:07][DEBUG] : Frame received: 0x0e66000700a600 [2021-02-01 09:26:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:07][DEBUG] : Send: 0x04660007000000 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x04660013000c00221602173501010000000000 [2021-02-01 09:26:07][DEBUG] : APS data confirm response for request with id 22: 00 [2021-02-01 09:26:07][DEBUG] : Request id: 0x16 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x1c67000c000500022aa7ffcb [2021-02-01 09:26:07][DEBUG] : Frame received: 0x0e68000700aa00 [2021-02-01 09:26:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:07][DEBUG] : Send: 0x1767000800010001 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x17670024001d0022020000010217350104010000060018150104008600afdf65f60000be [2021-02-01 09:26:07][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\x15\x01\x04\x00\x86', 0, 175, 223, 101, 246, 0, 0, -66] [2021-02-01 09:26:07][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=21 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:07][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181501040086' [2021-02-01 09:26:07][DEBUG] : Sending Zigbee request with tsn 23 under 24 request id, data: b'0017000500' [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_request (20, 24, 0, , 260, 0, 1, b'\x00\x17\x00\x05\x00', 2, 0) [2021-02-01 09:26:07][DEBUG] : Send: 0x1268001b0014001800021735010401000001050000170005000200 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x126800090002002218 [2021-02-01 09:26:07][DEBUG] : APS data request response: [2, , 24] [2021-02-01 09:26:07][DEBUG] : Frame received: 0x0e69000700a600 [2021-02-01 09:26:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:07][DEBUG] : Send: 0x04690007000000 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x04690013000c00221802173501010000000000 [2021-02-01 09:26:07][DEBUG] : APS data confirm response for request with id 24: 00 [2021-02-01 09:26:07][DEBUG] : Request id: 0x18 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x0e6a000700aa00 [2021-02-01 09:26:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:07][DEBUG] : Send: 0x176a000800010001 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x176a0024001d0022020000010217350104010000060018170105008600afd765f60000bd [2021-02-01 09:26:07][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\x17\x01\x05\x00\x86', 0, 175, 215, 101, 246, 0, 0, -67] [2021-02-01 09:26:07][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=23 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:07][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181701050086' [2021-02-01 09:26:07][DEBUG] : [0x3517:1] Manufacturer: None [2021-02-01 09:26:07][DEBUG] : [0x3517:1] Model: None [2021-02-01 09:26:07][DEBUG] : [0x9e94] Extending timeout for 0x19 request [2021-02-01 09:26:07][DEBUG] : Sending Zigbee request with tsn 25 under 26 request id, data: b'00190004000500' [2021-02-01 09:26:07][DEBUG] : Command Command.aps_data_request (22, 26, 0, , 260, 0, 1, b'\x00\x19\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:26:07][DEBUG] : Send: 0x126b001d0016001a0002949e0104010000010700001900040005000200 [2021-02-01 09:26:07][DEBUG] : Frame received: 0x126b0009000200221a [2021-02-01 09:26:07][DEBUG] : APS data request response: [2, , 26] [2021-02-01 09:26:17][DEBUG] : Frame received: 0x0e6c000700a600 [2021-02-01 09:26:17][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:17][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:17][DEBUG] : Send: 0x046c0007000000 [2021-02-01 09:26:17][DEBUG] : Frame received: 0x046c0013000c00221a02949e0101d000000000 [2021-02-01 09:26:17][DEBUG] : APS data confirm response for request with id 26: d0 [2021-02-01 09:26:17][DEBUG] : Request id: 0x1a 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:26:17][DEBUG] : Error while sending 26 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:26:17][DEBUG] : [0x9e94] Delivery error for seq # 0x19, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:26:17][DEBUG] : [0x9e94] Extending timeout for 0x1b request [2021-02-01 09:26:17][DEBUG] : Sending Zigbee request with tsn 27 under 28 request id, data: b'001b000400' [2021-02-01 09:26:17][DEBUG] : Command Command.aps_data_request (20, 28, 0, , 260, 0, 1, b'\x00\x1b\x00\x04\x00', 2, 0) [2021-02-01 09:26:17][DEBUG] : Send: 0x126d001b0014001c0002949e0104010000010500001b0004000200 [2021-02-01 09:26:17][DEBUG] : Frame received: 0x126d0009000200221c [2021-02-01 09:26:17][DEBUG] : APS data request response: [2, , 28] [2021-02-01 09:26:27][DEBUG] : Frame received: 0x0e6e000700a600 [2021-02-01 09:26:27][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:27][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:27][DEBUG] : Send: 0x046e0007000000 [2021-02-01 09:26:27][DEBUG] : Frame received: 0x046e0013000c00221c02949e0101d000000000 [2021-02-01 09:26:27][DEBUG] : APS data confirm response for request with id 28: d0 [2021-02-01 09:26:27][DEBUG] : Request id: 0x1c 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:26:27][DEBUG] : Error while sending 28 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:26:27][DEBUG] : [0x9e94] Delivery error for seq # 0x1b, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:26:27][DEBUG] : [0x9e94] Extending timeout for 0x1d request [2021-02-01 09:26:27][DEBUG] : Sending Zigbee request with tsn 29 under 30 request id, data: b'001d000500' [2021-02-01 09:26:27][DEBUG] : Command Command.aps_data_request (20, 30, 0, , 260, 0, 1, b'\x00\x1d\x00\x05\x00', 2, 0) [2021-02-01 09:26:27][DEBUG] : Send: 0x126f001b0014001e0002949e0104010000010500001d0005000200 [2021-02-01 09:26:27][DEBUG] : Frame received: 0x126f0009000200221e [2021-02-01 09:26:27][DEBUG] : APS data request response: [2, , 30] [2021-02-01 09:26:33][DEBUG] : [0x346d] Extending timeout for 0x1f request [2021-02-01 09:26:33][DEBUG] : Sending Zigbee request with tsn 31 under 32 request id, data: b'001f000400' [2021-02-01 09:26:33][DEBUG] : Command Command.aps_data_request (20, 32, 0, , 260, 0, 1, b'\x00\x1f\x00\x04\x00', 2, 0) [2021-02-01 09:26:33][DEBUG] : Send: 0x1270001b0014002000026d340104010000010500001f0004000200 [2021-02-01 09:26:33][DEBUG] : Frame received: 0x127000090002002220 [2021-02-01 09:26:33][DEBUG] : APS data request response: [2, , 32] [2021-02-01 09:26:33][DEBUG] : Frame received: 0x0e71000700a600 [2021-02-01 09:26:33][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:33][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:33][DEBUG] : Send: 0x04710007000000 [2021-02-01 09:26:33][DEBUG] : Frame received: 0x04710013000c002220026d3401010000000000 [2021-02-01 09:26:33][DEBUG] : APS data confirm response for request with id 32: 00 [2021-02-01 09:26:33][DEBUG] : Request id: 0x20 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:37][DEBUG] : Frame received: 0x0e72000700a600 [2021-02-01 09:26:37][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:37][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:37][DEBUG] : Send: 0x04720007000000 [2021-02-01 09:26:37][DEBUG] : Frame received: 0x04720013000c00221e02949e0101d000000000 [2021-02-01 09:26:37][DEBUG] : APS data confirm response for request with id 30: d0 [2021-02-01 09:26:37][DEBUG] : Request id: 0x1e 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:26:37][DEBUG] : Error while sending 30 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:26:37][DEBUG] : [0x9e94] Delivery error for seq # 0x1d, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:26:37][DEBUG] : [0x9e94:1] Manufacturer: None [2021-02-01 09:26:37][DEBUG] : [0x9e94:1] Model: None [2021-02-01 09:26:37][DEBUG] : [0x130b:1] Manufacturer: Philips [2021-02-01 09:26:37][DEBUG] : [0x130b:1] Model: RWL021 [2021-02-01 09:26:37][DEBUG] : [0x130b:2] Manufacturer: Philips [2021-02-01 09:26:37][DEBUG] : [0x130b:2] Model: RWL021 [2021-02-01 09:26:37][DEBUG] : [0x507c:1] Manufacturer: IKEA of Sweden [2021-02-01 09:26:37][DEBUG] : [0x507c:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:26:37][DEBUG] : [0x924f:1] Manufacturer: IKEA of Sweden [2021-02-01 09:26:37][DEBUG] : [0x924f:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:26:37][DEBUG] : [0xd759:1] Manufacturer: IKEA of Sweden [2021-02-01 09:26:37][DEBUG] : [0xd759:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:26:37][DEBUG] : [0x35b7:1] Manufacturer: IKEA of Sweden [2021-02-01 09:26:37][DEBUG] : [0x35b7:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:26:37][DEBUG] : [0x8a87:1] Manufacturer: IKEA of Sweden [2021-02-01 09:26:37][DEBUG] : [0x8a87:1] Model: TRADFRI bulb E14 W op/ch 400lm [2021-02-01 09:26:37][DEBUG] : [0xbd2f:1] Manufacturer: IKEA of Sweden [2021-02-01 09:26:37][DEBUG] : [0xbd2f:1] Model: TRADFRI bulb E14 W op/ch 400lm [2021-02-01 09:26:37][DEBUG] : [0xabb3:1] Manufacturer: IKEA of Sweden [2021-02-01 09:26:37][DEBUG] : [0xabb3:1] Model: TRADFRI control outlet [2021-02-01 09:26:37][DEBUG] : Sending Zigbee request with tsn 33 under 34 request id, data: b'00210004000500' [2021-02-01 09:26:37][DEBUG] : Command Command.aps_data_request (22, 34, 0, , 260, 0, 1, b'\x00!\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:26:37][DEBUG] : Send: 0x1273001d001600220002d70f0104010000010700002100040005000200 [2021-02-01 09:26:37][DEBUG] : Frame received: 0x127300090002002222 [2021-02-01 09:26:37][DEBUG] : APS data request response: [2, , 34] [2021-02-01 09:26:37][DEBUG] : Frame received: 0x0e74000700a600 [2021-02-01 09:26:37][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:37][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:37][DEBUG] : Send: 0x04740007000000 [2021-02-01 09:26:37][DEBUG] : Frame received: 0x04740013000c00222202d70f01010000000000 [2021-02-01 09:26:37][DEBUG] : APS data confirm response for request with id 34: 00 [2021-02-01 09:26:37][DEBUG] : Request id: 0x22 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x0e75000700aa00 [2021-02-01 09:26:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:38][DEBUG] : Send: 0x1775000800010001 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x17750027002000220200000102d70f0104010000090018210104008605008600afff80f60000c7 [2021-02-01 09:26:38][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18!\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 128, 246, 0, 0, -57] [2021-02-01 09:26:38][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=33 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:38][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'182101040086050086' [2021-02-01 09:26:38][DEBUG] : Sending Zigbee request with tsn 35 under 36 request id, data: b'0023000400' [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_request (20, 36, 0, , 260, 0, 1, b'\x00#\x00\x04\x00', 2, 0) [2021-02-01 09:26:38][DEBUG] : Send: 0x1276001b001400240002d70f010401000001050000230004000200 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x127600090002002224 [2021-02-01 09:26:38][DEBUG] : APS data request response: [2, , 36] [2021-02-01 09:26:38][DEBUG] : Frame received: 0x0e77000700a600 [2021-02-01 09:26:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:38][DEBUG] : Send: 0x04770007000000 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x04770013000c00222402d70f01010000000000 [2021-02-01 09:26:38][DEBUG] : APS data confirm response for request with id 36: 00 [2021-02-01 09:26:38][DEBUG] : Request id: 0x24 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x0e78000700aa00 [2021-02-01 09:26:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:38][DEBUG] : Send: 0x1778000800010001 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x17780024001d00220200000102d70f0104010000060018230104008600afff50f60000c7 [2021-02-01 09:26:38][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18#\x01\x04\x00\x86', 0, 175, 255, 80, 246, 0, 0, -57] [2021-02-01 09:26:38][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=35 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:38][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'182301040086' [2021-02-01 09:26:38][DEBUG] : Sending Zigbee request with tsn 37 under 38 request id, data: b'0025000500' [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_request (20, 38, 0, , 260, 0, 1, b'\x00%\x00\x05\x00', 2, 0) [2021-02-01 09:26:38][DEBUG] : Send: 0x1279001b001400260002d70f010401000001050000250005000200 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x127900090002002226 [2021-02-01 09:26:38][DEBUG] : APS data request response: [2, , 38] [2021-02-01 09:26:38][DEBUG] : Frame received: 0x0e7a000700a600 [2021-02-01 09:26:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:38][DEBUG] : Send: 0x047a0007000000 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x047a0013000c00222602d70f01010000000000 [2021-02-01 09:26:38][DEBUG] : APS data confirm response for request with id 38: 00 [2021-02-01 09:26:38][DEBUG] : Request id: 0x26 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x0e7b000700aa00 [2021-02-01 09:26:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:26:38][DEBUG] : Send: 0x177b000800010001 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x177b0024001d00220200000102d70f0104010000060018250105008600afff80f60000c7 [2021-02-01 09:26:38][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18%\x01\x05\x00\x86', 0, 175, 255, 128, 246, 0, 0, -57] [2021-02-01 09:26:38][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=37 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:26:38][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'182501050086' [2021-02-01 09:26:38][DEBUG] : [0x0fd7:1] Manufacturer: None [2021-02-01 09:26:38][DEBUG] : [0x0fd7:1] Model: None [2021-02-01 09:26:38][DEBUG] : [0xa72a] Extending timeout for 0x27 request [2021-02-01 09:26:38][DEBUG] : Sending Zigbee request with tsn 39 under 40 request id, data: b'00270004000500' [2021-02-01 09:26:38][DEBUG] : Command Command.aps_data_request (22, 40, 0, , 260, 0, 1, b"\x00'\x00\x04\x00\x05\x00", 2, 0) [2021-02-01 09:26:38][DEBUG] : Send: 0x127c001d0016002800022aa70104010000010700002700040005000200 [2021-02-01 09:26:38][DEBUG] : Frame received: 0x127c00090002002228 [2021-02-01 09:26:38][DEBUG] : APS data request response: [2, , 40] [2021-02-01 09:26:48][DEBUG] : Frame received: 0x0e7d000700a600 [2021-02-01 09:26:48][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:48][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:48][DEBUG] : Send: 0x047d0007000000 [2021-02-01 09:26:48][DEBUG] : Frame received: 0x047d0013000c002228022aa70101d000000000 [2021-02-01 09:26:48][DEBUG] : APS data confirm response for request with id 40: d0 [2021-02-01 09:26:48][DEBUG] : Request id: 0x28 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:26:48][DEBUG] : Error while sending 40 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:26:48][DEBUG] : [0xa72a] Delivery error for seq # 0x27, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:26:48][DEBUG] : [0xa72a] Extending timeout for 0x29 request [2021-02-01 09:26:48][DEBUG] : Sending Zigbee request with tsn 41 under 42 request id, data: b'0029000400' [2021-02-01 09:26:48][DEBUG] : Command Command.aps_data_request (20, 42, 0, , 260, 0, 1, b'\x00)\x00\x04\x00', 2, 0) [2021-02-01 09:26:48][DEBUG] : Send: 0x127e001b0014002a00022aa7010401000001050000290004000200 [2021-02-01 09:26:48][DEBUG] : Frame received: 0x127e0009000200222a [2021-02-01 09:26:48][DEBUG] : APS data request response: [2, , 42] [2021-02-01 09:26:57][DEBUG] : Frame received: 0x1c7f000c00050002949effd1 [2021-02-01 09:26:58][DEBUG] : Frame received: 0x0e80000700a600 [2021-02-01 09:26:58][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:26:58][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:26:58][DEBUG] : Send: 0x047f0007000000 [2021-02-01 09:26:58][DEBUG] : Frame received: 0x047f0013000c00222a022aa70101d000000000 [2021-02-01 09:26:58][DEBUG] : APS data confirm response for request with id 42: d0 [2021-02-01 09:26:58][DEBUG] : Request id: 0x2a 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:26:58][DEBUG] : Error while sending 42 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:26:58][DEBUG] : [0xa72a] Delivery error for seq # 0x29, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:26:58][DEBUG] : [0xa72a] Extending timeout for 0x2b request [2021-02-01 09:26:58][DEBUG] : Sending Zigbee request with tsn 43 under 44 request id, data: b'002b000500' [2021-02-01 09:26:58][DEBUG] : Command Command.aps_data_request (20, 44, 0, , 260, 0, 1, b'\x00+\x00\x05\x00', 2, 0) [2021-02-01 09:26:58][DEBUG] : Send: 0x1280001b0014002c00022aa70104010000010500002b0005000200 [2021-02-01 09:26:58][DEBUG] : Frame received: 0x12800009000200222c [2021-02-01 09:26:58][DEBUG] : APS data request response: [2, , 44] [2021-02-01 09:27:01][DEBUG] : [0x346d] Extending timeout for 0x2d request [2021-02-01 09:27:01][DEBUG] : Sending Zigbee request with tsn 45 under 46 request id, data: b'002d000500' [2021-02-01 09:27:01][DEBUG] : Command Command.aps_data_request (20, 46, 0, , 260, 0, 1, b'\x00-\x00\x05\x00', 2, 0) [2021-02-01 09:27:01][DEBUG] : Send: 0x1281001b0014002e00026d340104010000010500002d0005000200 [2021-02-01 09:27:01][DEBUG] : Frame received: 0x12810009000200222e [2021-02-01 09:27:01][DEBUG] : APS data request response: [2, , 46] [2021-02-01 09:27:01][DEBUG] : Frame received: 0x0e82000700a600 [2021-02-01 09:27:01][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:01][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:27:01][DEBUG] : Send: 0x04820007000000 [2021-02-01 09:27:01][DEBUG] : Frame received: 0x04820013000c00222e026d3401010000000000 [2021-02-01 09:27:01][DEBUG] : APS data confirm response for request with id 46: 00 [2021-02-01 09:27:01][DEBUG] : Request id: 0x2e 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:27:01][DEBUG] : Frame received: 0x1c83000c00050002f584ffcf [2021-02-01 09:27:04][DEBUG] : Frame received: 0x0e84000700aa00 [2021-02-01 09:27:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:04][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:27:04][DEBUG] : Send: 0x1783000800010001 [2021-02-01 09:27:04][DEBUG] : Frame received: 0x17830025001e0022020000010259d701040108000700083b0a000020fe00af7f95f60000b2 [2021-02-01 09:27:04][DEBUG] : APS data indication response: [30, , , 1, , 1, 260, 8, b'\x08; \x00\x00 \xfe', 0, 175, 127, 149, 246, 0, 0, -78] [2021-02-01 09:27:04][DEBUG] : [0xd759:1:0x0008] ZCL deserialize: manufacturer=None tsn=59 command_id=Command.Report_Attributes> [2021-02-01 09:27:04][DEBUG] : [0xd759:1:0x0008] ZCL request 0x000a: [[Attribute(attrid=0, value=)]] [2021-02-01 09:27:04][INFO] : [00:0b:57:ff:fe:a0:1a:ba][listener.general_command] Cluster: ClusterId: 0x0008 command_id: manufacturer=None tsn=59 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=)]],) [2021-02-01 09:27:04][DEBUG] : [0xd759:1:0x0008] Attribute report received: current_level=254 [2021-02-01 09:27:04][INFO] : [00:0b:57:ff:fe:a0:1a:ba][listener.attribute_updated] Received an attribute update 0=254 on cluster 8 [2021-02-01 09:27:04][DEBUG] : Sending Zigbee request with tsn 59 under 47 request id, data: b'183b0b0a00' [2021-02-01 09:27:04][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'083b0a000020fe' [2021-02-01 09:27:04][DEBUG] : Command Command.aps_data_request (20, 47, 0, , 260, 8, 1, b'\x18;\x0b \x00', 2, 0) [2021-02-01 09:27:04][DEBUG] : Send: 0x1284001b0014002f000259d70104010800010500183b0b0a000200 [2021-02-01 09:27:04][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:0b:57:ff:fe:a0:1a:ba, 1, 8, 0, 254)) [2021-02-01 09:27:04][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:0b:57:ff:fe:a0:1a:ba, 1, 8, 0, 254)) completed [2021-02-01 09:27:04][DEBUG] : executing functools.partial() [2021-02-01 09:27:04][DEBUG] : Frame received: 0x12840009000200222f [2021-02-01 09:27:04][DEBUG] : APS data request response: [2, , 47] [2021-02-01 09:27:04][DEBUG] : Frame received: 0x0e85000700a600 [2021-02-01 09:27:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:04][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:27:04][DEBUG] : Send: 0x04850007000000 [2021-02-01 09:27:04][DEBUG] : Frame received: 0x04850013000c00222f0259d701010000000000 [2021-02-01 09:27:04][DEBUG] : APS data confirm response for request with id 47: 00 [2021-02-01 09:27:04][DEBUG] : Request id: 0x2f 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:27:04][DEBUG] : operation functools.partial() completed [2021-02-01 09:27:04][DEBUG] : Send to jeedom : {'devices': {'00:0b:57:ff:fe:a0:1a:ba': {'1': {'8': {'0': {'value': '254', 'cluster_name': 'Level control'}}}}}} [2021-02-01 09:27:04][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80 [2021-02-01 09:27:04][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=V7BsmMlaK71xWYTqkNbwKrvBtNuAK9NF HTTP/1.1" 200 0 [2021-02-01 09:27:07][DEBUG] : Frame received: 0x1c86000c000500022aa7ffcb [2021-02-01 09:27:08][DEBUG] : Frame received: 0x0e87000700a600 [2021-02-01 09:27:08][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:08][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:27:08][DEBUG] : Send: 0x04860007000000 [2021-02-01 09:27:08][DEBUG] : Frame received: 0x04860013000c00222c022aa70101d000000000 [2021-02-01 09:27:08][DEBUG] : APS data confirm response for request with id 44: d0 [2021-02-01 09:27:08][DEBUG] : Request id: 0x2c 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:27:08][DEBUG] : Error while sending 44 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:27:08][DEBUG] : [0xa72a] Delivery error for seq # 0x2b, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:27:08][DEBUG] : [0xa72a:1] Manufacturer: None [2021-02-01 09:27:08][DEBUG] : [0xa72a:1] Model: None [2021-02-01 09:27:08][DEBUG] : Sending Zigbee request with tsn 48 under 49 request id, data: b'00300004000500' [2021-02-01 09:27:08][DEBUG] : Command Command.aps_data_request (22, 49, 0, , 260, 0, 1, b'\x000\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:27:08][DEBUG] : Send: 0x1287001d001600310002739b0104010000010700003000040005000200 [2021-02-01 09:27:08][DEBUG] : Frame received: 0x128700090002002231 [2021-02-01 09:27:08][DEBUG] : APS data request response: [2, , 49] [2021-02-01 09:27:08][DEBUG] : Frame received: 0x0e88000700a600 [2021-02-01 09:27:08][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:08][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:27:08][DEBUG] : Send: 0x04880007000000 [2021-02-01 09:27:08][DEBUG] : Frame received: 0x04880013000c00223102739b01010000000000 [2021-02-01 09:27:08][DEBUG] : APS data confirm response for request with id 49: 00 [2021-02-01 09:27:08][DEBUG] : Request id: 0x31 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x0e89000700aa00 [2021-02-01 09:27:09][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:27:09][DEBUG] : Send: 0x1789000800010001 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x17890027002000220200000102739b0104010000090018300104008605008600afd795f60000bd [2021-02-01 09:27:09][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x180\x01\x04\x00\x86\x05\x00\x86', 0, 175, 215, 149, 246, 0, 0, -67] [2021-02-01 09:27:09][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=48 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:27:09][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'183001040086050086' [2021-02-01 09:27:09][DEBUG] : Sending Zigbee request with tsn 50 under 51 request id, data: b'0032000400' [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_request (20, 51, 0, , 260, 0, 1, b'\x002\x00\x04\x00', 2, 0) [2021-02-01 09:27:09][DEBUG] : Send: 0x128a001b001400330002739b010401000001050000320004000200 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x128a00090002002233 [2021-02-01 09:27:09][DEBUG] : APS data request response: [2, , 51] [2021-02-01 09:27:09][DEBUG] : Frame received: 0x0e8b000700a600 [2021-02-01 09:27:09][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:27:09][DEBUG] : Send: 0x048b0007000000 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x048b0013000c00223302739b01010000000000 [2021-02-01 09:27:09][DEBUG] : APS data confirm response for request with id 51: 00 [2021-02-01 09:27:09][DEBUG] : Request id: 0x33 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x0e8c000700aa00 [2021-02-01 09:27:09][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:27:09][DEBUG] : Send: 0x178c000800010001 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x178c0024001d00220200000102739b0104010000060018320104008600afd795f60000bd [2021-02-01 09:27:09][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x182\x01\x04\x00\x86', 0, 175, 215, 149, 246, 0, 0, -67] [2021-02-01 09:27:09][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=50 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:27:09][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'183201040086' [2021-02-01 09:27:09][DEBUG] : Sending Zigbee request with tsn 52 under 53 request id, data: b'0034000500' [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_request (20, 53, 0, , 260, 0, 1, b'\x004\x00\x05\x00', 2, 0) [2021-02-01 09:27:09][DEBUG] : Send: 0x128d001b001400350002739b010401000001050000340005000200 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x128d00090002002235 [2021-02-01 09:27:09][DEBUG] : APS data request response: [2, , 53] [2021-02-01 09:27:09][DEBUG] : Frame received: 0x0e8e000700a600 [2021-02-01 09:27:09][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:27:09][DEBUG] : Send: 0x048e0007000000 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x048e0013000c00223502739b01010000000000 [2021-02-01 09:27:09][DEBUG] : APS data confirm response for request with id 53: 00 [2021-02-01 09:27:09][DEBUG] : Request id: 0x35 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x0e8f000700aa00 [2021-02-01 09:27:09][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:27:09][DEBUG] : Send: 0x178f000800010001 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x178f0024001d00220200000102739b0104010000060018340105008600afd795f60000bd [2021-02-01 09:27:09][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x184\x01\x05\x00\x86', 0, 175, 215, 149, 246, 0, 0, -67] [2021-02-01 09:27:09][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=52 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:27:09][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'183401050086' [2021-02-01 09:27:09][DEBUG] : [0x9b73:1] Manufacturer: None [2021-02-01 09:27:09][DEBUG] : [0x9b73:1] Model: None [2021-02-01 09:27:09][DEBUG] : [0x346d] Extending timeout for 0x36 request [2021-02-01 09:27:09][DEBUG] : Sending Zigbee request with tsn 54 under 55 request id, data: b'00360004000500' [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_request (22, 55, 0, , 260, 0, 1, b'\x006\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:27:09][DEBUG] : Send: 0x1290001d0016003700026d340104010000010700003600040005000200 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x129000090002002237 [2021-02-01 09:27:09][DEBUG] : APS data request response: [2, , 55] [2021-02-01 09:27:09][DEBUG] : Frame received: 0x0e91000700a600 [2021-02-01 09:27:09][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:27:09][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:27:09][DEBUG] : Send: 0x04910007000000 [2021-02-01 09:27:09][DEBUG] : Frame received: 0x04910013000c002237026d3401010000000000 [2021-02-01 09:27:09][DEBUG] : APS data confirm response for request with id 55: 00 [2021-02-01 09:27:09][DEBUG] : Request id: 0x37 'aps_data_confirm' for , status: 0x00