[2021-04-19 21:54:56][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:04:74:00:00:83:84:e4', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'on', 'await': 1}], 'allowQueue': False}
[2021-04-19 21:54:56][DEBUG] : request (0x7E69, 260, 6, 1, 1, 6, b'\x01\x06\x01', True, False)
[2021-04-19 21:54:56][DEBUG] : Send: 0x0530 b'027e69010100060104000003010601'
[2021-04-19 21:54:56][DEBUG] : Frame to send: b'\x050\x00\x0f)\x02~i\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\x06\x01'
[2021-04-19 21:54:56][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x02\x1f)\x02\x12~i\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x16\x02\x11'
[2021-04-19 21:54:56][DEBUG] : Frame received: 800000071c00ae0530000000
[2021-04-19 21:54:56][DEBUG] : data received 0x8000 b'00ae05300000' LQI:0
[2021-04-19 21:54:56][DEBUG] : zigate_callback_handler [0, 174, 1328, b'\x00\x00']
[2021-04-19 21:54:56][DEBUG] : Frame received: 800200132000010400060101027e6902000018060b0100b1
[2021-04-19 21:54:56][DEBUG] : data received 0x8002 b'00010400060101027e6902000018060b0100' LQI:177
[2021-04-19 21:54:56][DEBUG] : zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x7e69>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x06\x0b\x01\x00']
[2021-04-19 21:54:56][DEBUG] : [0x7e69:1:0x0006] 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.Default_Response>
[2021-04-19 21:54:56][INFO] : 200 PUT /device/command (127.0.0.1) 52.69ms
[2021-04-19 21:54:58][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:04:74:00:00:83:84:e4', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'off', 'await': 1}], 'allowQueue': False}
[2021-04-19 21:54:58][DEBUG] : request (0x7E69, 260, 6, 1, 1, 7, b'\x01\x07\x00', True, False)
[2021-04-19 21:54:58][DEBUG] : Send: 0x0530 b'027e69010100060104000003010700'
[2021-04-19 21:54:58][DEBUG] : Frame to send: b'\x050\x00\x0f)\x02~i\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\x07\x00'
[2021-04-19 21:54:58][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x02\x1f)\x02\x12~i\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x17\x02\x10'
[2021-04-19 21:54:58][DEBUG] : Frame received: 800000071d00af0530000000
[2021-04-19 21:54:58][DEBUG] : data received 0x8000 b'00af05300000' LQI:0
[2021-04-19 21:54:58][DEBUG] : zigate_callback_handler [0, 175, 1328, b'\x00\x00']
[2021-04-19 21:54:58][DEBUG] : Frame received: 800200132000010400060101027e6902000018070b0000b1
[2021-04-19 21:54:58][DEBUG] : data received 0x8002 b'00010400060101027e6902000018070b0000' LQI:177
[2021-04-19 21:54:58][DEBUG] : zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x7e69>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x07\x0b\x00\x00']
[2021-04-19 21:54:58][DEBUG] : [0x7e69:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=7 command_id=Command.Default_Response>
[2021-04-19 21:54:58][INFO] : 200 PUT /device/command (127.0.0.1) 59.30ms
[2021-04-19 21:54:59][DEBUG] : Frame received: 80020016930001040b040101027e69020000189e0a0b05290000b1
[2021-04-19 21:54:59][DEBUG] : data received 0x8002 b'0001040b040101027e69020000189e0a0b05290000' LQI:177
[2021-04-19 21:54:59][DEBUG] : zigate_callback_handler [0, 260, 2820, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x7e69>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x9e
\x0b\x05)\x00\x00']
[2021-04-19 21:54:59][DEBUG] : [0x7e69:1:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=158 command_id=Command.Report_Attributes>
[2021-04-19 21:54:59][DEBUG] : [0x7e69:1:0x0b04] ZCL request 0x000a: [[Attribute(attrid=1291, value=<TypeValue type=int16s, value=0>)]]
[2021-04-19 21:54:59][INFO] : [00:04:74:00:00:83:84:e4][listener.general_command] Cluster: <zigpy.zcl.clusters.homeautomation.ElectricalMeasurement object at 0x73be1cf0> ClusterId: 0x0b04 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=158 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1291, value=<TypeValue type=int16s, value=0>)]],)
[2021-04-19 21:54:59][DEBUG] : [0x7e69:1:0x0b04] Attribute report received: active_power=0
[2021-04-19 21:54:59][INFO] : [00:04:74:00:00:83:84:e4][listener.attribute_updated] Received an attribute update 1291=0 on cluster 2820
[2021-04-19 21:54:59][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73de6f20>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:04:74:00:00:83:84:e4, 1, 2820, 1291, 0))
[2021-04-19 21:54:59][DEBUG] : returning <sqlite3.Cursor object at 0x75003a20>
[2021-04-19 21:54:59][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73de6f20>)
[2021-04-19 21:54:59][DEBUG] : returning None
[2021-04-19 21:54:59][DEBUG] : Send to jeedom : {'devices': {'00:04:74:00:00:83:84:e4': {'1': {'2820': {'1291': {'value': '0', 'cluster_name': 'Electrical Measurement'}}}}}}
[2021-04-19 21:54:59][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-04-19 21:54:59][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=H3fqIuXg4xS4DptVvnG1hBW4nrTZKxbt HTTP/1.1" 200 0
[2021-04-19 21:55:00][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:04:74:00:00:83:84:e4', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'on', 'await': 1}], 'allowQueue': False}
[2021-04-19 21:55:00][DEBUG] : request (0x7E69, 260, 6, 1, 1, 8, b'\x01\x08\x01', True, False)
[2021-04-19 21:55:00][DEBUG] : Send: 0x0530 b'027e69010100060104000003010801'
[2021-04-19 21:55:00][DEBUG] : Frame to send: b"\x050\x00\x0f'\x02~i\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\x08\x01"
[2021-04-19 21:55:00][DEBUG] : Frame escaped: b"\x02\x150\x02\x10\x02\x1f'\x02\x12~i\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x18\x02\x11"
[2021-04-19 21:55:00][DEBUG] : Frame received: 800000070200b00530000000
[2021-04-19 21:55:00][DEBUG] : data received 0x8000 b'00b005300000' LQI:0
[2021-04-19 21:55:00][DEBUG] : zigate_callback_handler [0, 176, 1328, b'\x00\x00']
[2021-04-19 21:55:00][DEBUG] : Frame received: 800200132e00010400060101027e6902000018080b0100b1
[2021-04-19 21:55:00][DEBUG] : data received 0x8002 b'00010400060101027e6902000018080b0100' LQI:177
[2021-04-19 21:55:00][DEBUG] : zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x7e69>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x08\x0b\x01\x00']
[2021-04-19 21:55:00][DEBUG] : [0x7e69:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=8 command_id=Command.Default_Response>
[2021-04-19 21:55:00][INFO] : 200 PUT /device/command (127.0.0.1) 52.97ms
[2021-04-19 21:55:29][DEBUG] : Frame received: 800200169a0001040b040101027e69020000189f0a0b05290d00b4
[2021-04-19 21:55:29][DEBUG] : data received 0x8002 b'0001040b040101027e69020000189f0a0b05290d00' LQI:180
[2021-04-19 21:55:29][DEBUG] : zigate_callback_handler [0, 260, 2820, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x7e69>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x9f
\x0b\x05)
\x00']
[2021-04-19 21:55:29][DEBUG] : [0x7e69:1:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=159 command_id=Command.Report_Attributes>
[2021-04-19 21:55:29][DEBUG] : [0x7e69:1:0x0b04] ZCL request 0x000a: [[Attribute(attrid=1291, value=<TypeValue type=int16s, value=13>)]]
[2021-04-19 21:55:29][INFO] : [00:04:74:00:00:83:84:e4][listener.general_command] Cluster: <zigpy.zcl.clusters.homeautomation.ElectricalMeasurement object at 0x73be1cf0> ClusterId: 0x0b04 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=159 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1291, value=<TypeValue type=int16s, value=13>)]],)
[2021-04-19 21:55:29][DEBUG] : [0x7e69:1:0x0b04] Attribute report received: active_power=13
[2021-04-19 21:55:29][INFO] : [00:04:74:00:00:83:84:e4][listener.attribute_updated] Received an attribute update 1291=13 on cluster 2820
[2021-04-19 21:55:29][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73de6f20>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:04:74:00:00:83:84:e4, 1, 2820, 1291, 13))
[2021-04-19 21:55:29][DEBUG] : returning <sqlite3.Cursor object at 0x750038a0>
[2021-04-19 21:55:29][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73de6f20>)
[2021-04-19 21:55:29][DEBUG] : returning None
[2021-04-19 21:55:29][DEBUG] : Send to jeedom : {'devices': {'00:04:74:00:00:83:84:e4': {'1': {'2820': {'1291': {'value': '13', 'cluster_name': 'Electrical Measurement'}}}}}}
[2021-04-19 21:55:29][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-04-19 21:55:29][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=H3fqIuXg4xS4DptVvnG1hBW4nrTZKxbt HTTP/1.1" 200 0
[2021-04-19 21:57:05][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:04:74:00:00:0d:b7:44', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'on', 'await': 1}], 'allowQueue': False}
[2021-04-19 21:57:05][DEBUG] : request (0x0E25, 260, 6, 1, 1, 9, b'\x01	\x01', True, False)
[2021-04-19 21:57:05][DEBUG] : Send: 0x0530 b'020e25010100060104000003010901'
[2021-04-19 21:57:05][DEBUG] : Frame to send: b'\x050\x00\x0f\x1a\x02\x0e%\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01	\x01'
[2021-04-19 21:57:05][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x02\x1f\x1a\x02\x12\x02\x1e%\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x19\x02\x11'
[2021-04-19 21:57:05][DEBUG] : Frame received: 800000070300b10530000000
[2021-04-19 21:57:05][DEBUG] : data received 0x8000 b'00b105300000' LQI:0
[2021-04-19 21:57:05][DEBUG] : zigate_callback_handler [0, 177, 1328, b'\x00\x00']
[2021-04-19 21:57:05][DEBUG] : Frame received: 80020013cd00010400060101020e2502000018090b01006f
[2021-04-19 21:57:05][DEBUG] : data received 0x8002 b'00010400060101020e2502000018090b0100' LQI:111
[2021-04-19 21:57:05][DEBUG] : zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x0e25>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18	\x0b\x01\x00']
[2021-04-19 21:57:05][DEBUG] : [0x0e25:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=9 command_id=Command.Default_Response>
[2021-04-19 21:57:05][INFO] : 200 PUT /device/command (127.0.0.1) 49.65ms
[2021-04-19 21:57:06][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '00:04:74:00:00:0d:b7:44', 'cmd': [{'endpoint': 1, 'cluster': 'on_off', 'command': 'off', 'await': 1}], 'allowQueue': False}
[2021-04-19 21:57:06][DEBUG] : request (0x0E25, 260, 6, 1, 1, 10, b'\x01
\x00', True, False)
[2021-04-19 21:57:06][DEBUG] : Send: 0x0530 b'020e25010100060104000003010a00'
[2021-04-19 21:57:06][DEBUG] : Frame to send: b'\x050\x00\x0f\x18\x02\x0e%\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01
\x00'
[2021-04-19 21:57:06][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x02\x1f\x18\x02\x12\x02\x1e%\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x1a\x02\x10'
[2021-04-19 21:57:06][DEBUG] : Frame received: 800000070000b20530000000
[2021-04-19 21:57:06][DEBUG] : data received 0x8000 b'00b205300000' LQI:0
[2021-04-19 21:57:06][DEBUG] : zigate_callback_handler [0, 178, 1328, b'\x00\x00']
[2021-04-19 21:57:06][DEBUG] : Frame received: 80020013cc00010400060101020e25020000180a0b00006c
[2021-04-19 21:57:06][DEBUG] : data received 0x8002 b'00010400060101020e25020000180a0b0000' LQI:108
[2021-04-19 21:57:06][DEBUG] : zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x0e25>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18
\x0b\x00\x00']
[2021-04-19 21:57:06][DEBUG] : [0x0e25:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=10 command_id=Command.Default_Response>
[2021-04-19 21:57:06][INFO] : 200 PUT /device/command (127.0.0.1) 56.60ms
[2021-04-19 21:58:53][DEBUG] : Frame received: 80020016940001040b040101029ff7020000189e0a0b05290000c9
[2021-04-19 21:58:53][DEBUG] : data received 0x8002 b'0001040b040101029ff7020000189e0a0b05290000' LQI:201
[2021-04-19 21:58:53][DEBUG] : zigate_callback_handler [0, 260, 2820, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x9ff7>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x9e\n\x0b\x05)\x00\x00']
[2021-04-19 21:58:53][DEBUG] : [0x9ff7:1:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=158 command_id=Command.Report_Attributes>
[2021-04-19 21:58:53][DEBUG] : [0x9ff7:1:0x0b04] ZCL request 0x000a: [[Attribute(attrid=1291, value=<TypeValue type=int16s, value=0>)]]
[2021-04-19 21:58:53][INFO] : [00:04:74:00:00:83:84:f0][listener.general_command] Cluster: <zigpy.zcl.clusters.homeautomation.ElectricalMeasurement object at 0x73be1ef0> ClusterId: 0x0b04 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=158 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1291, value=<TypeValue type=int16s, value=0>)]],)
[2021-04-19 21:58:53][DEBUG] : [0x9ff7:1:0x0b04] Attribute report received: active_power=0
[2021-04-19 21:58:53][INFO] : [00:04:74:00:00:83:84:f0][listener.attribute_updated] Received an attribute update 1291=0 on cluster 2820
[2021-04-19 21:58:53][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73de6f20>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:04:74:00:00:83:84:f0, 1, 2820, 1291, 0))
[2021-04-19 21:58:53][DEBUG] : Frame received: 80020015fe000104000f0101029ff7020000189f0a55001000c3
[2021-04-19 21:58:53][DEBUG] : returning <sqlite3.Cursor object at 0x750038a0>
[2021-04-19 21:58:53][DEBUG] : data received 0x8002 b'000104000f0101029ff7020000189f0a55001000' LQI:195
[2021-04-19 21:58:53][DEBUG] : zigate_callback_handler [0, 260, 15, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x9ff7>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x9f\nU\x00\x10\x00']
[2021-04-19 21:58:53][DEBUG] : [0x9ff7:1:0x000f] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=159 command_id=Command.Report_Attributes>
[2021-04-19 21:58:53][DEBUG] : [0x9ff7:1:0x000f] ZCL request 0x000a: [[Attribute(attrid=85, value=<TypeValue type=Bool, value=Bool.false>)]]
[2021-04-19 21:58:53][INFO] : [00:04:74:00:00:83:84:f0][listener.general_command] Cluster: <zigpy.zcl.clusters.general.BinaryInput object at 0x73be1f30> ClusterId: 0x000f command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=159 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=85, value=<TypeValue type=Bool, value=Bool.false>)]],)
[2021-04-19 21:58:53][DEBUG] : [0x9ff7:1:0x000f] Attribute report received: present_value=0
[2021-04-19 21:58:53][INFO] : [00:04:74:00:00:83:84:f0][listener.attribute_updated] Received an attribute update 85=Bool.false on cluster 15
[2021-04-19 21:58:53][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73de6f20>)
[2021-04-19 21:58:53][DEBUG] : returning None
[2021-04-19 21:58:53][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73de6f20>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (00:04:74:00:00:83:84:f0, 1, 15, 85, <Bool.false: 0>))
[2021-04-19 21:58:53][DEBUG] : returning <sqlite3.Cursor object at 0x750038a0>
[2021-04-19 21:58:53][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73de6f20>)
[2021-04-19 21:58:53][DEBUG] : returning None
[2021-04-19 21:58:53][DEBUG] : Send to jeedom : {'devices': {'00:04:74:00:00:83:84:f0': {'1': {'2820': {'1291': {'value': '0', 'cluster_name': 'Electrical Measurement'}}, '15': {'85': {'value': 'Bool.false', 'cluster_name': 'Binary Input (Basic)'}}}}}}
[2021-04-19 21:58:53][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-04-19 21:58:53][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=H3fqIuXg4xS4DptVvnG1hBW4nrTZKxbt HTTP/1.1" 200 0