[2021-02-03 10:10:03][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2021-02-03 10:10:03][DEBUG] : Sending Zigbee request with tsn 2 under 3 request id, data: b'00020004000500'
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_request (22, 3, 0, <DeconzAddressEndpoint address_mode=2 address=0x3517 endpoint=1>, 260, 0, 1, b'\x00\x02\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:10:03][DEBUG] : Send: 0x121e001d00160003000217350104010000010700000200040005000200
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x121e00090002002203
[2021-02-03 10:10:03][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 3]
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x0e1f000700a600
[2021-02-03 10:10:03][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:10:03][DEBUG] : Send: 0x041f0007000000
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x041f0013000c00220302173501010000000000
[2021-02-03 10:10:03][DEBUG] : APS data confirm response for request with id 3: 00
[2021-02-03 10:10:03][DEBUG] : Request id: 0x03 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x3517 endpoint=1>, status: 0x00
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x0e20000700aa00
[2021-02-03 10:10:03][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:10:03][DEBUG] : Send: 0x1720000800010001
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x1720002700200022020000010217350104010000090018020104008605008600afff874d0100c6
[2021-02-03 10:10:03][DEBUG] : APS data indication response: [32, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, 1, 260, 0, b'\x18\x02\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 135, 77, 1, 0, -58]
[2021-02-03 10:10:03][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=2 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:10:03][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'180201040086050086'
[2021-02-03 10:10:03][DEBUG] : Sending Zigbee request with tsn 4 under 5 request id, data: b'0004000400'
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_request (20, 5, 0, <DeconzAddressEndpoint address_mode=2 address=0x3517 endpoint=1>, 260, 0, 1, b'\x00\x04\x00\x04\x00', 2, 0)
[2021-02-03 10:10:03][DEBUG] : Send: 0x1221001b0014000500021735010401000001050000040004000200
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x122100090002002205
[2021-02-03 10:10:03][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 5]
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x0e22000700a600
[2021-02-03 10:10:03][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:10:03][DEBUG] : Send: 0x04220007000000
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x04220013000c00220502173501010000000000
[2021-02-03 10:10:03][DEBUG] : APS data confirm response for request with id 5: 00
[2021-02-03 10:10:03][DEBUG] : Request id: 0x05 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x3517 endpoint=1>, status: 0x00
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x0e23000700aa00
[2021-02-03 10:10:03][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:10:03][DEBUG] : Send: 0x1723000800010001
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x17230024001d0022020000010217350104010000060018040104008600afff874d0100c6
[2021-02-03 10:10:03][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, 1, 260, 0, b'\x18\x04\x01\x04\x00\x86', 0, 175, 255, 135, 77, 1, 0, -58]
[2021-02-03 10:10:03][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=4 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:10:03][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'180401040086'
[2021-02-03 10:10:03][DEBUG] : Sending Zigbee request with tsn 6 under 7 request id, data: b'0006000500'
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_request (20, 7, 0, <DeconzAddressEndpoint address_mode=2 address=0x3517 endpoint=1>, 260, 0, 1, b'\x00\x06\x00\x05\x00', 2, 0)
[2021-02-03 10:10:03][DEBUG] : Send: 0x1224001b0014000700021735010401000001050000060005000200
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x122400090002002207
[2021-02-03 10:10:03][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 7]
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x0e25000700a600
[2021-02-03 10:10:03][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:10:03][DEBUG] : Send: 0x04250007000000
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x04250013000c00220702173501010000000000
[2021-02-03 10:10:03][DEBUG] : APS data confirm response for request with id 7: 00
[2021-02-03 10:10:03][DEBUG] : Request id: 0x07 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x3517 endpoint=1>, status: 0x00
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x0e26000700aa00
[2021-02-03 10:10:03][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:10:03][DEBUG] : Send: 0x1726000800010001
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x17260024001d0022020000010217350104010000060018060105008600afff874d0100c6
[2021-02-03 10:10:03][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, 1, 260, 0, b'\x18\x06\x01\x05\x00\x86', 0, 175, 255, 135, 77, 1, 0, -58]
[2021-02-03 10:10:03][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=6 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:10:03][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'180601050086'
[2021-02-03 10:10:03][DEBUG] : [0x3517:1] Manufacturer: None
[2021-02-03 10:10:03][DEBUG] : [0x3517:1] Model: None
[2021-02-03 10:10:03][DEBUG] : [0x9e94] Extending timeout for 0x08 request
[2021-02-03 10:10:03][DEBUG] : Sending Zigbee request with tsn 8 under 9 request id, data: b'00080004000500'
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_request (22, 9, 0, <DeconzAddressEndpoint address_mode=2 address=0x9E94 endpoint=1>, 260, 0, 1, b'\x00\x08\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:10:03][DEBUG] : Send: 0x1227001d001600090002949e0104010000010700000800040005000200
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x122700090002002209
[2021-02-03 10:10:03][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 9]
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x0e28000700a600
[2021-02-03 10:10:03][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:10:03][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:10:03][DEBUG] : Send: 0x04280007000000
[2021-02-03 10:10:03][DEBUG] : Frame received: 0x04280013000c00220902949e01010000000000
[2021-02-03 10:10:03][DEBUG] : APS data confirm response for request with id 9: 00
[2021-02-03 10:10:03][DEBUG] : Request id: 0x09 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9e94 endpoint=1>, status: 0x00
[2021-02-03 10:10:20][DEBUG] : Frame received: 0x1c29000c00050002f584ffcb
[2021-02-03 10:10:31][DEBUG] : [0x9e94] Extending timeout for 0x0a request
[2021-02-03 10:10:31][DEBUG] : Sending Zigbee request with tsn 10 under 11 request id, data: b'000a000400'
[2021-02-03 10:10:31][DEBUG] : Command Command.aps_data_request (20, 11, 0, <DeconzAddressEndpoint address_mode=2 address=0x9E94 endpoint=1>, 260, 0, 1, b'\x00
\x00\x04\x00', 2, 0)
[2021-02-03 10:10:31][DEBUG] : Send: 0x1229001b0014000b0002949e0104010000010500000a0004000200
[2021-02-03 10:10:31][DEBUG] : Frame received: 0x12290009000200220b
[2021-02-03 10:10:31][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 11]
[2021-02-03 10:10:31][DEBUG] : Frame received: 0x0e2a000700a600
[2021-02-03 10:10:31][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:10:31][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:10:31][DEBUG] : Send: 0x042a0007000000
[2021-02-03 10:10:31][DEBUG] : Frame received: 0x042a0013000c00220b02949e01010000000000
[2021-02-03 10:10:31][DEBUG] : APS data confirm response for request with id 11: 00
[2021-02-03 10:10:31][DEBUG] : Request id: 0x0b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9e94 endpoint=1>, status: 0x00
[2021-02-03 10:10:39][DEBUG] : Frame received: 0x0e2b000700aa00
[2021-02-03 10:10:39][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:10:39][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:10:39][DEBUG] : Send: 0x172b000800010001
[2021-02-03 10:10:39][DEBUG] : Frame received: 0x172b0039003200220200000002878a00000038801b00000000f8ff071700060010aea0ada5aaafa8b7bd9e9c9d9d9c9c9c00afff924d0100c9
[2021-02-03 10:10:39][DEBUG] : APS data indication response: [50, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8a87>, 0, 0, 32824, b'\x00\x00\x00\xf8\xff\x07\x17\x00\x06\x00\x10\xae\xa0\xad\xa5\xaa\xaf\xa8\xb7\xbd\x9e\x9c\x9d\x9d\x9c\x9c\x9c', 0, 175, 255, 146, 77, 1, 0, -55]
[2021-02-03 10:10:39][DEBUG] : [0x8a87:zdo] ZDO request ZDOCmd.Mgmt_NWK_Update_rsp: [<Status.SUCCESS: 0>, <Channels.ALL_CHANNELS: 134215680>, 23, 6, [174, 160, 173, 165, 170, 175, 168, 183, 189, 158, 156, 157, 157, 156, 156, 156]]
[2021-02-03 10:10:39][DEBUG] : [0x8a87:zdo] Unsupported ZDO request:ZDOCmd.Mgmt_NWK_Update_rsp
[2021-02-03 10:10:39][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8a87>, ep: 0, profile: 0x0000, cluster_id: 0x8038, data: b'000000f8ff071700060010aea0ada5aaafa8b7bd9e9c9d9d9c9c9c'
[2021-02-03 10:10:40][DEBUG] : Frame received: 0x1c2c000c000500020f5dffcb
[2021-02-03 10:10:40][DEBUG] : Frame received: 0x0e2d000700aa00
[2021-02-03 10:10:40][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:10:40][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:10:40][DEBUG] : Send: 0x172c000800010001
[2021-02-03 10:10:40][DEBUG] : Frame received: 0x172c004a0043002202000001020f5d01040100002c00188f0a01ff42250121210c0421a8130521b9b3062401000000006429ae0865211915662b7f7d01000a21000000afff924d0100cb
[2021-02-03 10:10:40][DEBUG] : APS data indication response: [67, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5d0f>, 1, 260, 0, b'\x18\x8f
\x01\xffB%\x01!!\x0c\x04!\xa8\x13\x05!\xb9\xb3\x06$\x01\x00\x00\x00\x00d)\xae\x08e!\x19\x15f+\x7f}\x01\x00
!\x00\x00', 0, 175, 255, 146, 77, 1, 0, -53]
[2021-02-03 10:10:40][DEBUG] : [0x5d0f:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=143 command_id=Command.Report_Attributes>
[2021-02-03 10:10:40][DEBUG] : [0x5d0f:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=
!!!�!��$
>)]]
[2021-02-03 10:10:40][INFO] : [00:15:8d:00:02:e9:6f:44][listener.general_command] Cluster: <zhaquirks.xiaomi.BasicCluster object at 0x7fe5626ade80> ClusterId: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=143 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=
!!!�!��$
>)]],)
[2021-02-03 10:10:40][DEBUG] : [0x5d0f:1:0x0000] Attribute report received: 65281=
!!!�!��$

[2021-02-03 10:10:40][INFO] : [00:15:8d:00:02:e9:6f:44][listener.attribute_updated] Received an attribute update 65281=b'\x01!!\x0c\x04!\xa8\x13\x05!\xb9\xb3\x06$\x01\x00\x00\x00\x00d)\xae\x08e!\x19\x15f+\x7f}\x01\x00
!\x00\x00' on cluster 0
[2021-02-03 10:10:40][DEBUG] : 00:15:8d:00:02:e9:6f:44 - Attribute report. attribute_id: [65281] value: [{'battery_voltage_mV': 3105, 'X-attrib-4': 5032, 'X-attrib-5': 46009, 'X-attrib-6': 1, 'temperature_measurement': 2222, 'humidity_measurement': 5401, 'pressure_measurement': 97663, 'path': 0}]
[2021-02-03 10:10:40][INFO] : [00:15:8d:00:02:e9:6f:44][listener.attribute_updated] Received an attribute update 32=31 on cluster 1
[2021-02-03 10:10:40][DEBUG] : [0x5d0f:1:0x0001] Voltage mV: [Min]:2820 < [RAW]:3100 < [Max]:3100, Battery Percent: 100.0
[2021-02-03 10:10:40][INFO] : [00:15:8d:00:02:e9:6f:44][listener.attribute_updated] Received an attribute update 33=200 on cluster 1
[2021-02-03 10:10:40][INFO] : [00:15:8d:00:02:e9:6f:44][listener.attribute_updated] Received an attribute update 0=2222 on cluster 1026
[2021-02-03 10:10:40][INFO] : [00:15:8d:00:02:e9:6f:44][listener.attribute_updated] Received an attribute update 0=5401 on cluster 1029
[2021-02-03 10:10:40][INFO] : [00:15:8d:00:02:e9:6f:44][listener.attribute_updated] Received an attribute update 0=976.63 on cluster 1027
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 0, 65281, b'\x01!!\x0c\x04!\xa8\x13\x05!\xb9\xb3\x06$\x01\x00\x00\x00\x00d)\xae\x08e!\x19\x15f+\x7f}\x01\x00
!\x00\x00'))
[2021-02-03 10:10:40][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5d0f>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'188f0a01ff42250121210c0421a8130521b9b3062401000000006429ae0865211915662b7f7d01000a210000'
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 0, 65281, b'\x01!!\x0c\x04!\xa8\x13\x05!\xb9\xb3\x06$\x01\x00\x00\x00\x00d)\xae\x08e!\x19\x15f+\x7f}\x01\x00
!\x00\x00')) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:10:40][DEBUG] : Send to jeedom : {'devices': {'00:15:8d:00:02:e9:6f:44': {'1': {'0': {'65281': {'value': "b'\x01!!\x0c\x04!\xa8\x13\x05!\xb9\xb3\x06$\x01\x00\x00\x00\x00d)\xae\x08e!\x19\x15f+\x7f}\x01\x00
!\x00\x00'", 'cluster_name': 'BasicCluster'}}, '1': {'32': {'value': '31', 'cluster_name': 'Power Configuration'}, '33': {'value': '200', 'cluster_name': 'Power Configuration'}}, '1026': {'0': {'value': '2222', 'cluster_name': 'Temperature Measurement'}}, '1029': {'0': {'value': '5401', 'cluster_name': 'Relative Humidity Measurement'}}, '1027': {'0': {'value': '976.63', 'cluster_name': 'Pressure Measurement'}}}}}}
[2021-02-03 10:10:40][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-02-03 10:10:40][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=V7BsmMlaK71xWYTqkNbwKrvBtNuAK9NF HTTP/1.1" 200 0
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1, 32, 31))
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1, 32, 31)) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1, 33, 200))
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1, 33, 200)) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1026, 0, 2222))
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1026, 0, 2222)) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1029, 0, 5401))
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1029, 0, 5401)) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1027, 0, 976.63))
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:15:8d:00:02:e9:6f:44, 1, 1027, 0, 976.63)) completed
[2021-02-03 10:10:40][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:10:40][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:10:40][DEBUG] : Frame received: 0x1c2d000c000500020f5dffcb
[2021-02-03 10:10:43][DEBUG] : Frame received: 0x0e2e000700aa00
[2021-02-03 10:10:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:10:43][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:10:43][DEBUG] : Send: 0x172d000800010001
[2021-02-03 10:10:43][DEBUG] : Frame received: 0x172d0025001e002202000001029b640104010000070018610a0100205000afb7924d0100b9
[2021-02-03 10:10:43][DEBUG] : APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x649b>, 1, 260, 0, b'\x18a
\x01\x00 P', 0, 175, 183, 146, 77, 1, 0, -71]
[2021-02-03 10:10:43][DEBUG] : [0x649b:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=97 command_id=Command.Report_Attributes>
[2021-02-03 10:10:43][DEBUG] : [0x649b:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=80>)]]
[2021-02-03 10:10:43][INFO] : [84:2e:14:ff:fe:2f:b7:74][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x7fe5626a52e8> ClusterId: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=97 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=80>)]],)
[2021-02-03 10:10:43][DEBUG] : [0x649b:1:0x0000] Attribute report received: app_version=80
[2021-02-03 10:10:43][INFO] : [84:2e:14:ff:fe:2f:b7:74][listener.attribute_updated] Received an attribute update 1=80 on cluster 0
[2021-02-03 10:10:43][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (84:2e:14:ff:fe:2f:b7:74, 1, 0, 1, 80))
[2021-02-03 10:10:43][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x649b>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18610a01002050'
[2021-02-03 10:10:43][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (84:2e:14:ff:fe:2f:b7:74, 1, 0, 1, 80)) completed
[2021-02-03 10:10:43][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:10:43][DEBUG] : Send to jeedom : {'devices': {'84:2e:14:ff:fe:2f:b7:74': {'1': {'0': {'1': {'value': '80', 'cluster_name': 'Basic'}}}}}}
[2021-02-03 10:10:43][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-02-03 10:10:43][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=V7BsmMlaK71xWYTqkNbwKrvBtNuAK9NF HTTP/1.1" 200 0
[2021-02-03 10:10:43][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:10:59][DEBUG] : [0x9e94] Extending timeout for 0x0c request
[2021-02-03 10:10:59][DEBUG] : Sending Zigbee request with tsn 12 under 13 request id, data: b'000c000500'
[2021-02-03 10:10:59][DEBUG] : Command Command.aps_data_request (20, 13, 0, <DeconzAddressEndpoint address_mode=2 address=0x9E94 endpoint=1>, 260, 0, 1, b'\x00\x0c\x00\x05\x00', 2, 0)
[2021-02-03 10:10:59][DEBUG] : Send: 0x122e001b0014000d0002949e0104010000010500000c0005000200
[2021-02-03 10:10:59][DEBUG] : Frame received: 0x122e0009000200220d
[2021-02-03 10:10:59][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 13]
[2021-02-03 10:10:59][DEBUG] : Frame received: 0x0e2f000700a600
[2021-02-03 10:10:59][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:10:59][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:10:59][DEBUG] : Send: 0x042f0007000000
[2021-02-03 10:10:59][DEBUG] : Frame received: 0x042f0013000c00220d02949e01010000000000
[2021-02-03 10:10:59][DEBUG] : APS data confirm response for request with id 13: 00
[2021-02-03 10:10:59][DEBUG] : Request id: 0x0d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9e94 endpoint=1>, status: 0x00
[2021-02-03 10:11:09][DEBUG] : [ApplicationHandler.prepare] Json arg : None
[2021-02-03 10:11:09][INFO] : 200 GET /application/info (127.0.0.1) 2.18ms
[2021-02-03 10:11:09][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2021-02-03 10:11:09][DEBUG] : Sending Zigbee request with tsn 14 under 15 request id, data: b'000e0004000500'
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_request (22, 15, 0, <DeconzAddressEndpoint address_mode=2 address=0x3517 endpoint=1>, 260, 0, 1, b'\x00\x0e\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:11:09][DEBUG] : Send: 0x1230001d0016000f000217350104010000010700000e00040005000200
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x12300009000200220f
[2021-02-03 10:11:09][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 15]
[2021-02-03 10:11:09][DEBUG] : [NetworkHandler.prepare] Json arg : None
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 15 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 14 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 14 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 15 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 10 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 14 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 15 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 15 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 12 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 13 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 13 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 14 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 14 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 15 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 15 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 0 neighbours
[2021-02-03 10:11:09][DEBUG] : [map.scan] Done scanning. Total 14 neighbours
[2021-02-03 10:11:09][INFO] : 200 GET /network/map (127.0.0.1) 36.20ms
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x0e31000700a600
[2021-02-03 10:11:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:09][DEBUG] : Send: 0x04310007000000
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x04310013000c00220f02173501010000000000
[2021-02-03 10:11:09][DEBUG] : APS data confirm response for request with id 15: 00
[2021-02-03 10:11:09][DEBUG] : Request id: 0x0f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x3517 endpoint=1>, status: 0x00
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x0e32000700aa00
[2021-02-03 10:11:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:09][DEBUG] : Send: 0x1732000800010001
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x17320027002000220200000102173501040100000900180e0104008605008600afff974d0100c6
[2021-02-03 10:11:09][DEBUG] : APS data indication response: [32, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, 1, 260, 0, b'\x18\x0e\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 151, 77, 1, 0, -58]
[2021-02-03 10:11:09][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=14 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:09][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'180e01040086050086'
[2021-02-03 10:11:09][DEBUG] : Sending Zigbee request with tsn 16 under 17 request id, data: b'0010000400'
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_request (20, 17, 0, <DeconzAddressEndpoint address_mode=2 address=0x3517 endpoint=1>, 260, 0, 1, b'\x00\x10\x00\x04\x00', 2, 0)
[2021-02-03 10:11:09][DEBUG] : Send: 0x1233001b0014001100021735010401000001050000100004000200
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x123300090002002211
[2021-02-03 10:11:09][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 17]
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x0e34000700a600
[2021-02-03 10:11:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:09][DEBUG] : Send: 0x04340007000000
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x04340013000c00221102173501010000000000
[2021-02-03 10:11:09][DEBUG] : APS data confirm response for request with id 17: 00
[2021-02-03 10:11:09][DEBUG] : Request id: 0x11 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x3517 endpoint=1>, status: 0x00
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x0e35000700aa00
[2021-02-03 10:11:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:09][DEBUG] : Send: 0x1735000800010001
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x17350024001d0022020000010217350104010000060018100104008600afff974d0100c6
[2021-02-03 10:11:09][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, 1, 260, 0, b'\x18\x10\x01\x04\x00\x86', 0, 175, 255, 151, 77, 1, 0, -58]
[2021-02-03 10:11:09][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=16 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:09][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181001040086'
[2021-02-03 10:11:09][DEBUG] : Sending Zigbee request with tsn 18 under 19 request id, data: b'0012000500'
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_request (20, 19, 0, <DeconzAddressEndpoint address_mode=2 address=0x3517 endpoint=1>, 260, 0, 1, b'\x00\x12\x00\x05\x00', 2, 0)
[2021-02-03 10:11:09][DEBUG] : Send: 0x1236001b0014001300021735010401000001050000120005000200
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x123600090002002213
[2021-02-03 10:11:09][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 19]
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x0e37000700a600
[2021-02-03 10:11:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:09][DEBUG] : Send: 0x04370007000000
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x04370013000c00221302173501010000000000
[2021-02-03 10:11:09][DEBUG] : APS data confirm response for request with id 19: 00
[2021-02-03 10:11:09][DEBUG] : Request id: 0x13 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x3517 endpoint=1>, status: 0x00
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x0e38000700aa00
[2021-02-03 10:11:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:09][DEBUG] : Send: 0x1738000800010001
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x17380024001d0022020000010217350104010000060018120105008600afff974d0100c6
[2021-02-03 10:11:09][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, 1, 260, 0, b'\x18\x12\x01\x05\x00\x86', 0, 175, 255, 151, 77, 1, 0, -58]
[2021-02-03 10:11:09][DEBUG] : [0x3517:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=18 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:09][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3517>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181201050086'
[2021-02-03 10:11:09][DEBUG] : [0x3517:1] Manufacturer: None
[2021-02-03 10:11:09][DEBUG] : [0x3517:1] Model: None
[2021-02-03 10:11:09][DEBUG] : [0x9e94] Extending timeout for 0x14 request
[2021-02-03 10:11:09][DEBUG] : Sending Zigbee request with tsn 20 under 21 request id, data: b'00140004000500'
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_request (22, 21, 0, <DeconzAddressEndpoint address_mode=2 address=0x9E94 endpoint=1>, 260, 0, 1, b'\x00\x14\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:11:09][DEBUG] : Send: 0x1239001d001600150002949e0104010000010700001400040005000200
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x123900090002002215
[2021-02-03 10:11:09][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 21]
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x0e3a000700a600
[2021-02-03 10:11:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:09][DEBUG] : Send: 0x043a0007000000
[2021-02-03 10:11:09][DEBUG] : Frame received: 0x043a0013000c00221502949e01010000000000
[2021-02-03 10:11:09][DEBUG] : APS data confirm response for request with id 21: 00
[2021-02-03 10:11:09][DEBUG] : Request id: 0x15 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9e94 endpoint=1>, status: 0x00
[2021-02-03 10:11:21][DEBUG] : Frame received: 0x1c3b000c00050002f584ffcc
[2021-02-03 10:11:27][DEBUG] : [0x9e94:1] Manufacturer: None
[2021-02-03 10:11:27][DEBUG] : [0x9e94:1] Model: None
[2021-02-03 10:11:27][DEBUG] : [0x130b:1] Manufacturer: Philips
[2021-02-03 10:11:27][DEBUG] : [0x130b:1] Model: RWL021
[2021-02-03 10:11:27][DEBUG] : [0x130b:2] Manufacturer: Philips
[2021-02-03 10:11:27][DEBUG] : [0x130b:2] Model: RWL021
[2021-02-03 10:11:27][DEBUG] : [0x507c:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:27][DEBUG] : [0x507c:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:27][DEBUG] : [0x924f:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:27][DEBUG] : [0x924f:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:27][DEBUG] : [0xd759:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:27][DEBUG] : [0xd759:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:27][DEBUG] : [0xca67:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:27][DEBUG] : [0xca67:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:27][DEBUG] : [0x8a87:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:27][DEBUG] : [0x8a87:1] Model: TRADFRI bulb E14 W op/ch 400lm
[2021-02-03 10:11:27][DEBUG] : [0xbd2f:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:27][DEBUG] : [0xbd2f:1] Model: TRADFRI bulb E14 W op/ch 400lm
[2021-02-03 10:11:27][DEBUG] : [0xabb3:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:27][DEBUG] : [0xabb3:1] Model: TRADFRI control outlet
[2021-02-03 10:11:27][DEBUG] : Sending Zigbee request with tsn 22 under 23 request id, data: b'00160004000500'
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_request (22, 23, 0, <DeconzAddressEndpoint address_mode=2 address=0x0FD7 endpoint=1>, 260, 0, 1, b'\x00\x16\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:11:27][DEBUG] : Send: 0x123b001d001600170002d70f0104010000010700001600040005000200
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x123b00090002002217
[2021-02-03 10:11:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 23]
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x0e3c000700a600
[2021-02-03 10:11:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:27][DEBUG] : Send: 0x043c0007000000
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x043c0013000c00221702d70f01010000000000
[2021-02-03 10:11:27][DEBUG] : APS data confirm response for request with id 23: 00
[2021-02-03 10:11:27][DEBUG] : Request id: 0x17 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0fd7 endpoint=1>, status: 0x00
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x0e3d000700aa00
[2021-02-03 10:11:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:27][DEBUG] : Send: 0x173d000800010001
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x173d0027002000220200000102d70f0104010000090018160104008605008600afff9f4d0100c5
[2021-02-03 10:11:27][DEBUG] : APS data indication response: [32, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, 1, 260, 0, b'\x18\x16\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 159, 77, 1, 0, -59]
[2021-02-03 10:11:27][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=22 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181601040086050086'
[2021-02-03 10:11:27][DEBUG] : Sending Zigbee request with tsn 24 under 25 request id, data: b'0018000400'
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_request (20, 25, 0, <DeconzAddressEndpoint address_mode=2 address=0x0FD7 endpoint=1>, 260, 0, 1, b'\x00\x18\x00\x04\x00', 2, 0)
[2021-02-03 10:11:27][DEBUG] : Send: 0x123e001b001400190002d70f010401000001050000180004000200
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x123e00090002002219
[2021-02-03 10:11:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 25]
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x0e3f000700a600
[2021-02-03 10:11:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:27][DEBUG] : Send: 0x043f0007000000
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x043f0013000c00221902d70f01010000000000
[2021-02-03 10:11:27][DEBUG] : APS data confirm response for request with id 25: 00
[2021-02-03 10:11:27][DEBUG] : Request id: 0x19 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0fd7 endpoint=1>, status: 0x00
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x0e40000700aa00
[2021-02-03 10:11:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:27][DEBUG] : Send: 0x1740000800010001
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x17400024001d00220200000102d70f0104010000060018180104008600afff9f4d0100c6
[2021-02-03 10:11:27][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, 1, 260, 0, b'\x18\x18\x01\x04\x00\x86', 0, 175, 255, 159, 77, 1, 0, -58]
[2021-02-03 10:11:27][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=24 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181801040086'
[2021-02-03 10:11:27][DEBUG] : Sending Zigbee request with tsn 26 under 27 request id, data: b'001a000500'
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_request (20, 27, 0, <DeconzAddressEndpoint address_mode=2 address=0x0FD7 endpoint=1>, 260, 0, 1, b'\x00\x1a\x00\x05\x00', 2, 0)
[2021-02-03 10:11:27][DEBUG] : Send: 0x1241001b0014001b0002d70f0104010000010500001a0005000200
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x12410009000200221b
[2021-02-03 10:11:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 27]
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x0e42000700a600
[2021-02-03 10:11:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:27][DEBUG] : Send: 0x04420007000000
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x04420013000c00221b02d70f01010000000000
[2021-02-03 10:11:27][DEBUG] : APS data confirm response for request with id 27: 00
[2021-02-03 10:11:27][DEBUG] : Request id: 0x1b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0fd7 endpoint=1>, status: 0x00
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x0e43000700aa00
[2021-02-03 10:11:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:27][DEBUG] : Send: 0x1743000800010001
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x17430024001d00220200000102d70f01040100000600181a0105008600afff9f4d0100c6
[2021-02-03 10:11:27][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, 1, 260, 0, b'\x18\x1a\x01\x05\x00\x86', 0, 175, 255, 159, 77, 1, 0, -58]
[2021-02-03 10:11:27][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=26 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'181a01050086'
[2021-02-03 10:11:27][DEBUG] : [0x0fd7:1] Manufacturer: None
[2021-02-03 10:11:27][DEBUG] : [0x0fd7:1] Model: None
[2021-02-03 10:11:27][DEBUG] : [0xa72a] Extending timeout for 0x1c request
[2021-02-03 10:11:27][DEBUG] : Sending Zigbee request with tsn 28 under 29 request id, data: b'001c0004000500'
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_request (22, 29, 0, <DeconzAddressEndpoint address_mode=2 address=0xA72A endpoint=1>, 260, 0, 1, b'\x00\x1c\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:11:27][DEBUG] : Send: 0x1244001d0016001d00022aa70104010000010700001c00040005000200
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x12440009000200221d
[2021-02-03 10:11:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 29]
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x0e45000700a600
[2021-02-03 10:11:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:27][DEBUG] : Send: 0x04450007000000
[2021-02-03 10:11:27][DEBUG] : Frame received: 0x04450013000c00221d022aa701010000000000
[2021-02-03 10:11:27][DEBUG] : APS data confirm response for request with id 29: 00
[2021-02-03 10:11:27][DEBUG] : Request id: 0x1d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa72a endpoint=1>, status: 0x00
[2021-02-03 10:11:37][DEBUG] : [0x9e94] Extending timeout for 0x1e request
[2021-02-03 10:11:37][DEBUG] : Sending Zigbee request with tsn 30 under 31 request id, data: b'001e000400'
[2021-02-03 10:11:37][DEBUG] : Command Command.aps_data_request (20, 31, 0, <DeconzAddressEndpoint address_mode=2 address=0x9E94 endpoint=1>, 260, 0, 1, b'\x00\x1e\x00\x04\x00', 2, 0)
[2021-02-03 10:11:37][DEBUG] : Send: 0x1246001b0014001f0002949e0104010000010500001e0004000200
[2021-02-03 10:11:37][DEBUG] : Frame received: 0x12460009000200221f
[2021-02-03 10:11:37][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 31]
[2021-02-03 10:11:37][DEBUG] : Frame received: 0x0e47000700a600
[2021-02-03 10:11:37][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:37][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:37][DEBUG] : Send: 0x04470007000000
[2021-02-03 10:11:37][DEBUG] : Frame received: 0x04470013000c00221f02949e01010000000000
[2021-02-03 10:11:37][DEBUG] : APS data confirm response for request with id 31: 00
[2021-02-03 10:11:37][DEBUG] : Request id: 0x1f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9e94 endpoint=1>, status: 0x00
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e48000700aa00
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:43][DEBUG] : Send: 0x1748000800010001
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x17480024001d00220200000102949e01040100000600081e0104008600afffa74d0100ca
[2021-02-03 10:11:43][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9e94>, 1, 260, 0, b'\x08\x1e\x01\x04\x00\x86', 0, 175, 255, 167, 77, 1, 0, -54]
[2021-02-03 10:11:43][DEBUG] : [0x9e94:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=30 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:43][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9e94>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'081e01040086'
[2021-02-03 10:11:43][DEBUG] : [0x9e94] Extending timeout for 0x20 request
[2021-02-03 10:11:43][DEBUG] : Sending Zigbee request with tsn 32 under 33 request id, data: b'0020000500'
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_request (20, 33, 0, <DeconzAddressEndpoint address_mode=2 address=0x9E94 endpoint=1>, 260, 0, 1, b'\x00 \x00\x05\x00', 2, 0)
[2021-02-03 10:11:43][DEBUG] : Send: 0x1249001b001400210002949e010401000001050000200005000200
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x124900090002002221
[2021-02-03 10:11:43][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 33]
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e4a000700a600
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:43][DEBUG] : Send: 0x044a0007000000
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x044a0013000c00222102949e01010000000000
[2021-02-03 10:11:43][DEBUG] : APS data confirm response for request with id 33: 00
[2021-02-03 10:11:43][DEBUG] : Request id: 0x21 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9e94 endpoint=1>, status: 0x00
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e4b000700aa00
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:43][DEBUG] : Send: 0x174b000800010001
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x174b0024001d00220200000102949e0104010000060008200105008600afffa74d0100c9
[2021-02-03 10:11:43][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9e94>, 1, 260, 0, b'\x08 \x01\x05\x00\x86', 0, 175, 255, 167, 77, 1, 0, -55]
[2021-02-03 10:11:43][DEBUG] : [0x9e94:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=32 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:43][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9e94>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'082001050086'
[2021-02-03 10:11:43][DEBUG] : [0x9e94:1] Manufacturer: None
[2021-02-03 10:11:43][DEBUG] : [0x9e94:1] Model: None
[2021-02-03 10:11:43][DEBUG] : [0x130b:1] Manufacturer: Philips
[2021-02-03 10:11:43][DEBUG] : [0x130b:1] Model: RWL021
[2021-02-03 10:11:43][DEBUG] : [0x130b:2] Manufacturer: Philips
[2021-02-03 10:11:43][DEBUG] : [0x130b:2] Model: RWL021
[2021-02-03 10:11:43][DEBUG] : [0x507c:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:43][DEBUG] : [0x507c:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:43][DEBUG] : [0x924f:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:43][DEBUG] : [0x924f:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:43][DEBUG] : [0xd759:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:43][DEBUG] : [0xd759:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:43][DEBUG] : [0xca67:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:43][DEBUG] : [0xca67:1] Model: TRADFRI bulb E27 W opal 1000lm
[2021-02-03 10:11:43][DEBUG] : [0x8a87:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:43][DEBUG] : [0x8a87:1] Model: TRADFRI bulb E14 W op/ch 400lm
[2021-02-03 10:11:43][DEBUG] : [0xbd2f:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:43][DEBUG] : [0xbd2f:1] Model: TRADFRI bulb E14 W op/ch 400lm
[2021-02-03 10:11:43][DEBUG] : [0xabb3:1] Manufacturer: IKEA of Sweden
[2021-02-03 10:11:43][DEBUG] : [0xabb3:1] Model: TRADFRI control outlet
[2021-02-03 10:11:43][DEBUG] : Sending Zigbee request with tsn 34 under 35 request id, data: b'00220004000500'
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_request (22, 35, 0, <DeconzAddressEndpoint address_mode=2 address=0x0FD7 endpoint=1>, 260, 0, 1, b'\x00"\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:11:43][DEBUG] : Send: 0x124c001d001600230002d70f0104010000010700002200040005000200
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x124c00090002002223
[2021-02-03 10:11:43][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 35]
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e4d000700a600
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:43][DEBUG] : Send: 0x044d0007000000
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x044d0013000c00222302d70f01010000000000
[2021-02-03 10:11:43][DEBUG] : APS data confirm response for request with id 35: 00
[2021-02-03 10:11:43][DEBUG] : Request id: 0x23 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0fd7 endpoint=1>, status: 0x00
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e4e000700aa00
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:43][DEBUG] : Send: 0x174e000800010001
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x174e0027002000220200000102d70f0104010000090018220104008605008600afffa74d0100c3
[2021-02-03 10:11:43][DEBUG] : APS data indication response: [32, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, 1, 260, 0, b'\x18"\x01\x04\x00\x86\x05\x00\x86', 0, 175, 255, 167, 77, 1, 0, -61]
[2021-02-03 10:11:43][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=34 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:43][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'182201040086050086'
[2021-02-03 10:11:43][DEBUG] : Sending Zigbee request with tsn 36 under 37 request id, data: b'0024000400'
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_request (20, 37, 0, <DeconzAddressEndpoint address_mode=2 address=0x0FD7 endpoint=1>, 260, 0, 1, b'\x00$\x00\x04\x00', 2, 0)
[2021-02-03 10:11:43][DEBUG] : Send: 0x124f001b001400250002d70f010401000001050000240004000200
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x124f00090002002225
[2021-02-03 10:11:43][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 37]
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e50000700a600
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:43][DEBUG] : Send: 0x04500007000000
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x04500013000c00222502d70f01010000000000
[2021-02-03 10:11:43][DEBUG] : APS data confirm response for request with id 37: 00
[2021-02-03 10:11:43][DEBUG] : Request id: 0x25 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0fd7 endpoint=1>, status: 0x00
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e51000700aa00
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:43][DEBUG] : Send: 0x1751000800010001
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x17510024001d00220200000102d70f0104010000060018240104008600afffa74d0100c3
[2021-02-03 10:11:43][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, 1, 260, 0, b'\x18$\x01\x04\x00\x86', 0, 175, 255, 167, 77, 1, 0, -61]
[2021-02-03 10:11:43][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=36 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:43][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'182401040086'
[2021-02-03 10:11:43][DEBUG] : Sending Zigbee request with tsn 38 under 39 request id, data: b'0026000500'
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_request (20, 39, 0, <DeconzAddressEndpoint address_mode=2 address=0x0FD7 endpoint=1>, 260, 0, 1, b'\x00&\x00\x05\x00', 2, 0)
[2021-02-03 10:11:43][DEBUG] : Send: 0x1252001b001400270002d70f010401000001050000260005000200
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x125200090002002227
[2021-02-03 10:11:43][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 39]
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e53000700a600
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:43][DEBUG] : Send: 0x04530007000000
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x04530013000c00222702d70f01010000000000
[2021-02-03 10:11:43][DEBUG] : APS data confirm response for request with id 39: 00
[2021-02-03 10:11:43][DEBUG] : Request id: 0x27 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0fd7 endpoint=1>, status: 0x00
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e54000700aa00
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:11:43][DEBUG] : Send: 0x1754000800010001
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x17540024001d00220200000102d70f0104010000060018260105008600afffa74d0100c3
[2021-02-03 10:11:43][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, 1, 260, 0, b'\x18&\x01\x05\x00\x86', 0, 175, 255, 167, 77, 1, 0, -61]
[2021-02-03 10:11:43][DEBUG] : [0x0fd7:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=38 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:11:43][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0fd7>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'182601050086'
[2021-02-03 10:11:43][DEBUG] : [0x0fd7:1] Manufacturer: None
[2021-02-03 10:11:43][DEBUG] : [0x0fd7:1] Model: None
[2021-02-03 10:11:43][DEBUG] : [0xa72a] Extending timeout for 0x28 request
[2021-02-03 10:11:43][DEBUG] : Sending Zigbee request with tsn 40 under 41 request id, data: b'00280004000500'
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_request (22, 41, 0, <DeconzAddressEndpoint address_mode=2 address=0xA72A endpoint=1>, 260, 0, 1, b'\x00(\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:11:43][DEBUG] : Send: 0x1255001d0016002900022aa70104010000010700002800040005000200
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x125500090002002229
[2021-02-03 10:11:43][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 41]
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x0e56000700a600
[2021-02-03 10:11:43][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:43][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:43][DEBUG] : Send: 0x04560007000000
[2021-02-03 10:11:43][DEBUG] : Frame received: 0x04560013000c002229022aa701010000000000
[2021-02-03 10:11:43][DEBUG] : APS data confirm response for request with id 41: 00
[2021-02-03 10:11:43][DEBUG] : Request id: 0x29 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa72a endpoint=1>, status: 0x00
[2021-02-03 10:11:55][DEBUG] : [0xa72a] Extending timeout for 0x2a request
[2021-02-03 10:11:55][DEBUG] : Sending Zigbee request with tsn 42 under 43 request id, data: b'002a000400'
[2021-02-03 10:11:55][DEBUG] : Command Command.aps_data_request (20, 43, 0, <DeconzAddressEndpoint address_mode=2 address=0xA72A endpoint=1>, 260, 0, 1, b'\x00*\x00\x04\x00', 2, 0)
[2021-02-03 10:11:55][DEBUG] : Send: 0x1257001b0014002b00022aa70104010000010500002a0004000200
[2021-02-03 10:11:55][DEBUG] : Frame received: 0x12570009000200222b
[2021-02-03 10:11:55][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 43]
[2021-02-03 10:11:55][DEBUG] : Frame received: 0x0e58000700a600
[2021-02-03 10:11:55][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:11:55][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:11:55][DEBUG] : Send: 0x04580007000000
[2021-02-03 10:11:55][DEBUG] : Frame received: 0x04580013000c00222b022aa701010000000000
[2021-02-03 10:11:55][DEBUG] : APS data confirm response for request with id 43: 00
[2021-02-03 10:11:55][DEBUG] : Request id: 0x2b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa72a endpoint=1>, status: 0x00
[2021-02-03 10:12:07][DEBUG] : Frame received: 0x0e59000700aa00
[2021-02-03 10:12:07][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:12:07][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:12:07][DEBUG] : Send: 0x1759000800010001
[2021-02-03 10:12:07][DEBUG] : Frame received: 0x17590025001e0022020000010259d70104010800070008430a000020fe00af7fb54d0100b2
[2021-02-03 10:12:07][DEBUG] : APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd759>, 1, 260, 8, b'\x08C
\x00\x00 \xfe', 0, 175, 127, 181, 77, 1, 0, -78]
[2021-02-03 10:12:07][DEBUG] : [0xd759:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=67 command_id=Command.Report_Attributes>
[2021-02-03 10:12:07][DEBUG] : [0xd759:1:0x0008] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint8_t, value=254>)]]
[2021-02-03 10:12:07][INFO] : [00:0b:57:ff:fe:a0:1a:ba][listener.general_command] Cluster: <zigpy.zcl.clusters.general.LevelControl object at 0x7fe562ef1fd0> ClusterId: 0x0008 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=67 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=uint8_t, value=254>)]],)
[2021-02-03 10:12:07][DEBUG] : [0xd759:1:0x0008] Attribute report received: current_level=254
[2021-02-03 10:12:07][INFO] : [00:0b:57:ff:fe:a0:1a:ba][listener.attribute_updated] Received an attribute update 0=254 on cluster 8
[2021-02-03 10:12:07][DEBUG] : Sending Zigbee request with tsn 67 under 44 request id, data: b'18430b0a00'
[2021-02-03 10:12:07][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xd759>, ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'08430a000020fe'
[2021-02-03 10:12:07][DEBUG] : Command Command.aps_data_request (20, 44, 0, <DeconzAddressEndpoint address_mode=2 address=0xD759 endpoint=1>, 260, 8, 1, b'\x18C\x0b
\x00', 2, 0)
[2021-02-03 10:12:07][DEBUG] : Send: 0x125a001b0014002c000259d7010401080001050018430b0a000200
[2021-02-03 10:12:07][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:0b:57:ff:fe:a0:1a:ba, 1, 8, 0, 254))
[2021-02-03 10:12:07][DEBUG] : Frame received: 0x125a0009000200222c
[2021-02-03 10:12:07][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 44]
[2021-02-03 10:12:07][DEBUG] : Frame received: 0x0e5b000700a600
[2021-02-03 10:12:07][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:07][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:07][DEBUG] : Send: 0x045b0007000000
[2021-02-03 10:12:07][DEBUG] : Frame received: 0x045b0013000c00222c0259d701010000000000
[2021-02-03 10:12:07][DEBUG] : APS data confirm response for request with id 44: 00
[2021-02-03 10:12:07][DEBUG] : Request id: 0x2c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xd759 endpoint=1>, status: 0x00
[2021-02-03 10:12:07][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:0b:57:ff:fe:a0:1a:ba, 1, 8, 0, 254)) completed
[2021-02-03 10:12:07][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:12:07][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:12:08][DEBUG] : Send to jeedom : {'devices': {'00:0b:57:ff:fe:a0:1a:ba': {'1': {'8': {'0': {'value': '254', 'cluster_name': 'Level control'}}}}}}
[2021-02-03 10:12:08][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-02-03 10:12:08][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=V7BsmMlaK71xWYTqkNbwKrvBtNuAK9NF HTTP/1.1" 200 0
[2021-02-03 10:12:11][DEBUG] : [0xa72a] Extending timeout for 0x2d request
[2021-02-03 10:12:11][DEBUG] : Sending Zigbee request with tsn 45 under 46 request id, data: b'002d000400'
[2021-02-03 10:12:11][DEBUG] : Command Command.aps_data_request (20, 46, 0, <DeconzAddressEndpoint address_mode=2 address=0xA72A endpoint=1>, 260, 0, 1, b'\x00-\x00\x04\x00', 2, 0)
[2021-02-03 10:12:11][DEBUG] : Send: 0x125c001b0014002e00022aa70104010000010500002d0004000200
[2021-02-03 10:12:11][DEBUG] : Frame received: 0x125c0009000200222e
[2021-02-03 10:12:11][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 46]
[2021-02-03 10:12:11][DEBUG] : Frame received: 0x0e5d000700a600
[2021-02-03 10:12:11][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:11][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:11][DEBUG] : Send: 0x045d0007000000
[2021-02-03 10:12:11][DEBUG] : Frame received: 0x045d0013000c00222e022aa701010000000000
[2021-02-03 10:12:11][DEBUG] : APS data confirm response for request with id 46: 00
[2021-02-03 10:12:11][DEBUG] : Request id: 0x2e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa72a endpoint=1>, status: 0x00
[2021-02-03 10:12:18][DEBUG] : Frame received: 0x0e5e000700aa00
[2021-02-03 10:12:18][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:12:18][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:12:18][DEBUG] : Send: 0x175e000800010001
[2021-02-03 10:12:18][DEBUG] : Frame received: 0x175e0025001e002202000001024f920104010600070008510a0000100000afffb54d0100c6
[2021-02-03 10:12:18][DEBUG] : APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x924f>, 1, 260, 6, b'\x08Q
\x00\x00\x10\x00', 0, 175, 255, 181, 77, 1, 0, -58]
[2021-02-03 10:12:18][DEBUG] : [0x924f:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=81 command_id=Command.Report_Attributes>
[2021-02-03 10:12:18][DEBUG] : [0x924f:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.false>)]]
[2021-02-03 10:12:18][INFO] : [d0:cf:5e:ff:fe:ef:2c:48][listener.general_command] Cluster: <zigpy.zcl.clusters.general.OnOff object at 0x7fe562ef1b70> ClusterId: 0x0006 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=81 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.false>)]],)
[2021-02-03 10:12:18][DEBUG] : [0x924f:1:0x0006] Attribute report received: on_off=0
[2021-02-03 10:12:18][INFO] : [d0:cf:5e:ff:fe:ef:2c:48][listener.attribute_updated] Received an attribute update 0=Bool.false on cluster 6
[2021-02-03 10:12:18][DEBUG] : Sending Zigbee request with tsn 81 under 47 request id, data: b'18510b0a00'
[2021-02-03 10:12:18][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x924f>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'08510a00001000'
[2021-02-03 10:12:18][DEBUG] : Command Command.aps_data_request (20, 47, 0, <DeconzAddressEndpoint address_mode=2 address=0x924F endpoint=1>, 260, 6, 1, b'\x18Q\x0b
\x00', 2, 0)
[2021-02-03 10:12:18][DEBUG] : Send: 0x125f001b0014002f00024f92010401060001050018510b0a000200
[2021-02-03 10:12:18][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (d0:cf:5e:ff:fe:ef:2c:48, 1, 6, 0, <Bool.false: 0>))
[2021-02-03 10:12:18][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fe5637abd50>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (d0:cf:5e:ff:fe:ef:2c:48, 1, 6, 0, <Bool.false: 0>)) completed
[2021-02-03 10:12:18][DEBUG] : Frame received: 0x125f0009000200222f
[2021-02-03 10:12:18][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 47]
[2021-02-03 10:12:18][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>)
[2021-02-03 10:12:18][DEBUG] : Frame received: 0x0e60000700a600
[2021-02-03 10:12:18][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:18][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:18][DEBUG] : Send: 0x04600007000000
[2021-02-03 10:12:18][DEBUG] : Frame received: 0x04600013000c00222f024f9201010000000000
[2021-02-03 10:12:18][DEBUG] : APS data confirm response for request with id 47: 00
[2021-02-03 10:12:18][DEBUG] : Request id: 0x2f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x924f endpoint=1>, status: 0x00
[2021-02-03 10:12:18][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fe5637abd50>) completed
[2021-02-03 10:12:18][DEBUG] : Send to jeedom : {'devices': {'d0:cf:5e:ff:fe:ef:2c:48': {'1': {'6': {'0': {'value': 'Bool.false', 'cluster_name': 'On/Off'}}}}}}
[2021-02-03 10:12:18][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-02-03 10:12:18][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=V7BsmMlaK71xWYTqkNbwKrvBtNuAK9NF HTTP/1.1" 200 0
[2021-02-03 10:12:21][DEBUG] : Frame received: 0x1c61000c00050002f584ffcc
[2021-02-03 10:12:23][DEBUG] : [0xa72a] Extending timeout for 0x30 request
[2021-02-03 10:12:23][DEBUG] : Sending Zigbee request with tsn 48 under 49 request id, data: b'0030000500'
[2021-02-03 10:12:23][DEBUG] : Command Command.aps_data_request (20, 49, 0, <DeconzAddressEndpoint address_mode=2 address=0xA72A endpoint=1>, 260, 0, 1, b'\x000\x00\x05\x00', 2, 0)
[2021-02-03 10:12:23][DEBUG] : Send: 0x1261001b0014003100022aa7010401000001050000300005000200
[2021-02-03 10:12:23][DEBUG] : Frame received: 0x126100090002002231
[2021-02-03 10:12:23][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 49]
[2021-02-03 10:12:23][DEBUG] : Frame received: 0x0e62000700a600
[2021-02-03 10:12:23][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:23][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:23][DEBUG] : Send: 0x04620007000000
[2021-02-03 10:12:23][DEBUG] : Frame received: 0x04620013000c002231022aa701010000000000
[2021-02-03 10:12:23][DEBUG] : APS data confirm response for request with id 49: 00
[2021-02-03 10:12:23][DEBUG] : Request id: 0x31 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa72a endpoint=1>, status: 0x00
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e63000700aa00
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:12:27][DEBUG] : Send: 0x1763000800010001
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x17630024001d002202000001022aa70104010000060008300105008600afffbb4d0100c9
[2021-02-03 10:12:27][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa72a>, 1, 260, 0, b'\x080\x01\x05\x00\x86', 0, 175, 255, 187, 77, 1, 0, -55]
[2021-02-03 10:12:27][DEBUG] : [0xa72a:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=48 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:12:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa72a>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'083001050086'
[2021-02-03 10:12:27][DEBUG] : [0xa72a:1] Manufacturer: None
[2021-02-03 10:12:27][DEBUG] : [0xa72a:1] Model: None
[2021-02-03 10:12:27][DEBUG] : Sending Zigbee request with tsn 50 under 51 request id, data: b'00320004000500'
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_request (22, 51, 0, <DeconzAddressEndpoint address_mode=2 address=0x9B73 endpoint=1>, 260, 0, 1, b'\x002\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:12:27][DEBUG] : Send: 0x1264001d001600330002739b0104010000010700003200040005000200
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x126400090002002233
[2021-02-03 10:12:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 51]
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e65000700a600
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:27][DEBUG] : Send: 0x04650007000000
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x04650013000c00223302739b01010000000000
[2021-02-03 10:12:27][DEBUG] : APS data confirm response for request with id 51: 00
[2021-02-03 10:12:27][DEBUG] : Request id: 0x33 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9b73 endpoint=1>, status: 0x00
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e66000700aa00
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:12:27][DEBUG] : Send: 0x1766000800010001
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x17660027002000220200000102739b0104010000090018320104008605008600afe7bb4d0100bf
[2021-02-03 10:12:27][DEBUG] : APS data indication response: [32, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9b73>, 1, 260, 0, b'\x182\x01\x04\x00\x86\x05\x00\x86', 0, 175, 231, 187, 77, 1, 0, -65]
[2021-02-03 10:12:27][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=50 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:12:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9b73>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'183201040086050086'
[2021-02-03 10:12:27][DEBUG] : Sending Zigbee request with tsn 52 under 53 request id, data: b'0034000400'
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_request (20, 53, 0, <DeconzAddressEndpoint address_mode=2 address=0x9B73 endpoint=1>, 260, 0, 1, b'\x004\x00\x04\x00', 2, 0)
[2021-02-03 10:12:27][DEBUG] : Send: 0x1267001b001400350002739b010401000001050000340004000200
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x126700090002002235
[2021-02-03 10:12:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 53]
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e68000700a600
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:27][DEBUG] : Send: 0x04680007000000
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x04680013000c00223502739b01010000000000
[2021-02-03 10:12:27][DEBUG] : APS data confirm response for request with id 53: 00
[2021-02-03 10:12:27][DEBUG] : Request id: 0x35 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9b73 endpoint=1>, status: 0x00
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e69000700aa00
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:12:27][DEBUG] : Send: 0x1769000800010001
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x17690024001d00220200000102739b0104010000060018340104008600afefbb4d0100c0
[2021-02-03 10:12:27][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9b73>, 1, 260, 0, b'\x184\x01\x04\x00\x86', 0, 175, 239, 187, 77, 1, 0, -64]
[2021-02-03 10:12:27][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=52 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:12:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9b73>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'183401040086'
[2021-02-03 10:12:27][DEBUG] : Sending Zigbee request with tsn 54 under 55 request id, data: b'0036000500'
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_request (20, 55, 0, <DeconzAddressEndpoint address_mode=2 address=0x9B73 endpoint=1>, 260, 0, 1, b'\x006\x00\x05\x00', 2, 0)
[2021-02-03 10:12:27][DEBUG] : Send: 0x126a001b001400370002739b010401000001050000360005000200
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x126a00090002002237
[2021-02-03 10:12:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 55]
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e6b000700a600
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:27][DEBUG] : Send: 0x046b0007000000
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x046b0013000c00223702739b01010000000000
[2021-02-03 10:12:27][DEBUG] : APS data confirm response for request with id 55: 00
[2021-02-03 10:12:27][DEBUG] : Request id: 0x37 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9b73 endpoint=1>, status: 0x00
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e6c000700aa00
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2021-02-03 10:12:27][DEBUG] : Send: 0x176c000800010001
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x176c0024001d00220200000102739b0104010000060018360105008600afefbb4d0100c0
[2021-02-03 10:12:27][DEBUG] : APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9b73>, 1, 260, 0, b'\x186\x01\x05\x00\x86', 0, 175, 239, 187, 77, 1, 0, -64]
[2021-02-03 10:12:27][DEBUG] : [0x9b73:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=54 command_id=Command.Read_Attributes_rsp>
[2021-02-03 10:12:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9b73>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'183601050086'
[2021-02-03 10:12:27][DEBUG] : [0x9b73:1] Manufacturer: None
[2021-02-03 10:12:27][DEBUG] : [0x9b73:1] Model: None
[2021-02-03 10:12:27][DEBUG] : [0x346d] Extending timeout for 0x38 request
[2021-02-03 10:12:27][DEBUG] : Sending Zigbee request with tsn 56 under 57 request id, data: b'00380004000500'
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_request (22, 57, 0, <DeconzAddressEndpoint address_mode=2 address=0x346D endpoint=1>, 260, 0, 1, b'\x008\x00\x04\x00\x05\x00', 2, 0)
[2021-02-03 10:12:27][DEBUG] : Send: 0x126d001d0016003900026d340104010000010700003800040005000200
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x126d00090002002239
[2021-02-03 10:12:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 57]
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x0e6e000700a600
[2021-02-03 10:12:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:27][DEBUG] : Send: 0x046e0007000000
[2021-02-03 10:12:27][DEBUG] : Frame received: 0x046e0013000c002239026d3401010000000000
[2021-02-03 10:12:27][DEBUG] : APS data confirm response for request with id 57: 00
[2021-02-03 10:12:27][DEBUG] : Request id: 0x39 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x346d endpoint=1>, status: 0x00
[2021-02-03 10:12:39][DEBUG] : [0xa72a] Extending timeout for 0x3a request
[2021-02-03 10:12:39][DEBUG] : Sending Zigbee request with tsn 58 under 59 request id, data: b'003a000500'
[2021-02-03 10:12:39][DEBUG] : Command Command.aps_data_request (20, 59, 0, <DeconzAddressEndpoint address_mode=2 address=0xA72A endpoint=1>, 260, 0, 1, b'\x00:\x00\x05\x00', 2, 0)
[2021-02-03 10:12:39][DEBUG] : Send: 0x126f001b0014003b00022aa70104010000010500003a0005000200
[2021-02-03 10:12:39][DEBUG] : Frame received: 0x126f0009000200223b
[2021-02-03 10:12:39][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 59]
[2021-02-03 10:12:39][DEBUG] : Frame received: 0x0e70000700a600
[2021-02-03 10:12:39][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2021-02-03 10:12:39][DEBUG] : Command Command.aps_data_confirm (0,)
[2021-02-03 10:12:39][DEBUG] : Send: 0x04700007000000
[2021-02-03 10:12:39][DEBUG] : Frame received: 0x04700013000c00223b022aa701010000000000
[2021-02-03 10:12:39][DEBUG] : APS data confirm response for request with id 59: 00
[2021-02-03 10:12:39][DEBUG] : Request id: 0x3b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa72a endpoint=1>, status: 0x00