[2021-02-01 09:51:34][DEBUG] : [DeviceHandler.prepare] Json arg : None [2021-02-01 09:51:34][DEBUG] : Sending Zigbee request with tsn 145 under 146 request id, data: b'00910004000500' [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_request (22, 146, 0, , 260, 0, 1, b'\x00\x91\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:51:34][DEBUG] : Send: 0x127f001d00160092000217350104010000010700009100040005000200 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x127f00090002002292 [2021-02-01 09:51:34][DEBUG] : APS data request response: [2, , 146] [2021-02-01 09:51:34][DEBUG] : Frame received: 0x0e80000700a600 [2021-02-01 09:51:34][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:34][DEBUG] : Send: 0x04800007000000 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x04800013000c00229202173501010000000000 [2021-02-01 09:51:34][DEBUG] : APS data confirm response for request with id 146: 00 [2021-02-01 09:51:34][DEBUG] : Request id: 0x92 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x0e81000700aa00 [2021-02-01 09:51:34][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:34][DEBUG] : Send: 0x1781000800010001 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x1781002700200022020000010217350104010000090018910104008605008600afdffef70000be [2021-02-01 09:51:34][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\x91\x01\x04\x00\x86\x05\x00\x86', 0, 175, 223, 254, 247, 0, 0, -66] [2021-02-01 09:51:34][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=145 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:34][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'189101040086050086' [2021-02-01 09:51:34][DEBUG] : Sending Zigbee request with tsn 147 under 148 request id, data: b'0093000400' [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_request (20, 148, 0, , 260, 0, 1, b'\x00\x93\x00\x04\x00', 2, 0) [2021-02-01 09:51:34][DEBUG] : Send: 0x1282001b0014009400021735010401000001050000930004000200 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x128200090002002294 [2021-02-01 09:51:34][DEBUG] : APS data request response: [2, , 148] [2021-02-01 09:51:34][DEBUG] : Frame received: 0x0e83000700a600 [2021-02-01 09:51:34][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:34][DEBUG] : Send: 0x04830007000000 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x04830013000c00229402173501010000000000 [2021-02-01 09:51:34][DEBUG] : APS data confirm response for request with id 148: 00 [2021-02-01 09:51:34][DEBUG] : Request id: 0x94 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x0e84000700aa00 [2021-02-01 09:51:34][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:34][DEBUG] : Send: 0x1784000800010001 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x17840024001d0022020000010217350104010000060018930104008600afd78bf80000bd [2021-02-01 09:51:34][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\x93\x01\x04\x00\x86', 0, 175, 215, 139, 248, 0, 0, -67] [2021-02-01 09:51:34][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=147 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:34][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'189301040086' [2021-02-01 09:51:34][DEBUG] : Sending Zigbee request with tsn 149 under 150 request id, data: b'0095000500' [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_request (20, 150, 0, , 260, 0, 1, b'\x00\x95\x00\x05\x00', 2, 0) [2021-02-01 09:51:34][DEBUG] : Send: 0x1285001b0014009600021735010401000001050000950005000200 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x128500090002002296 [2021-02-01 09:51:34][DEBUG] : APS data request response: [2, , 150] [2021-02-01 09:51:34][DEBUG] : Frame received: 0x0e86000700a600 [2021-02-01 09:51:34][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:34][DEBUG] : Send: 0x04860007000000 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x04860013000c00229602173501010000000000 [2021-02-01 09:51:34][DEBUG] : APS data confirm response for request with id 150: 00 [2021-02-01 09:51:34][DEBUG] : Request id: 0x96 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x0e87000700aa00 [2021-02-01 09:51:34][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:34][DEBUG] : Send: 0x1787000800010001 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x17870024001d0022020000010217350104010000060018950105008600afd78bf80000bd [2021-02-01 09:51:34][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\x95\x01\x05\x00\x86', 0, 175, 215, 139, 248, 0, 0, -67] [2021-02-01 09:51:34][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=149 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:34][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'189501050086' [2021-02-01 09:51:34][DEBUG] : [0x3517:1] Manufacturer: None [2021-02-01 09:51:34][DEBUG] : [0x3517:1] Model: None [2021-02-01 09:51:34][DEBUG] : [0x9e94] Extending timeout for 0x97 request [2021-02-01 09:51:34][DEBUG] : Sending Zigbee request with tsn 151 under 152 request id, data: b'00970004000500' [2021-02-01 09:51:34][DEBUG] : Command Command.aps_data_request (22, 152, 0, , 260, 0, 1, b'\x00\x97\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:51:34][DEBUG] : Send: 0x1288001d001600980002949e0104010000010700009700040005000200 [2021-02-01 09:51:34][DEBUG] : Frame received: 0x128800090002002298 [2021-02-01 09:51:34][DEBUG] : APS data request response: [2, , 152] [2021-02-01 09:51:45][DEBUG] : Frame received: 0x0e89000700a600 [2021-02-01 09:51:45][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:45][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:45][DEBUG] : Send: 0x04890007000000 [2021-02-01 09:51:45][DEBUG] : Frame received: 0x04890013000c00229802949e0101d000000000 [2021-02-01 09:51:45][DEBUG] : APS data confirm response for request with id 152: d0 [2021-02-01 09:51:45][DEBUG] : Request id: 0x98 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:51:45][DEBUG] : Error while sending 152 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:51:45][DEBUG] : [0x9e94] Delivery error for seq # 0x97, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:51:45][DEBUG] : [0x9e94] Extending timeout for 0x99 request [2021-02-01 09:51:45][DEBUG] : Sending Zigbee request with tsn 153 under 154 request id, data: b'0099000400' [2021-02-01 09:51:45][DEBUG] : Command Command.aps_data_request (20, 154, 0, , 260, 0, 1, b'\x00\x99\x00\x04\x00', 2, 0) [2021-02-01 09:51:45][DEBUG] : Send: 0x128a001b0014009a0002949e010401000001050000990004000200 [2021-02-01 09:51:45][DEBUG] : Frame received: 0x128a0009000200229a [2021-02-01 09:51:45][DEBUG] : APS data request response: [2, , 154] [2021-02-01 09:51:52][DEBUG] : Frame received: 0x0e8b000700aa00 [2021-02-01 09:51:52][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:52][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:52][DEBUG] : Send: 0x178b000800010001 [2021-02-01 09:51:52][DEBUG] : Frame received: 0x178b0025001e00220200000102d3740104010800070018610a000020fe00afbf9ff80000ba [2021-02-01 09:51:52][DEBUG] : APS data indication response: [30, , , 1, , 1, 260, 8, b'\x18a \x00\x00 \xfe', 0, 175, 191, 159, 248, 0, 0, -70] [2021-02-01 09:51:52][DEBUG] : [0x74d3:1:0x0008] ZCL deserialize: manufacturer=None tsn=97 command_id=Command.Report_Attributes> [2021-02-01 09:51:52][DEBUG] : [0x74d3:1:0x0008] ZCL request 0x000a: [[Attribute(attrid=0, value=)]] [2021-02-01 09:51:52][INFO] : [20:91:8a:00:00:06:b4:bc][listener.general_command] Cluster: ClusterId: 0x0008 command_id: manufacturer=None tsn=97 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=)]],) [2021-02-01 09:51:52][DEBUG] : [0x74d3:1:0x0008] Attribute report received: current_level=254 [2021-02-01 09:51:52][INFO] : [20:91:8a:00:00:06:b4:bc][listener.attribute_updated] Received an attribute update 0=254 on cluster 8 [2021-02-01 09:51:52][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'18610a000020fe' [2021-02-01 09:51:52][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:bc, 1, 8, 0, 254)) [2021-02-01 09:51:52][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:bc, 1, 8, 0, 254)) completed [2021-02-01 09:51:52][DEBUG] : executing functools.partial() [2021-02-01 09:51:52][DEBUG] : operation functools.partial() completed [2021-02-01 09:51:52][DEBUG] : Send to jeedom : {'devices': {'20:91:8a:00:00:06:b4:bc': {'1': {'8': {'0': {'value': '254', 'cluster_name': 'Level control'}}}}}} [2021-02-01 09:51:52][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80 [2021-02-01 09:51:52][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:51:55][DEBUG] : [0x25e1] Extending timeout for 0x9b request [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 155 under 156 request id, data: b'009b000500' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (20, 156, 0, , 260, 0, 1, b'\x00\x9b\x00\x05\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x128c001b0014009c0002e1250104010000010500009b0005000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x128c0009000200229c [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 156] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e8d000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x048d0007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x048d0013000c00229c02e12501010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 156: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0x9c 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e8e000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x048e0007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x048e0013000c00229a02949e0101d000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 154: d0 [2021-02-01 09:51:55][DEBUG] : Request id: 0x9a 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:51:55][DEBUG] : Error while sending 154 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:51:55][DEBUG] : [0x9e94] Delivery error for seq # 0x99, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:51:55][DEBUG] : [0x9e94] Extending timeout for 0x9d request [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 157 under 158 request id, data: b'009d000500' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (20, 158, 0, , 260, 0, 1, b'\x00\x9d\x00\x05\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x128f001b0014009e0002949e0104010000010500009d0005000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x128f0009000200229e [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 158] [2021-02-01 09:51:55][DEBUG] : [0x7ef2:1] Manufacturer: None [2021-02-01 09:51:55][DEBUG] : [0x7ef2:1] Model: None [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 159 under 160 request id, data: b'009f0004000500' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (22, 160, 0, , 260, 0, 1, b'\x00\x9f\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x1290001d001600a00002475a0104010000010700009f00040005000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x1290000900020022a0 [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 160] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e91000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x04910007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x04910013000c0022a002475a01010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 160: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0xa0 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e92000700aa00 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:55][DEBUG] : Send: 0x1792000800010001 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x17920027002000220200000102475a01040100000900189f0104008605008600afff97f80000c6 [2021-02-01 09:51:55][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\x9f\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 151, 248, 0, 0, -58] [2021-02-01 09:51:55][DEBUG] : [0x5a47:1:0x0000] ZCL deserialize: manufacturer=None tsn=159 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:55][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'189f01040086050086' [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 161 under 162 request id, data: b'00a1000400' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (20, 162, 0, , 260, 0, 1, b'\x00\xa1\x00\x04\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x1293001b001400a20002475a010401000001050000a10004000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x1293000900020022a2 [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 162] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e94000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x04940007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x04940013000c0022a202475a01010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 162: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0xa2 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e95000700aa00 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:55][DEBUG] : Send: 0x1795000800010001 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x17950024001d00220200000102475a0104010000060018a10104008600afff97f80000c7 [2021-02-01 09:51:55][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xa1\x01\x04\x00\x86', 0, 175, 255, 151, 248, 0, 0, -57] [2021-02-01 09:51:55][DEBUG] : [0x5a47:1:0x0000] ZCL deserialize: manufacturer=None tsn=161 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:55][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18a101040086' [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 163 under 164 request id, data: b'00a3000500' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (20, 164, 0, , 260, 0, 1, b'\x00\xa3\x00\x05\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x1296001b001400a40002475a010401000001050000a30005000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x1296000900020022a4 [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 164] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e97000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x04970007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x04970013000c0022a402475a01010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 164: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0xa4 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e98000700aa00 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:55][DEBUG] : Send: 0x1798000800010001 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x17980024001d00220200000102475a0104010000060018a30105008600afff97f80000c7 [2021-02-01 09:51:55][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xa3\x01\x05\x00\x86', 0, 175, 255, 151, 248, 0, 0, -57] [2021-02-01 09:51:55][DEBUG] : [0x5a47:1:0x0000] ZCL deserialize: manufacturer=None tsn=163 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:55][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18a301050086' [2021-02-01 09:51:55][DEBUG] : [0x5a47:1] Manufacturer: None [2021-02-01 09:51:55][DEBUG] : [0x5a47:1] Model: None [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 165 under 166 request id, data: b'00a50004000500' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (22, 166, 0, , 260, 0, 1, b'\x00\xa5\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x1299001d001600a60002d374010401000001070000a500040005000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x1299000900020022a6 [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 166] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e9a000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x049a0007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x049a0013000c0022a602d37401010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 166: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0xa6 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e9b000700aa00 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:55][DEBUG] : Send: 0x179b000800010001 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x179b0027002000220200000102d3740104010000090018a50104008605008600afbf97f80000ba [2021-02-01 09:51:55][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\xa5\x01\x04\x00\x86\x05\x00\x86', 0, 175, 191, 151, 248, 0, 0, -70] [2021-02-01 09:51:55][DEBUG] : [0x74d3:1:0x0000] ZCL deserialize: manufacturer=None tsn=165 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:55][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18a501040086050086' [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 167 under 168 request id, data: b'00a7000400' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (20, 168, 0, , 260, 0, 1, b'\x00\xa7\x00\x04\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x129c001b001400a80002d374010401000001050000a70004000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x129c000900020022a8 [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 168] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e9d000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x049d0007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x049d0013000c0022a802d37401010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 168: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0xa8 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0e9e000700aa00 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:55][DEBUG] : Send: 0x179e000800010001 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x179e0024001d00220200000102d3740104010000060018a70104008600afbf97f80000ba [2021-02-01 09:51:55][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xa7\x01\x04\x00\x86', 0, 175, 191, 151, 248, 0, 0, -70] [2021-02-01 09:51:55][DEBUG] : [0x74d3:1:0x0000] ZCL deserialize: manufacturer=None tsn=167 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:55][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18a701040086' [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 169 under 170 request id, data: b'00a9000500' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (20, 170, 0, , 260, 0, 1, b'\x00\xa9\x00\x05\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x129f001b001400aa0002d374010401000001050000a90005000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x129f000900020022aa [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 170] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0ea0000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x04a00007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x04a00013000c0022aa02d37401010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 170: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0xaa 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0ea1000700aa00 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:51:55][DEBUG] : Send: 0x17a1000800010001 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x17a10024001d00220200000102d3740104010000060018a90105008600afbf97f80000ba [2021-02-01 09:51:55][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xa9\x01\x05\x00\x86', 0, 175, 191, 151, 248, 0, 0, -70] [2021-02-01 09:51:55][DEBUG] : [0x74d3:1:0x0000] ZCL deserialize: manufacturer=None tsn=169 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:51:55][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18a901050086' [2021-02-01 09:51:55][DEBUG] : [0x74d3:1] Manufacturer: None [2021-02-01 09:51:55][DEBUG] : [0x74d3:1] Model: None [2021-02-01 09:51:55][DEBUG] : [0xd976] Extending timeout for 0xab request [2021-02-01 09:51:55][DEBUG] : Sending Zigbee request with tsn 171 under 172 request id, data: b'00ab0004000500' [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_request (22, 172, 0, , 260, 0, 1, b'\x00\xab\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:51:55][DEBUG] : Send: 0x12a2001d001600ac000276d9010401000001070000ab00040005000200 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x12a2000900020022ac [2021-02-01 09:51:55][DEBUG] : APS data request response: [2, , 172] [2021-02-01 09:51:55][DEBUG] : Frame received: 0x0ea3000700a600 [2021-02-01 09:51:55][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:51:55][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:51:55][DEBUG] : Send: 0x04a30007000000 [2021-02-01 09:51:55][DEBUG] : Frame received: 0x04a30013000c0022ac0276d901010000000000 [2021-02-01 09:51:55][DEBUG] : APS data confirm response for request with id 172: 00 [2021-02-01 09:51:55][DEBUG] : Request id: 0xac 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:01][DEBUG] : Frame received: 0x1ca4000c00050002949effd1 [2021-02-01 09:52:01][DEBUG] : Frame received: 0x0ea5000700aa00 [2021-02-01 09:52:01][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:01][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:01][DEBUG] : Send: 0x17a4000800010001 [2021-02-01 09:52:01][DEBUG] : Frame received: 0x17a4002700200022020000010276d90104010000090008ab0104008605008600afc7b2f80000bb [2021-02-01 09:52:01][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x08\xab\x01\x04\x00\x86\x05\x00\x86', 0, 175, 199, 178, 248, 0, 0, -69] [2021-02-01 09:52:01][DEBUG] : [0xd976:1:0x0000] ZCL deserialize: manufacturer=None tsn=171 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:01][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08ab01040086050086' [2021-02-01 09:52:01][DEBUG] : [0xd976] Extending timeout for 0xad request [2021-02-01 09:52:01][DEBUG] : Sending Zigbee request with tsn 173 under 174 request id, data: b'00ad000400' [2021-02-01 09:52:01][DEBUG] : Command Command.aps_data_request (20, 174, 0, , 260, 0, 1, b'\x00\xad\x00\x04\x00', 2, 0) [2021-02-01 09:52:01][DEBUG] : Send: 0x12a5001b001400ae000276d9010401000001050000ad0004000200 [2021-02-01 09:52:01][DEBUG] : Frame received: 0x12a5000900020022ae [2021-02-01 09:52:01][DEBUG] : APS data request response: [2, , 174] [2021-02-01 09:52:01][DEBUG] : Frame received: 0x0ea6000700a600 [2021-02-01 09:52:01][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:01][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:01][DEBUG] : Send: 0x04a60007000000 [2021-02-01 09:52:01][DEBUG] : Frame received: 0x04a60013000c0022ae0276d901010000000000 [2021-02-01 09:52:01][DEBUG] : APS data confirm response for request with id 174: 00 [2021-02-01 09:52:01][DEBUG] : Request id: 0xae 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0ea7000700aa00 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:02][DEBUG] : Send: 0x17a7000800010001 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x17a70024001d0022020000010276d90104010000060008ad0104008600afbfb2f80000ba [2021-02-01 09:52:02][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x08\xad\x01\x04\x00\x86', 0, 175, 191, 178, 248, 0, 0, -70] [2021-02-01 09:52:02][DEBUG] : [0xd976:1:0x0000] ZCL deserialize: manufacturer=None tsn=173 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:02][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08ad01040086' [2021-02-01 09:52:02][DEBUG] : [0xd976] Extending timeout for 0xaf request [2021-02-01 09:52:02][DEBUG] : Sending Zigbee request with tsn 175 under 176 request id, data: b'00af000500' [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_request (20, 176, 0, , 260, 0, 1, b'\x00\xaf\x00\x05\x00', 2, 0) [2021-02-01 09:52:02][DEBUG] : Send: 0x12a8001b001400b0000276d9010401000001050000af0005000200 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x12a8000900020022b0 [2021-02-01 09:52:02][DEBUG] : APS data request response: [2, , 176] [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0ea9000700a600 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:02][DEBUG] : Send: 0x04a90007000000 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x04a90013000c0022b00276d901010000000000 [2021-02-01 09:52:02][DEBUG] : APS data confirm response for request with id 176: 00 [2021-02-01 09:52:02][DEBUG] : Request id: 0xb0 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0eaa000700aa00 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:02][DEBUG] : Send: 0x17aa000800010001 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x17aa0024001d0022020000010276d90104010000060008af0105008600afbfb2f80000ba [2021-02-01 09:52:02][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x08\xaf\x01\x05\x00\x86', 0, 175, 191, 178, 248, 0, 0, -70] [2021-02-01 09:52:02][DEBUG] : [0xd976:1:0x0000] ZCL deserialize: manufacturer=None tsn=175 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:02][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08af01050086' [2021-02-01 09:52:02][DEBUG] : [0xd976:1] Manufacturer: None [2021-02-01 09:52:02][DEBUG] : [0xd976:1] Model: None [2021-02-01 09:52:02][DEBUG] : Sending Zigbee request with tsn 177 under 178 request id, data: b'00b10004000500' [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_request (22, 178, 0, , 260, 0, 1, b'\x00\xb1\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:02][DEBUG] : Send: 0x12ab001d001600b200028fc6010401000001070000b100040005000200 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x12ab000900020022b2 [2021-02-01 09:52:02][DEBUG] : APS data request response: [2, , 178] [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0eac000700a600 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:02][DEBUG] : Send: 0x04ac0007000000 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x04ac0013000c0022b2028fc601010000000000 [2021-02-01 09:52:02][DEBUG] : APS data confirm response for request with id 178: 00 [2021-02-01 09:52:02][DEBUG] : Request id: 0xb2 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0ead000700aa00 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:02][DEBUG] : Send: 0x17ad000800010001 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x17ad00270020002202000001028fc60104010000090018b10104008605008600afcfb2f80000bc [2021-02-01 09:52:02][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\xb1\x01\x04\x00\x86\x05\x00\x86', 0, 175, 207, 178, 248, 0, 0, -68] [2021-02-01 09:52:02][DEBUG] : [0xc68f:1:0x0000] ZCL deserialize: manufacturer=None tsn=177 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:02][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18b101040086050086' [2021-02-01 09:52:02][DEBUG] : Sending Zigbee request with tsn 179 under 180 request id, data: b'00b3000400' [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_request (20, 180, 0, , 260, 0, 1, b'\x00\xb3\x00\x04\x00', 2, 0) [2021-02-01 09:52:02][DEBUG] : Send: 0x12ae001b001400b400028fc6010401000001050000b30004000200 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x12ae000900020022b4 [2021-02-01 09:52:02][DEBUG] : APS data request response: [2, , 180] [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0eaf000700a600 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:02][DEBUG] : Send: 0x04af0007000000 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x04af0013000c0022b4028fc601010000000000 [2021-02-01 09:52:02][DEBUG] : APS data confirm response for request with id 180: 00 [2021-02-01 09:52:02][DEBUG] : Request id: 0xb4 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0eb0000700aa00 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:02][DEBUG] : Send: 0x17b0000800010001 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x17b00024001d002202000001028fc60104010000060018b30104008600afc7b2f80000bb [2021-02-01 09:52:02][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xb3\x01\x04\x00\x86', 0, 175, 199, 178, 248, 0, 0, -69] [2021-02-01 09:52:02][DEBUG] : [0xc68f:1:0x0000] ZCL deserialize: manufacturer=None tsn=179 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:02][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18b301040086' [2021-02-01 09:52:02][DEBUG] : Sending Zigbee request with tsn 181 under 182 request id, data: b'00b5000500' [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_request (20, 182, 0, , 260, 0, 1, b'\x00\xb5\x00\x05\x00', 2, 0) [2021-02-01 09:52:02][DEBUG] : Send: 0x12b1001b001400b600028fc6010401000001050000b50005000200 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x12b1000900020022b6 [2021-02-01 09:52:02][DEBUG] : APS data request response: [2, , 182] [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0eb2000700a600 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:02][DEBUG] : Send: 0x04b20007000000 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x04b20013000c0022b6028fc601010000000000 [2021-02-01 09:52:02][DEBUG] : APS data confirm response for request with id 182: 00 [2021-02-01 09:52:02][DEBUG] : Request id: 0xb6 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0eb3000700aa00 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:02][DEBUG] : Send: 0x17b3000800010001 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x17b30024001d002202000001028fc60104010000060018b50105008600afcfb2f80000bc [2021-02-01 09:52:02][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xb5\x01\x05\x00\x86', 0, 175, 207, 178, 248, 0, 0, -68] [2021-02-01 09:52:02][DEBUG] : [0xc68f:1:0x0000] ZCL deserialize: manufacturer=None tsn=181 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:02][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18b501050086' [2021-02-01 09:52:02][DEBUG] : [0xc68f:1] Manufacturer: None [2021-02-01 09:52:02][DEBUG] : [0xc68f:1] Model: None [2021-02-01 09:52:02][DEBUG] : [0x25e1] Extending timeout for 0xb7 request [2021-02-01 09:52:02][DEBUG] : Sending Zigbee request with tsn 183 under 184 request id, data: b'00b70004000500' [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_request (22, 184, 0, , 260, 0, 1, b'\x00\xb7\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:02][DEBUG] : Send: 0x12b4001d001600b80002e125010401000001070000b700040005000200 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x12b4000900020022b8 [2021-02-01 09:52:02][DEBUG] : APS data request response: [2, , 184] [2021-02-01 09:52:02][DEBUG] : Frame received: 0x0eb5000700a600 [2021-02-01 09:52:02][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:02][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:02][DEBUG] : Send: 0x04b50007000000 [2021-02-01 09:52:02][DEBUG] : Frame received: 0x04b50013000c0022b802e12501010000000000 [2021-02-01 09:52:02][DEBUG] : APS data confirm response for request with id 184: 00 [2021-02-01 09:52:02][DEBUG] : Request id: 0xb8 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x1cb6000c00050002f584ffcf [2021-02-01 09:52:05][DEBUG] : Frame received: 0x0eb7000700a600 [2021-02-01 09:52:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:05][DEBUG] : Send: 0x04b60007000000 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x04b60013000c00229e02949e0101d000000000 [2021-02-01 09:52:05][DEBUG] : APS data confirm response for request with id 158: d0 [2021-02-01 09:52:05][DEBUG] : Request id: 0x9e 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:52:05][DEBUG] : Error while sending 158 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:52:05][DEBUG] : [0x9e94] Delivery error for seq # 0x9d, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:52:05][DEBUG] : [0x9e94:1] Manufacturer: None [2021-02-01 09:52:05][DEBUG] : [0x9e94:1] Model: None [2021-02-01 09:52:05][DEBUG] : [0x130b:1] Manufacturer: Philips [2021-02-01 09:52:05][DEBUG] : [0x130b:1] Model: RWL021 [2021-02-01 09:52:05][DEBUG] : [0x130b:2] Manufacturer: Philips [2021-02-01 09:52:05][DEBUG] : [0x130b:2] Model: RWL021 [2021-02-01 09:52:05][DEBUG] : [0x507c:1] Manufacturer: IKEA of Sweden [2021-02-01 09:52:05][DEBUG] : [0x507c:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:52:05][DEBUG] : [0x924f:1] Manufacturer: IKEA of Sweden [2021-02-01 09:52:05][DEBUG] : [0x924f:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:52:05][DEBUG] : [0xd759:1] Manufacturer: IKEA of Sweden [2021-02-01 09:52:05][DEBUG] : [0xd759:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:52:05][DEBUG] : [0x35b7:1] Manufacturer: IKEA of Sweden [2021-02-01 09:52:05][DEBUG] : [0x35b7:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:52:05][DEBUG] : [0x8a87:1] Manufacturer: IKEA of Sweden [2021-02-01 09:52:05][DEBUG] : [0x8a87:1] Model: TRADFRI bulb E14 W op/ch 400lm [2021-02-01 09:52:05][DEBUG] : [0xbd2f:1] Manufacturer: IKEA of Sweden [2021-02-01 09:52:05][DEBUG] : [0xbd2f:1] Model: TRADFRI bulb E14 W op/ch 400lm [2021-02-01 09:52:05][DEBUG] : [0xabb3:1] Manufacturer: IKEA of Sweden [2021-02-01 09:52:05][DEBUG] : [0xabb3:1] Model: TRADFRI control outlet [2021-02-01 09:52:05][DEBUG] : Sending Zigbee request with tsn 185 under 186 request id, data: b'00b90004000500' [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_request (22, 186, 0, , 260, 0, 1, b'\x00\xb9\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:05][DEBUG] : Send: 0x12b7001d001600ba0002d70f010401000001070000b900040005000200 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x12b7000900020022ba [2021-02-01 09:52:05][DEBUG] : APS data request response: [2, , 186] [2021-02-01 09:52:05][DEBUG] : Frame received: 0x0eb8000700a600 [2021-02-01 09:52:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:05][DEBUG] : Send: 0x04b80007000000 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x04b80013000c0022ba02d70f01010000000000 [2021-02-01 09:52:05][DEBUG] : APS data confirm response for request with id 186: 00 [2021-02-01 09:52:05][DEBUG] : Request id: 0xba 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x0eb9000700aa00 [2021-02-01 09:52:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:05][DEBUG] : Send: 0x17b9000800010001 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x17b90027002000220200000102d70f0104010000090018b90104008605008600afffbff80000c8 [2021-02-01 09:52:05][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\xb9\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 191, 248, 0, 0, -56] [2021-02-01 09:52:05][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=185 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:05][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18b901040086050086' [2021-02-01 09:52:05][DEBUG] : Sending Zigbee request with tsn 187 under 188 request id, data: b'00bb000400' [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_request (20, 188, 0, , 260, 0, 1, b'\x00\xbb\x00\x04\x00', 2, 0) [2021-02-01 09:52:05][DEBUG] : Send: 0x12ba001b001400bc0002d70f010401000001050000bb0004000200 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x12ba000900020022bc [2021-02-01 09:52:05][DEBUG] : APS data request response: [2, , 188] [2021-02-01 09:52:05][DEBUG] : Frame received: 0x0ebb000700a600 [2021-02-01 09:52:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:05][DEBUG] : Send: 0x04bb0007000000 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x04bb0013000c0022bc02d70f01010000000000 [2021-02-01 09:52:05][DEBUG] : APS data confirm response for request with id 188: 00 [2021-02-01 09:52:05][DEBUG] : Request id: 0xbc 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x0ebc000700aa00 [2021-02-01 09:52:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:05][DEBUG] : Send: 0x17bc000800010001 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x17bc0024001d00220200000102d70f0104010000060018bb0104008600afffbff80000c6 [2021-02-01 09:52:05][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xbb\x01\x04\x00\x86', 0, 175, 255, 191, 248, 0, 0, -58] [2021-02-01 09:52:05][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=187 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:05][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18bb01040086' [2021-02-01 09:52:05][DEBUG] : Sending Zigbee request with tsn 189 under 190 request id, data: b'00bd000500' [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_request (20, 190, 0, , 260, 0, 1, b'\x00\xbd\x00\x05\x00', 2, 0) [2021-02-01 09:52:05][DEBUG] : Send: 0x12bd001b001400be0002d70f010401000001050000bd0005000200 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x12bd000900020022be [2021-02-01 09:52:05][DEBUG] : APS data request response: [2, , 190] [2021-02-01 09:52:05][DEBUG] : Frame received: 0x0ebe000700a600 [2021-02-01 09:52:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:05][DEBUG] : Send: 0x04be0007000000 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x04be0013000c0022be02d70f01010000000000 [2021-02-01 09:52:05][DEBUG] : APS data confirm response for request with id 190: 00 [2021-02-01 09:52:05][DEBUG] : Request id: 0xbe 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x0ebf000700aa00 [2021-02-01 09:52:05][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:05][DEBUG] : Send: 0x17bf000800010001 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x17bf0024001d00220200000102d70f0104010000060018bd0105008600afffbff80000c7 [2021-02-01 09:52:05][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xbd\x01\x05\x00\x86', 0, 175, 255, 191, 248, 0, 0, -57] [2021-02-01 09:52:05][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=189 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:05][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18bd01050086' [2021-02-01 09:52:05][DEBUG] : [0x0fd7:1] Manufacturer: None [2021-02-01 09:52:05][DEBUG] : [0x0fd7:1] Model: None [2021-02-01 09:52:05][DEBUG] : [0xa72a] Extending timeout for 0xbf request [2021-02-01 09:52:05][DEBUG] : Sending Zigbee request with tsn 191 under 192 request id, data: b'00bf0004000500' [2021-02-01 09:52:05][DEBUG] : Command Command.aps_data_request (22, 192, 0, , 260, 0, 1, b'\x00\xbf\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:05][DEBUG] : Send: 0x12c0001d001600c000022aa7010401000001070000bf00040005000200 [2021-02-01 09:52:05][DEBUG] : Frame received: 0x12c0000900020022c0 [2021-02-01 09:52:05][DEBUG] : APS data request response: [2, , 192] [2021-02-01 09:52:10][DEBUG] : Frame received: 0x1cc1000c000500022aa7ffcb [2021-02-01 09:52:15][DEBUG] : Frame received: 0x0ec2000700a600 [2021-02-01 09:52:15][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:16][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:16][DEBUG] : Send: 0x04c10007000000 [2021-02-01 09:52:16][DEBUG] : Frame received: 0x04c10013000c0022c0022aa70101d000000000 [2021-02-01 09:52:16][DEBUG] : APS data confirm response for request with id 192: d0 [2021-02-01 09:52:16][DEBUG] : Request id: 0xc0 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:52:16][DEBUG] : Error while sending 192 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:52:16][DEBUG] : [0xa72a] Delivery error for seq # 0xbf, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:52:16][DEBUG] : [0xa72a] Extending timeout for 0xc1 request [2021-02-01 09:52:16][DEBUG] : Sending Zigbee request with tsn 193 under 194 request id, data: b'00c1000400' [2021-02-01 09:52:16][DEBUG] : Command Command.aps_data_request (20, 194, 0, , 260, 0, 1, b'\x00\xc1\x00\x04\x00', 2, 0) [2021-02-01 09:52:16][DEBUG] : Send: 0x12c2001b001400c200022aa7010401000001050000c10004000200 [2021-02-01 09:52:16][DEBUG] : Frame received: 0x12c2000900020022c2 [2021-02-01 09:52:16][DEBUG] : APS data request response: [2, , 194] [2021-02-01 09:52:21][DEBUG] : Frame received: 0x0ec3000700aa00 [2021-02-01 09:52:21][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:21][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:21][DEBUG] : Send: 0x17c3000800010001 [2021-02-01 09:52:21][DEBUG] : Frame received: 0x17c30025001e00220200000102d70f0104010800070018480a000020fe00afffcef80000c7 [2021-02-01 09:52:21][DEBUG] : APS data indication response: [30, , , 1, , 1, 260, 8, b'\x18H \x00\x00 \xfe', 0, 175, 255, 206, 248, 0, 0, -57] [2021-02-01 09:52:21][DEBUG] : [0x0fd7:1:0x0008] ZCL deserialize: manufacturer=None tsn=72 command_id=Command.Report_Attributes> [2021-02-01 09:52:21][DEBUG] : [0x0fd7:1:0x0008] ZCL request 0x000a: [[Attribute(attrid=0, value=)]] [2021-02-01 09:52:21][INFO] : [20:91:8a:00:00:06:b4:b2][listener.general_command] Cluster: ClusterId: 0x0008 command_id: manufacturer=None tsn=72 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=)]],) [2021-02-01 09:52:21][DEBUG] : [0x0fd7:1:0x0008] Attribute report received: current_level=254 [2021-02-01 09:52:21][INFO] : [20:91:8a:00:00:06:b4:b2][listener.attribute_updated] Received an attribute update 0=254 on cluster 8 [2021-02-01 09:52:21][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'18480a000020fe' [2021-02-01 09:52:21][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:b2, 1, 8, 0, 254)) [2021-02-01 09:52:21][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:b2, 1, 8, 0, 254)) completed [2021-02-01 09:52:21][DEBUG] : executing functools.partial() [2021-02-01 09:52:21][DEBUG] : operation functools.partial() completed [2021-02-01 09:52:21][DEBUG] : Send to jeedom : {'devices': {'20:91:8a:00:00:06:b4:b2': {'1': {'8': {'0': {'value': '254', 'cluster_name': 'Level control'}}}}}} [2021-02-01 09:52:21][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80 [2021-02-01 09:52:21][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:52:22][DEBUG] : Frame received: 0x0ec4000700aa00 [2021-02-01 09:52:22][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:22][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:22][DEBUG] : Send: 0x17c4000800010001 [2021-02-01 09:52:22][DEBUG] : Frame received: 0x17c400390032002202000000027c5000000038801b00000000f8ff071e000e00109dc5c69ebeb9cca8caa59d9d9c9caf9f00afffcef80000cc [2021-02-01 09:52:22][DEBUG] : APS data indication response: [50, , , 0, , 0, 0, 32824, b'\x00\x00\x00\xf8\xff\x07\x1e\x00\x0e\x00\x10\x9d\xc5\xc6\x9e\xbe\xb9\xcc\xa8\xca\xa5\x9d\x9d\x9c\x9c\xaf\x9f', 0, 175, 255, 206, 248, 0, 0, -52] [2021-02-01 09:52:22][DEBUG] : [0x507c:zdo] ZDO request ZDOCmd.Mgmt_NWK_Update_rsp: [, , 30, 14, [157, 197, 198, 158, 190, 185, 204, 168, 202, 165, 157, 157, 156, 156, 175, 159]] [2021-02-01 09:52:22][DEBUG] : [0x507c:zdo] Unsupported ZDO request:ZDOCmd.Mgmt_NWK_Update_rsp [2021-02-01 09:52:22][DEBUG] : 'aps_data_indication' response from , ep: 0, profile: 0x0000, cluster_id: 0x8038, data: b'000000f8ff071e000e00109dc5c69ebeb9cca8caa59d9d9c9caf9f' [2021-02-01 09:52:23][DEBUG] : [0x25e1:1] Manufacturer: None [2021-02-01 09:52:23][DEBUG] : [0x25e1:1] Model: None [2021-02-01 09:52:23][INFO] : 200 GET /device/all?with_attributes=2 (127.0.0.1) 351904.87ms [2021-02-01 09:52:26][DEBUG] : Frame received: 0x0ec5000700a600 [2021-02-01 09:52:26][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:26][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:26][DEBUG] : Send: 0x04c50007000000 [2021-02-01 09:52:26][DEBUG] : Frame received: 0x04c50013000c0022c2022aa70101d000000000 [2021-02-01 09:52:26][DEBUG] : APS data confirm response for request with id 194: d0 [2021-02-01 09:52:26][DEBUG] : Request id: 0xc2 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:52:26][DEBUG] : Error while sending 194 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:52:26][DEBUG] : [0xa72a] Delivery error for seq # 0xc1, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:52:26][DEBUG] : [0xa72a] Extending timeout for 0xc3 request [2021-02-01 09:52:26][DEBUG] : Sending Zigbee request with tsn 195 under 196 request id, data: b'00c3000500' [2021-02-01 09:52:26][DEBUG] : Command Command.aps_data_request (20, 196, 0, , 260, 0, 1, b'\x00\xc3\x00\x05\x00', 2, 0) [2021-02-01 09:52:26][DEBUG] : Send: 0x12c6001b001400c400022aa7010401000001050000c30005000200 [2021-02-01 09:52:26][DEBUG] : Frame received: 0x12c6000900020022c4 [2021-02-01 09:52:26][DEBUG] : APS data request response: [2, , 196] [2021-02-01 09:52:27][DEBUG] : Frame received: 0x0ec7000700aa00 [2021-02-01 09:52:27][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:27][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:27][DEBUG] : Send: 0x17c7000800010001 [2021-02-01 09:52:27][DEBUG] : Frame received: 0x17c70025001e0022020000010217350104010800070018570a000020fe00afd797f80000bd [2021-02-01 09:52:27][DEBUG] : APS data indication response: [30, , , 1, , 1, 260, 8, b'\x18W \x00\x00 \xfe', 0, 175, 215, 151, 248, 0, 0, -67] [2021-02-01 09:52:27][DEBUG] : [0x3517:1:0x0008] ZCL deserialize: manufacturer=None tsn=87 command_id=Command.Report_Attributes> [2021-02-01 09:52:27][DEBUG] : [0x3517:1:0x0008] ZCL request 0x000a: [[Attribute(attrid=0, value=)]] [2021-02-01 09:52:27][INFO] : [20:91:8a:00:00:06:b4:b4][listener.general_command] Cluster: ClusterId: 0x0008 command_id: manufacturer=None tsn=87 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=)]],) [2021-02-01 09:52:27][DEBUG] : [0x3517:1:0x0008] Attribute report received: current_level=254 [2021-02-01 09:52:27][INFO] : [20:91:8a:00:00:06:b4:b4][listener.attribute_updated] Received an attribute update 0=254 on cluster 8 [2021-02-01 09:52:27][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:b4, 1, 8, 0, 254)) [2021-02-01 09:52:27][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'18570a000020fe' [2021-02-01 09:52:27][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:b4, 1, 8, 0, 254)) completed [2021-02-01 09:52:27][DEBUG] : executing functools.partial() [2021-02-01 09:52:27][DEBUG] : operation functools.partial() completed [2021-02-01 09:52:27][DEBUG] : Send to jeedom : {'devices': {'20:91:8a:00:00:06:b4:b4': {'1': {'8': {'0': {'value': '254', 'cluster_name': 'Level control'}}}}}} [2021-02-01 09:52:27][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80 [2021-02-01 09:52:27][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:52:28][DEBUG] : Frame received: 0x0ec8000700aa00 [2021-02-01 09:52:28][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:28][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:28][DEBUG] : Send: 0x17c8000800010001 [2021-02-01 09:52:28][DEBUG] : Frame received: 0x17c80025001e00220200000102475a0104010800070018480a000020fe00afffd5f80000c7 [2021-02-01 09:52:28][DEBUG] : APS data indication response: [30, , , 1, , 1, 260, 8, b'\x18H \x00\x00 \xfe', 0, 175, 255, 213, 248, 0, 0, -57] [2021-02-01 09:52:28][DEBUG] : [0x5a47:1:0x0008] ZCL deserialize: manufacturer=None tsn=72 command_id=Command.Report_Attributes> [2021-02-01 09:52:28][DEBUG] : [0x5a47:1:0x0008] ZCL request 0x000a: [[Attribute(attrid=0, value=)]] [2021-02-01 09:52:28][INFO] : [20:91:8a:00:00:06:b4:ba][listener.general_command] Cluster: ClusterId: 0x0008 command_id: manufacturer=None tsn=72 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=)]],) [2021-02-01 09:52:28][DEBUG] : [0x5a47:1:0x0008] Attribute report received: current_level=254 [2021-02-01 09:52:28][INFO] : [20:91:8a:00:00:06:b4:ba][listener.attribute_updated] Received an attribute update 0=254 on cluster 8 [2021-02-01 09:52:28][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:ba, 1, 8, 0, 254)) [2021-02-01 09:52:28][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'18480a000020fe' [2021-02-01 09:52:28][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (20:91:8a:00:00:06:b4:ba, 1, 8, 0, 254)) completed [2021-02-01 09:52:28][DEBUG] : executing functools.partial() [2021-02-01 09:52:29][DEBUG] : operation functools.partial() completed [2021-02-01 09:52:29][DEBUG] : Send to jeedom : {'devices': {'20:91:8a:00:00:06:b4:ba': {'1': {'8': {'0': {'value': '254', 'cluster_name': 'Level control'}}}}}} [2021-02-01 09:52:29][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80 [2021-02-01 09:52:29][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:52:30][DEBUG] : [0x25e1] Extending timeout for 0xc5 request [2021-02-01 09:52:30][DEBUG] : Sending Zigbee request with tsn 197 under 198 request id, data: b'00c5000400' [2021-02-01 09:52:30][DEBUG] : Command Command.aps_data_request (20, 198, 0, , 260, 0, 1, b'\x00\xc5\x00\x04\x00', 2, 0) [2021-02-01 09:52:30][DEBUG] : Send: 0x12c9001b001400c60002e125010401000001050000c50004000200 [2021-02-01 09:52:30][DEBUG] : Frame received: 0x12c9000900020022c6 [2021-02-01 09:52:30][DEBUG] : APS data request response: [2, , 198] [2021-02-01 09:52:30][DEBUG] : Frame received: 0x0eca000700a600 [2021-02-01 09:52:30][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:30][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:30][DEBUG] : Send: 0x04ca0007000000 [2021-02-01 09:52:30][DEBUG] : Frame received: 0x04ca0013000c0022c602e12501010000000000 [2021-02-01 09:52:30][DEBUG] : APS data confirm response for request with id 198: 00 [2021-02-01 09:52:30][DEBUG] : Request id: 0xc6 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:36][DEBUG] : [ApplicationHandler.prepare] Json arg : None [2021-02-01 09:52:36][INFO] : 200 GET /application/info (127.0.0.1) 1.64ms [2021-02-01 09:52:36][DEBUG] : [DeviceHandler.prepare] Json arg : None [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 199 under 200 request id, data: b'00c70004000500' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (22, 200, 0, , 260, 0, 1, b'\x00\xc7\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12cb001d001600c800021735010401000001070000c700040005000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12cb000900020022c8 [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 200] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ecc000700a600 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04cc0007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04cc0013000c0022c802173501010000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 200: 00 [2021-02-01 09:52:36][DEBUG] : Request id: 0xc8 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:36][DEBUG] : [NetworkHandler.prepare] Json arg : None [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 16 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 10 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 11 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 9 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 11 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 12 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 13 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 14 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 14 neighbours [2021-02-01 09:52:36][DEBUG] : [map.scan] Done scanning. Total 0 neighbours [2021-02-01 09:52:36][INFO] : 200 GET /network/map (127.0.0.1) 29.33ms [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ecd000700aa00 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:36][DEBUG] : Send: 0x17cd000800010001 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ece000700ae00 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x17cd002700200026020000010217350104010000090018c70104008605008600afd7d8f80000bd [2021-02-01 09:52:36][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\xc7\x01\x04\x00\x86\x05\x00\x86', 0, 175, 215, 216, 248, 0, 0, -67] [2021-02-01 09:52:36][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=199 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:36][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18c701040086050086' [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 201 under 202 request id, data: b'00c9000400' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04ce0007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04ce0013000c0022c4022aa70101d000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 196: d0 [2021-02-01 09:52:36][DEBUG] : Request id: 0xc4 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:52:36][DEBUG] : Error while sending 196 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:52:36][DEBUG] : [0xa72a] Delivery error for seq # 0xc3, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:52:36][DEBUG] : [0xa72a:1] Manufacturer: None [2021-02-01 09:52:36][DEBUG] : [0xa72a:1] Model: None [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 203 under 204 request id, data: b'00cb0004000500' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (20, 202, 0, , 260, 0, 1, b'\x00\xc9\x00\x04\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12cf001b001400ca00021735010401000001050000c90004000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12cf000900020022ca [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 202] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (22, 204, 0, , 260, 0, 1, b'\x00\xcb\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12d0001d001600cc0002739b010401000001070000cb00040005000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ed0000700a600 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12d0000900020026cc [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 204] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04d10007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04d10013000c0022ca02173501010000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 202: 00 [2021-02-01 09:52:36][DEBUG] : Request id: 0xca 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ed2000700a600 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04d20007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04d20013000c0022cc02739b01010000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 204: 00 [2021-02-01 09:52:36][DEBUG] : Request id: 0xcc 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ed3000700aa00 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:36][DEBUG] : Send: 0x17d3000800010001 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x17d30024001d0022020000010217350104010000060018c90104008600afd7d8f80000bd [2021-02-01 09:52:36][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xc9\x01\x04\x00\x86', 0, 175, 215, 216, 248, 0, 0, -67] [2021-02-01 09:52:36][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=201 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:36][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18c901040086' [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 205 under 206 request id, data: b'00cd000500' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (20, 206, 0, , 260, 0, 1, b'\x00\xcd\x00\x05\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12d4001b001400ce00021735010401000001050000cd0005000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ed4000700aa00 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12d400090002002ace [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 206] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:36][DEBUG] : Send: 0x17d5000800010001 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x17d50027002000220200000102739b0104010000090018cb0104008605008600afcfd8f80000bc [2021-02-01 09:52:36][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\xcb\x01\x04\x00\x86\x05\x00\x86', 0, 175, 207, 216, 248, 0, 0, -68] [2021-02-01 09:52:36][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=203 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:36][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18cb01040086050086' [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 207 under 208 request id, data: b'00cf000400' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (20, 208, 0, , 260, 0, 1, b'\x00\xcf\x00\x04\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12d6001b001400d00002739b010401000001050000cf0004000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12d6000900020022d0 [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 208] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ed7000700a600 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04d70007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04d70013000c0026ce02173501010000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 206: 00 [2021-02-01 09:52:36][DEBUG] : Request id: 0xce 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04d80007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04d80013000c0022d002739b01010000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 208: 00 [2021-02-01 09:52:36][DEBUG] : Request id: 0xd0 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ed9000700aa00 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:36][DEBUG] : Send: 0x17d9000800010001 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x17d90024001d0022020000010217350104010000060018cd0105008600afd7d8f80000bd [2021-02-01 09:52:36][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xcd\x01\x05\x00\x86', 0, 175, 215, 216, 248, 0, 0, -67] [2021-02-01 09:52:36][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: manufacturer=None tsn=205 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:36][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18cd01050086' [2021-02-01 09:52:36][DEBUG] : [0x3517:1] Manufacturer: None [2021-02-01 09:52:36][DEBUG] : [0x3517:1] Model: None [2021-02-01 09:52:36][DEBUG] : [0x9e94] Extending timeout for 0xd1 request [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 209 under 210 request id, data: b'00d10004000500' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (22, 210, 0, , 260, 0, 1, b'\x00\xd1\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12da001d001600d20002949e010401000001070000d100040005000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0eda000700aa00 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12da00090002002ad2 [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 210] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:36][DEBUG] : Send: 0x17db000800010001 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x17db0024001d00220200000102739b0104010000060018cf0104008600afd7d8f80000bd [2021-02-01 09:52:36][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xcf\x01\x04\x00\x86', 0, 175, 215, 216, 248, 0, 0, -67] [2021-02-01 09:52:36][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=207 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:36][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18cf01040086' [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 211 under 212 request id, data: b'00d3000500' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (20, 212, 0, , 260, 0, 1, b'\x00\xd3\x00\x05\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12dc001b001400d40002739b010401000001050000d30005000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12dc000900020022d4 [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 212] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0edd000700a600 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04dd0007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04dd0013000c0022d402739b01010000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 212: 00 [2021-02-01 09:52:36][DEBUG] : Request id: 0xd4 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ede000700aa00 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:52:36][DEBUG] : Send: 0x17de000800010001 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x17de0024001d00220200000102739b0104010000060018d30105008600afcf97f80000bc [2021-02-01 09:52:36][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xd3\x01\x05\x00\x86', 0, 175, 207, 151, 248, 0, 0, -68] [2021-02-01 09:52:36][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=211 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:52:36][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18d301050086' [2021-02-01 09:52:36][DEBUG] : [0x9b73:1] Manufacturer: None [2021-02-01 09:52:36][DEBUG] : [0x9b73:1] Model: None [2021-02-01 09:52:36][DEBUG] : [0x346d] Extending timeout for 0xd5 request [2021-02-01 09:52:36][DEBUG] : Sending Zigbee request with tsn 213 under 214 request id, data: b'00d50004000500' [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_request (22, 214, 0, , 260, 0, 1, b'\x00\xd5\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:52:36][DEBUG] : Send: 0x12df001d001600d600026d34010401000001070000d500040005000200 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x12df000900020022d6 [2021-02-01 09:52:36][DEBUG] : APS data request response: [2, , 214] [2021-02-01 09:52:36][DEBUG] : Frame received: 0x0ee0000700a600 [2021-02-01 09:52:36][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:36][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:36][DEBUG] : Send: 0x04e00007000000 [2021-02-01 09:52:36][DEBUG] : Frame received: 0x04e00013000c0022d6026d3401010000000000 [2021-02-01 09:52:36][DEBUG] : APS data confirm response for request with id 214: 00 [2021-02-01 09:52:36][DEBUG] : Request id: 0xd6 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:52:46][DEBUG] : Frame received: 0x0ee1000700a600 [2021-02-01 09:52:46][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:46][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:46][DEBUG] : Send: 0x04e10007000000 [2021-02-01 09:52:46][DEBUG] : Frame received: 0x04e10013000c0022d202949e0101d000000000 [2021-02-01 09:52:46][DEBUG] : APS data confirm response for request with id 210: d0 [2021-02-01 09:52:46][DEBUG] : Request id: 0xd2 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:52:46][DEBUG] : Error while sending 210 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:52:46][DEBUG] : [0x9e94] Delivery error for seq # 0xd1, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:52:46][DEBUG] : [0x9e94] Extending timeout for 0xd7 request [2021-02-01 09:52:46][DEBUG] : Sending Zigbee request with tsn 215 under 216 request id, data: b'00d7000400' [2021-02-01 09:52:46][DEBUG] : Command Command.aps_data_request (20, 216, 0, , 260, 0, 1, b'\x00\xd7\x00\x04\x00', 2, 0) [2021-02-01 09:52:46][DEBUG] : Send: 0x12e2001b001400d80002949e010401000001050000d70004000200 [2021-02-01 09:52:46][DEBUG] : Frame received: 0x12e2000900020022d8 [2021-02-01 09:52:46][DEBUG] : APS data request response: [2, , 216] [2021-02-01 09:52:57][DEBUG] : Frame received: 0x0ee3000700a600 [2021-02-01 09:52:57][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:57][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:57][DEBUG] : Send: 0x04e30007000000 [2021-02-01 09:52:57][DEBUG] : Frame received: 0x04e30013000c0022d802949e0101d000000000 [2021-02-01 09:52:57][DEBUG] : APS data confirm response for request with id 216: d0 [2021-02-01 09:52:57][DEBUG] : Request id: 0xd8 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:52:57][DEBUG] : Error while sending 216 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:52:57][DEBUG] : [0x9e94] Delivery error for seq # 0xd7, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:52:57][DEBUG] : [0x9e94] Extending timeout for 0xd9 request [2021-02-01 09:52:57][DEBUG] : Sending Zigbee request with tsn 217 under 218 request id, data: b'00d9000500' [2021-02-01 09:52:57][DEBUG] : Command Command.aps_data_request (20, 218, 0, , 260, 0, 1, b'\x00\xd9\x00\x05\x00', 2, 0) [2021-02-01 09:52:57][DEBUG] : Send: 0x12e4001b001400da0002949e010401000001050000d90005000200 [2021-02-01 09:52:57][DEBUG] : Frame received: 0x12e4000900020022da [2021-02-01 09:52:57][DEBUG] : APS data request response: [2, , 218] [2021-02-01 09:52:58][DEBUG] : [0x25e1] Extending timeout for 0xdb request [2021-02-01 09:52:58][DEBUG] : Sending Zigbee request with tsn 219 under 220 request id, data: b'00db000500' [2021-02-01 09:52:58][DEBUG] : Command Command.aps_data_request (20, 220, 0, , 260, 0, 1, b'\x00\xdb\x00\x05\x00', 2, 0) [2021-02-01 09:52:58][DEBUG] : Send: 0x12e5001b001400dc0002e125010401000001050000db0005000200 [2021-02-01 09:52:58][DEBUG] : Frame received: 0x12e5000900020022dc [2021-02-01 09:52:58][DEBUG] : APS data request response: [2, , 220] [2021-02-01 09:52:58][DEBUG] : Frame received: 0x0ee6000700a600 [2021-02-01 09:52:58][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:52:58][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:52:58][DEBUG] : Send: 0x04e60007000000 [2021-02-01 09:52:58][DEBUG] : Frame received: 0x04e60013000c0022dc02e12501010000000000 [2021-02-01 09:52:58][DEBUG] : APS data confirm response for request with id 220: 00 [2021-02-01 09:52:58][DEBUG] : Request id: 0xdc 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:01][DEBUG] : Frame received: 0x1ce7000c00050002949effd1 [2021-02-01 09:53:04][DEBUG] : [0x346d] Extending timeout for 0xdd request [2021-02-01 09:53:04][DEBUG] : Sending Zigbee request with tsn 221 under 222 request id, data: b'00dd000400' [2021-02-01 09:53:04][DEBUG] : Command Command.aps_data_request (20, 222, 0, , 260, 0, 1, b'\x00\xdd\x00\x04\x00', 2, 0) [2021-02-01 09:53:04][DEBUG] : Send: 0x12e7001b001400de00026d34010401000001050000dd0004000200 [2021-02-01 09:53:04][DEBUG] : Frame received: 0x12e7000900020022de [2021-02-01 09:53:04][DEBUG] : APS data request response: [2, , 222] [2021-02-01 09:53:04][DEBUG] : Frame received: 0x0ee8000700a600 [2021-02-01 09:53:04][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:04][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:04][DEBUG] : Send: 0x04e80007000000 [2021-02-01 09:53:04][DEBUG] : Frame received: 0x04e80013000c0022de026d3401010000000000 [2021-02-01 09:53:04][DEBUG] : APS data confirm response for request with id 222: 00 [2021-02-01 09:53:04][DEBUG] : Request id: 0xde 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:05][DEBUG] : Frame received: 0x1ce9000c00050002f584ffcf [2021-02-01 09:53:07][DEBUG] : Frame received: 0x0eea000700a600 [2021-02-01 09:53:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:07][DEBUG] : Send: 0x04e90007000000 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x04e90013000c0022da02949e0101d000000000 [2021-02-01 09:53:07][DEBUG] : APS data confirm response for request with id 218: d0 [2021-02-01 09:53:07][DEBUG] : Request id: 0xda 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:53:07][DEBUG] : Error while sending 218 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:53:07][DEBUG] : [0x9e94] Delivery error for seq # 0xd9, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:53:07][DEBUG] : [0x9e94:1] Manufacturer: None [2021-02-01 09:53:07][DEBUG] : [0x9e94:1] Model: None [2021-02-01 09:53:07][DEBUG] : [0x130b:1] Manufacturer: Philips [2021-02-01 09:53:07][DEBUG] : [0x130b:1] Model: RWL021 [2021-02-01 09:53:07][DEBUG] : [0x130b:2] Manufacturer: Philips [2021-02-01 09:53:07][DEBUG] : [0x130b:2] Model: RWL021 [2021-02-01 09:53:07][DEBUG] : [0x507c:1] Manufacturer: IKEA of Sweden [2021-02-01 09:53:07][DEBUG] : [0x507c:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:53:07][DEBUG] : [0x924f:1] Manufacturer: IKEA of Sweden [2021-02-01 09:53:07][DEBUG] : [0x924f:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:53:07][DEBUG] : [0xd759:1] Manufacturer: IKEA of Sweden [2021-02-01 09:53:07][DEBUG] : [0xd759:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:53:07][DEBUG] : [0x35b7:1] Manufacturer: IKEA of Sweden [2021-02-01 09:53:07][DEBUG] : [0x35b7:1] Model: TRADFRI bulb E27 W opal 1000lm [2021-02-01 09:53:07][DEBUG] : [0x8a87:1] Manufacturer: IKEA of Sweden [2021-02-01 09:53:07][DEBUG] : [0x8a87:1] Model: TRADFRI bulb E14 W op/ch 400lm [2021-02-01 09:53:07][DEBUG] : [0xbd2f:1] Manufacturer: IKEA of Sweden [2021-02-01 09:53:07][DEBUG] : [0xbd2f:1] Model: TRADFRI bulb E14 W op/ch 400lm [2021-02-01 09:53:07][DEBUG] : [0xabb3:1] Manufacturer: IKEA of Sweden [2021-02-01 09:53:07][DEBUG] : [0xabb3:1] Model: TRADFRI control outlet [2021-02-01 09:53:07][DEBUG] : Sending Zigbee request with tsn 223 under 224 request id, data: b'00df0004000500' [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_request (22, 224, 0, , 260, 0, 1, b'\x00\xdf\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:53:07][DEBUG] : Send: 0x12ea001d001600e00002d70f010401000001070000df00040005000200 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x12ea000900020022e0 [2021-02-01 09:53:07][DEBUG] : APS data request response: [2, , 224] [2021-02-01 09:53:07][DEBUG] : Frame received: 0x0eeb000700a600 [2021-02-01 09:53:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:07][DEBUG] : Send: 0x04eb0007000000 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x04eb0013000c0022e002d70f01010000000000 [2021-02-01 09:53:07][DEBUG] : APS data confirm response for request with id 224: 00 [2021-02-01 09:53:07][DEBUG] : Request id: 0xe0 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x0eec000700aa00 [2021-02-01 09:53:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:07][DEBUG] : Send: 0x17ec000800010001 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x17ec0027002000220200000102d70f0104010000090018df0104008605008600affff5f80000c7 [2021-02-01 09:53:07][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\xdf\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 245, 248, 0, 0, -57] [2021-02-01 09:53:07][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=223 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:07][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18df01040086050086' [2021-02-01 09:53:07][DEBUG] : Sending Zigbee request with tsn 225 under 226 request id, data: b'00e1000400' [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_request (20, 226, 0, , 260, 0, 1, b'\x00\xe1\x00\x04\x00', 2, 0) [2021-02-01 09:53:07][DEBUG] : Send: 0x12ed001b001400e20002d70f010401000001050000e10004000200 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x12ed000900020022e2 [2021-02-01 09:53:07][DEBUG] : APS data request response: [2, , 226] [2021-02-01 09:53:07][DEBUG] : Frame received: 0x0eee000700a600 [2021-02-01 09:53:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:07][DEBUG] : Send: 0x04ee0007000000 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x04ee0013000c0022e202d70f01010000000000 [2021-02-01 09:53:07][DEBUG] : APS data confirm response for request with id 226: 00 [2021-02-01 09:53:07][DEBUG] : Request id: 0xe2 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x0eef000700aa00 [2021-02-01 09:53:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:07][DEBUG] : Send: 0x17ef000800010001 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x17ef0024001d00220200000102d70f0104010000060018e10104008600affff5f80000c7 [2021-02-01 09:53:07][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xe1\x01\x04\x00\x86', 0, 175, 255, 245, 248, 0, 0, -57] [2021-02-01 09:53:07][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=225 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:07][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18e101040086' [2021-02-01 09:53:07][DEBUG] : Sending Zigbee request with tsn 227 under 228 request id, data: b'00e3000500' [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_request (20, 228, 0, , 260, 0, 1, b'\x00\xe3\x00\x05\x00', 2, 0) [2021-02-01 09:53:07][DEBUG] : Send: 0x12f0001b001400e40002d70f010401000001050000e30005000200 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x12f0000900020022e4 [2021-02-01 09:53:07][DEBUG] : APS data request response: [2, , 228] [2021-02-01 09:53:07][DEBUG] : Frame received: 0x0ef1000700a600 [2021-02-01 09:53:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:07][DEBUG] : Send: 0x04f10007000000 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x04f10013000c0022e402d70f01010000000000 [2021-02-01 09:53:07][DEBUG] : APS data confirm response for request with id 228: 00 [2021-02-01 09:53:07][DEBUG] : Request id: 0xe4 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x0ef2000700aa00 [2021-02-01 09:53:07][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:07][DEBUG] : Send: 0x17f2000800010001 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x17f20024001d00220200000102d70f0104010000060018e30105008600affff5f80000c7 [2021-02-01 09:53:07][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xe3\x01\x05\x00\x86', 0, 175, 255, 245, 248, 0, 0, -57] [2021-02-01 09:53:07][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: manufacturer=None tsn=227 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:07][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18e301050086' [2021-02-01 09:53:07][DEBUG] : [0x0fd7:1] Manufacturer: None [2021-02-01 09:53:07][DEBUG] : [0x0fd7:1] Model: None [2021-02-01 09:53:07][DEBUG] : [0xa72a] Extending timeout for 0xe5 request [2021-02-01 09:53:07][DEBUG] : Sending Zigbee request with tsn 229 under 230 request id, data: b'00e50004000500' [2021-02-01 09:53:07][DEBUG] : Command Command.aps_data_request (22, 230, 0, , 260, 0, 1, b'\x00\xe5\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:53:07][DEBUG] : Send: 0x12f3001d001600e600022aa7010401000001070000e500040005000200 [2021-02-01 09:53:07][DEBUG] : Frame received: 0x12f3000900020022e6 [2021-02-01 09:53:07][DEBUG] : APS data request response: [2, , 230] [2021-02-01 09:53:10][DEBUG] : Frame received: 0x1cf4000c000500022aa7ffcb [2021-02-01 09:53:17][DEBUG] : Frame received: 0x0ef5000700a600 [2021-02-01 09:53:17][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:17][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:17][DEBUG] : Send: 0x04f40007000000 [2021-02-01 09:53:17][DEBUG] : Frame received: 0x04f40013000c0022e6022aa70101d000000000 [2021-02-01 09:53:17][DEBUG] : APS data confirm response for request with id 230: d0 [2021-02-01 09:53:17][DEBUG] : Request id: 0xe6 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:53:17][DEBUG] : Error while sending 230 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:53:17][DEBUG] : [0xa72a] Delivery error for seq # 0xe5, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:53:17][DEBUG] : [0xa72a] Extending timeout for 0xe7 request [2021-02-01 09:53:17][DEBUG] : Sending Zigbee request with tsn 231 under 232 request id, data: b'00e7000400' [2021-02-01 09:53:17][DEBUG] : Command Command.aps_data_request (20, 232, 0, , 260, 0, 1, b'\x00\xe7\x00\x04\x00', 2, 0) [2021-02-01 09:53:17][DEBUG] : Send: 0x12f5001b001400e800022aa7010401000001050000e70004000200 [2021-02-01 09:53:17][DEBUG] : Frame received: 0x12f5000900020022e8 [2021-02-01 09:53:17][DEBUG] : APS data request response: [2, , 232] [2021-02-01 09:53:26][DEBUG] : [0x25e1:1] Manufacturer: None [2021-02-01 09:53:26][DEBUG] : [0x25e1:1] Model: None [2021-02-01 09:53:26][INFO] : 200 GET /device/all?with_attributes=0 (127.0.0.1) 352655.62ms [2021-02-01 09:53:27][DEBUG] : Frame received: 0x0ef6000700a600 [2021-02-01 09:53:27][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:27][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:27][DEBUG] : Send: 0x04f60007000000 [2021-02-01 09:53:27][DEBUG] : Frame received: 0x04f60013000c0022e8022aa70101d000000000 [2021-02-01 09:53:27][DEBUG] : APS data confirm response for request with id 232: d0 [2021-02-01 09:53:27][DEBUG] : Request id: 0xe8 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:53:28][DEBUG] : Error while sending 232 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:53:28][DEBUG] : [0xa72a] Delivery error for seq # 0xe7, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:53:28][DEBUG] : [0xa72a] Extending timeout for 0xe9 request [2021-02-01 09:53:28][DEBUG] : Sending Zigbee request with tsn 233 under 234 request id, data: b'00e9000500' [2021-02-01 09:53:28][DEBUG] : Command Command.aps_data_request (20, 234, 0, , 260, 0, 1, b'\x00\xe9\x00\x05\x00', 2, 0) [2021-02-01 09:53:28][DEBUG] : Send: 0x12f7001b001400ea00022aa7010401000001050000e90005000200 [2021-02-01 09:53:28][DEBUG] : Frame received: 0x12f7000900020022ea [2021-02-01 09:53:28][DEBUG] : APS data request response: [2, , 234] [2021-02-01 09:53:32][DEBUG] : [0x346d] Extending timeout for 0xeb request [2021-02-01 09:53:32][DEBUG] : Sending Zigbee request with tsn 235 under 236 request id, data: b'00eb000500' [2021-02-01 09:53:33][DEBUG] : Command Command.aps_data_request (20, 236, 0, , 260, 0, 1, b'\x00\xeb\x00\x05\x00', 2, 0) [2021-02-01 09:53:33][DEBUG] : Send: 0x12f8001b001400ec00026d34010401000001050000eb0005000200 [2021-02-01 09:53:33][DEBUG] : Frame received: 0x12f8000900020022ec [2021-02-01 09:53:33][DEBUG] : APS data request response: [2, , 236] [2021-02-01 09:53:33][DEBUG] : Frame received: 0x0ef9000700a600 [2021-02-01 09:53:33][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:33][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:33][DEBUG] : Send: 0x04f90007000000 [2021-02-01 09:53:33][DEBUG] : Frame received: 0x04f90013000c0022ec026d3401010000000000 [2021-02-01 09:53:33][DEBUG] : APS data confirm response for request with id 236: 00 [2021-02-01 09:53:33][DEBUG] : Request id: 0xec 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0efa000700a600 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:38][DEBUG] : Send: 0x04fa0007000000 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x04fa0013000c0022ea022aa70101d000000000 [2021-02-01 09:53:38][DEBUG] : APS data confirm response for request with id 234: d0 [2021-02-01 09:53:38][DEBUG] : Request id: 0xea 'aps_data_confirm' for , status: 0xd0 [2021-02-01 09:53:38][DEBUG] : Error while sending 234 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED [2021-02-01 09:53:38][DEBUG] : [0xa72a] Delivery error for seq # 0xe9, on endpoint id 1 cluster 0x0000: message send failure [2021-02-01 09:53:38][DEBUG] : [0xa72a:1] Manufacturer: None [2021-02-01 09:53:38][DEBUG] : [0xa72a:1] Model: None [2021-02-01 09:53:38][DEBUG] : Sending Zigbee request with tsn 237 under 238 request id, data: b'00ed0004000500' [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_request (22, 238, 0, , 260, 0, 1, b'\x00\xed\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:53:38][DEBUG] : Send: 0x12fb001d001600ee0002739b010401000001070000ed00040005000200 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x12fb000900020022ee [2021-02-01 09:53:38][DEBUG] : APS data request response: [2, , 238] [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0efc000700a600 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:38][DEBUG] : Send: 0x04fc0007000000 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x04fc0013000c0022ee02739b01010000000000 [2021-02-01 09:53:38][DEBUG] : APS data confirm response for request with id 238: 00 [2021-02-01 09:53:38][DEBUG] : Request id: 0xee 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0efd000700aa00 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:38][DEBUG] : Send: 0x17fd000800010001 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x17fd0027002000220200000102739b0104010000090018ed0104008605008600afd70af90000bd [2021-02-01 09:53:38][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x18\xed\x01\x04\x00\x86\x05\x00\x86', 0, 175, 215, 10, 249, 0, 0, -67] [2021-02-01 09:53:38][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=237 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:38][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18ed01040086050086' [2021-02-01 09:53:38][DEBUG] : Sending Zigbee request with tsn 239 under 240 request id, data: b'00ef000400' [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_request (20, 240, 0, , 260, 0, 1, b'\x00\xef\x00\x04\x00', 2, 0) [2021-02-01 09:53:38][DEBUG] : Send: 0x12fe001b001400f00002739b010401000001050000ef0004000200 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x12fe000900020022f0 [2021-02-01 09:53:38][DEBUG] : APS data request response: [2, , 240] [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0eff000700a600 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:38][DEBUG] : Send: 0x04ff0007000000 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x04ff0013000c0022f002739b01010000000000 [2021-02-01 09:53:38][DEBUG] : APS data confirm response for request with id 240: 00 [2021-02-01 09:53:38][DEBUG] : Request id: 0xf0 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0e00000700aa00 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:38][DEBUG] : Send: 0x1701000800010001 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x17010024001d00220200000102739b0104010000060018ef0104008600afd70af90000bd [2021-02-01 09:53:38][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xef\x01\x04\x00\x86', 0, 175, 215, 10, 249, 0, 0, -67] [2021-02-01 09:53:38][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=239 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:38][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18ef01040086' [2021-02-01 09:53:38][DEBUG] : Sending Zigbee request with tsn 241 under 242 request id, data: b'00f1000500' [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_request (20, 242, 0, , 260, 0, 1, b'\x00\xf1\x00\x05\x00', 2, 0) [2021-02-01 09:53:38][DEBUG] : Send: 0x1202001b001400f20002739b010401000001050000f10005000200 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x1202000900020022f2 [2021-02-01 09:53:38][DEBUG] : APS data request response: [2, , 242] [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0e03000700a600 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:38][DEBUG] : Send: 0x04030007000000 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x04030013000c0022f202739b01010000000000 [2021-02-01 09:53:38][DEBUG] : APS data confirm response for request with id 242: 00 [2021-02-01 09:53:38][DEBUG] : Request id: 0xf2 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0e04000700aa00 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:38][DEBUG] : Send: 0x1704000800010001 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x17040024001d00220200000102739b0104010000060018f10105008600afd70af90000bd [2021-02-01 09:53:38][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x18\xf1\x01\x05\x00\x86', 0, 175, 215, 10, 249, 0, 0, -67] [2021-02-01 09:53:38][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: manufacturer=None tsn=241 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:38][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18f101050086' [2021-02-01 09:53:38][DEBUG] : [0x9b73:1] Manufacturer: None [2021-02-01 09:53:38][DEBUG] : [0x9b73:1] Model: None [2021-02-01 09:53:38][DEBUG] : [0x346d] Extending timeout for 0xf3 request [2021-02-01 09:53:38][DEBUG] : Sending Zigbee request with tsn 243 under 244 request id, data: b'00f30004000500' [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_request (22, 244, 0, , 260, 0, 1, b'\x00\xf3\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:53:38][DEBUG] : Send: 0x1205001d001600f400026d34010401000001070000f300040005000200 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x1205000900020022f4 [2021-02-01 09:53:38][DEBUG] : APS data request response: [2, , 244] [2021-02-01 09:53:38][DEBUG] : Frame received: 0x0e06000700a600 [2021-02-01 09:53:38][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:38][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:38][DEBUG] : Send: 0x04060007000000 [2021-02-01 09:53:38][DEBUG] : Frame received: 0x04060013000c0022f4026d3401010000000000 [2021-02-01 09:53:38][DEBUG] : APS data confirm response for request with id 244: 00 [2021-02-01 09:53:38][DEBUG] : Request id: 0xf4 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x0e07000700aa00 [2021-02-01 09:53:45][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:45][DEBUG] : Send: 0x1707000800010001 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x170700270020002202000001026d340104010000090008f30104008605008600afff0af90000cb [2021-02-01 09:53:45][DEBUG] : APS data indication response: [32, , , 1, , 1, 260, 0, b'\x08\xf3\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 10, 249, 0, 0, -53] [2021-02-01 09:53:45][DEBUG] : [0x346d:1:0x0000] ZCL deserialize: manufacturer=None tsn=243 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:45][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08f301040086050086' [2021-02-01 09:53:45][DEBUG] : [0x346d] Extending timeout for 0xf5 request [2021-02-01 09:53:45][DEBUG] : Sending Zigbee request with tsn 245 under 246 request id, data: b'00f5000400' [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_request (20, 246, 0, , 260, 0, 1, b'\x00\xf5\x00\x04\x00', 2, 0) [2021-02-01 09:53:45][DEBUG] : Send: 0x1208001b001400f600026d34010401000001050000f50004000200 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x1208000900020022f6 [2021-02-01 09:53:45][DEBUG] : APS data request response: [2, , 246] [2021-02-01 09:53:45][DEBUG] : Frame received: 0x0e09000700a600 [2021-02-01 09:53:45][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:45][DEBUG] : Send: 0x04090007000000 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x04090013000c0022f6026d3401010000000000 [2021-02-01 09:53:45][DEBUG] : APS data confirm response for request with id 246: 00 [2021-02-01 09:53:45][DEBUG] : Request id: 0xf6 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x0e0a000700aa00 [2021-02-01 09:53:45][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:45][DEBUG] : Send: 0x170a000800010001 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x170a0024001d002202000001026d340104010000060008f50104008600afff0af90000cc [2021-02-01 09:53:45][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x08\xf5\x01\x04\x00\x86', 0, 175, 255, 10, 249, 0, 0, -52] [2021-02-01 09:53:45][DEBUG] : [0x346d:1:0x0000] ZCL deserialize: manufacturer=None tsn=245 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:45][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08f501040086' [2021-02-01 09:53:45][DEBUG] : [0x346d] Extending timeout for 0xf7 request [2021-02-01 09:53:45][DEBUG] : Sending Zigbee request with tsn 247 under 248 request id, data: b'00f7000500' [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_request (20, 248, 0, , 260, 0, 1, b'\x00\xf7\x00\x05\x00', 2, 0) [2021-02-01 09:53:45][DEBUG] : Send: 0x120b001b001400f800026d34010401000001050000f70005000200 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x120b000900020022f8 [2021-02-01 09:53:45][DEBUG] : APS data request response: [2, , 248] [2021-02-01 09:53:45][DEBUG] : Frame received: 0x0e0c000700a600 [2021-02-01 09:53:45][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_confirm (0,) [2021-02-01 09:53:45][DEBUG] : Send: 0x040c0007000000 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x040c0013000c0022f8026d3401010000000000 [2021-02-01 09:53:45][DEBUG] : APS data confirm response for request with id 248: 00 [2021-02-01 09:53:45][DEBUG] : Request id: 0xf8 'aps_data_confirm' for , status: 0x00 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x0e0d000700aa00 [2021-02-01 09:53:45][DEBUG] : Device state changed response: [, 0] [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_indication (1, 1) [2021-02-01 09:53:45][DEBUG] : Send: 0x170d000800010001 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x170d0024001d002202000001026d340104010000060008f70105008600afff0af90000cc [2021-02-01 09:53:45][DEBUG] : APS data indication response: [29, , , 1, , 1, 260, 0, b'\x08\xf7\x01\x05\x00\x86', 0, 175, 255, 10, 249, 0, 0, -52] [2021-02-01 09:53:45][DEBUG] : [0x346d:1:0x0000] ZCL deserialize: manufacturer=None tsn=247 command_id=Command.Read_Attributes_rsp> [2021-02-01 09:53:45][DEBUG] : 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08f701050086' [2021-02-01 09:53:45][DEBUG] : [0x346d:1] Manufacturer: None [2021-02-01 09:53:45][DEBUG] : [0x346d:1] Model: None [2021-02-01 09:53:45][DEBUG] : [0x84f5] Extending timeout for 0xf9 request [2021-02-01 09:53:45][DEBUG] : Sending Zigbee request with tsn 249 under 250 request id, data: b'00f90004000500' [2021-02-01 09:53:45][DEBUG] : Command Command.aps_data_request (22, 250, 0, , 260, 0, 1, b'\x00\xf9\x00\x04\x00\x05\x00', 2, 0) [2021-02-01 09:53:45][DEBUG] : Send: 0x120e001d001600fa0002f584010401000001070000f900040005000200 [2021-02-01 09:53:45][DEBUG] : Frame received: 0x120e000900020022fa [2021-02-01 09:53:45][DEBUG] : APS data request response: [2, , 250]