Skip to content

Instantly share code, notes, and snippets.

@Max101

Max101/log.log

Last active July 9, 2023 09:24
Show Gist options
  • Save Max101/0f05babf933f9c4f0c18ed58f93f713d to your computer and use it in GitHub Desktop.
Save Max101/0f05babf933f9c4f0c18ed58f93f713d to your computer and use it in GitHub Desktop.
Download ZIP
ZHA Light Auto turn on
Raw
log.log
2023-07-09 11:10:36.691 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893836.689953, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0})
2023-07-09 11:10:36.692 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893836.689953, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0}) completed
2023-07-09 11:10:36.692 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>)
2023-07-09 11:10:36.693 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>) completed
2023-07-09 11:10:36.694 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893836.690034, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0})
2023-07-09 11:10:36.694 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893836.690034, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0}) completed
2023-07-09 11:10:36.695 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>)
2023-07-09 11:10:36.695 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>) completed
2023-07-09 11:10:36.817 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2023-07-09 11:10:36.818 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=57)
2023-07-09 11:10:37.075 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=4, SrcAddr=0xEC80, SrcEndpoint=11, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=80, SecurityUse=<Bool.false: 0>, TimeStamp=12706519, TSN=0, Data=b'\x09\x39\x02\xFF\x02\x03\x00\x0A\x00', MacSrcAddr=0x6D49, MsgResultRadius=28)
2023-07-09 11:10:37.075 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xEC80), src_ep=11, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=4, data=Serialized[b'\t9\x02\xff\x02\x03\x00\n\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=80, rssi=None)
2023-07-09 11:10:37.076 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0004] Received ZCL frame: b'\t9\x02\xff\x02\x03\x00\n\x00'
2023-07-09 11:10:37.076 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=2, *direction=<Direction.Client_to_Server: 1>)
2023-07-09 11:10:37.076 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0004] Decoded ZCL frame: Groups:get_membership_response(capacity=255, groups=[0x0003, 0x000A])
2023-07-09 11:10:37.077 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893837.076135, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0})
2023-07-09 11:10:37.078 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893837.076135, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0}) completed
2023-07-09 11:10:37.078 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>)
2023-07-09 11:10:37.079 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>) completed
2023-07-09 11:10:37.573 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=8, SrcAddr=0xEC80, SrcEndpoint=11, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=83, SecurityUse=<Bool.false: 0>, TimeStamp=12738237, TSN=0, Data=b'\x08\x02\x0A\x00\x00\x20\xFE', MacSrcAddr=0x6D49, MsgResultRadius=28)
2023-07-09 11:10:37.573 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xEC80), src_ep=11, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=8, data=Serialized[b'\x08\x02\n\x00\x00 \xfe'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=83, rssi=None)
2023-07-09 11:10:37.573 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0008] Received ZCL frame: b'\x08\x02\n\x00\x00 \xfe'
2023-07-09 11:10:37.573 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=2, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-07-09 11:10:37.574 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254))])
2023-07-09 11:10:37.574 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0008] Received command 0x0A (TSN 2): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254))])
2023-07-09 11:10:37.574 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0008] Attribute report received: current_level=254
2023-07-09 11:10:37.574 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xEC80:11:0x0008]: received attribute: 0 update with value: 254
2023-07-09 11:10:37.574 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0008] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=2, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>)
2023-07-09 11:10:37.575 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0008] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2023-07-09 11:10:37.575 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=11, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xEC80), dst_ep=11, source_route=None, extended_timeout=False, tsn=2, profile_id=260, cluster_id=8, data=Serialized[b'\x18\x02\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-07-09 11:10:37.575 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xEC80), DstEndpoint=11, DstPanId=0x0000, SrcEndpoint=1, ClusterId=8, TSN=2, Options=<TransmitOptions.ACK_REQUEST|SUPPRESS_ROUTE_DISC_NETWORK: 48>, Radius=0, Data=b'\x18\x02\x0B\x0A\x00')
2023-07-09 11:10:37.576 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893837.573655, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0})
2023-07-09 11:10:37.576 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893837.573655, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0}) completed
2023-07-09 11:10:37.577 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>)
2023-07-09 11:10:37.577 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>) completed
2023-07-09 11:10:37.579 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, '\n INSERT INTO attributes_cache_v12\n VALUES (:ieee, :endpoint_id, :cluster_id, :attrid, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n AND :timestamp - last_updated > :min_update_delta\n ', {'ieee': 68:0a:e2:ff:fe:45:70:52, 'endpoint_id': 11, 'cluster_id': 8, 'attrid': 0, 'value': 254, 'timestamp': 1688893837.574473, 'min_update_delta': 30.0})
2023-07-09 11:10:37.580 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, '\n INSERT INTO attributes_cache_v12\n VALUES (:ieee, :endpoint_id, :cluster_id, :attrid, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n AND :timestamp - last_updated > :min_update_delta\n ', {'ieee': 68:0a:e2:ff:fe:45:70:52, 'endpoint_id': 11, 'cluster_id': 8, 'attrid': 0, 'value': 254, 'timestamp': 1688893837.574473, 'min_update_delta': 30.0}) completed
2023-07-09 11:10:37.582 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>)
2023-07-09 11:10:37.582 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>) completed
2023-07-09 11:10:37.589 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2023-07-09 11:10:37.869 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=2)
2023-07-09 11:10:38.271 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xEC80, SrcEndpoint=11, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=83, SecurityUse=<Bool.false: 0>, TimeStamp=12781770, TSN=0, Data=b'\x08\x00\x0A\x00\x00\x10\x01', MacSrcAddr=0x6D49, MsgResultRadius=28)
2023-07-09 11:10:38.272 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xEC80), src_ep=11, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x08\x00\n\x00\x00\x10\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=83, rssi=None)
2023-07-09 11:10:38.272 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0006] Received ZCL frame: b'\x08\x00\n\x00\x00\x10\x01'
2023-07-09 11:10:38.272 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=0, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-07-09 11:10:38.273 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2023-07-09 11:10:38.273 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0006] Received command 0x0A (TSN 0): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2023-07-09 11:10:38.273 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0006] Attribute report received: on_off=<Bool.true: 1>
2023-07-09 11:10:38.274 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893838.272579, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0})
2023-07-09 11:10:38.274 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1688893838.272579, 'ieee': 68:0a:e2:ff:fe:45:70:52, 'min_update_delta': 30.0}) completed
2023-07-09 11:10:38.276 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0006] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=0, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>)
2023-07-09 11:10:38.276 DEBUG (MainThread) [zigpy.zcl] [0xEC80:11:0x0006] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2023-07-09 11:10:38.276 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=11, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xEC80), dst_ep=11, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\x00\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-07-09 11:10:38.277 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xEC80), DstEndpoint=11, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=0, Options=<TransmitOptions.ACK_REQUEST|SUPPRESS_ROUTE_DISC_NETWORK: 48>, Radius=0, Data=b'\x18\x00\x0B\x0A\x00')
2023-07-09 11:10:38.278 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>)
2023-07-09 11:10:38.278 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>) completed
2023-07-09 11:10:38.279 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, '\n INSERT INTO attributes_cache_v12\n VALUES (:ieee, :endpoint_id, :cluster_id, :attrid, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n AND :timestamp - last_updated > :min_update_delta\n ', {'ieee': 68:0a:e2:ff:fe:45:70:52, 'endpoint_id': 11, 'cluster_id': 6, 'attrid': 0, 'value': <Bool.true: 1>, 'timestamp': 1688893838.273402, 'min_update_delta': 30.0})
2023-07-09 11:10:38.279 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fc9e73ba200>, '\n INSERT INTO attributes_cache_v12\n VALUES (:ieee, :endpoint_id, :cluster_id, :attrid, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n AND :timestamp - last_updated > :min_update_delta\n ', {'ieee': 68:0a:e2:ff:fe:45:70:52, 'endpoint_id': 11, 'cluster_id': 6, 'attrid': 0, 'value': <Bool.true: 1>, 'timestamp': 1688893838.273402, 'min_update_delta': 30.0}) completed
2023-07-09 11:10:38.280 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>)
2023-07-09 11:10:38.281 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fc9e73ba200>) completed
2023-07-09 11:10:38.304 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2023-07-09 11:10:38.330 DEBUG (MainThread) [homeassistant.components.zha.entity] light.zha_grp_living_room_0x0003: updating group state
2023-07-09 11:10:38.331 DEBUG (MainThread) [homeassistant.components.zha.entity] light.texas_instruments_cc1352_cc2652_z_stack_3_30_build_20220219_test_group_1_zha_group_0x000a: updating group state
2023-07-09 11:10:38.392 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=0)
2023-07-09 11:10:39.116 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x74E7](RB 265): Device seen - marking the device available and resetting counter
2023-07-09 11:10:39.116 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x74E7](RB 265): Update device availability - device available: True - new availability: True - changed: False
2023-07-09 11:10:39.166 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1777](GL-S-007P): Device seen - marking the device available and resetting counter
2023-07-09 11:10:39.166 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1777](GL-S-007P): Update device availability - device available: True - new availability: True - changed: False
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment