Skip to content

Instantly share code, notes, and snippets.

@deviantintegral

deviantintegral/74-task-exception.log

Created June 14, 2021 14:55
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save deviantintegral/2ce4aecf57efdff4758e687ffe5f7489 to your computer and use it in GitHub Desktop.
Save deviantintegral/2ce4aecf57efdff4758e687ffe5f7489 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
74-task-exception.log
2021-06-14 10:39:59 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=weather.guelph, old_state=<state weather.guelph=rainy; temperature=14.7, humidity=91, wind_bearing=279.2, wind_speed=12.0, attribution=Data provided by Environment Canada, forecast=[{'datetime': '2021-06-14T10:39:29.454753-04:00', 'condition': 'rainy', 'precipitation_probability': 60, 'temperature': 22, 'templow': 16}, {'datetime': '2021-06-15T10:39:29.454763-04:00', 'temperature': 23, 'templow': 9, 'condition': 'partlycloudy', 'precipitation_probability': 0}, {'datetime': '2021-06-16T10:39:29.454771-04:00', 'temperature': 21, 'templow': 6, 'condition': 'sunny', 'precipitation_probability': 0}, {'datetime': '2021-06-17T10:39:29.454779-04:00', 'temperature': 26, 'templow': 16, 'condition': 'sunny', 'precipitation_probability': 0}, {'datetime': '2021-06-18T10:39:29.454786-04:00', 'temperature': 24, 'templow': 13, 'condition': 'rainy', 'precipitation_probability': 60}, {'datetime': '2021-06-19T10:39:29.454794-04:00', 'temperature': 22, 'templow': 10, 'condition': 'rainy', 'precipitation_probability': 60}], friendly_name=Guelph @ 2021-06-14T09:21:27.899727-04:00>, new_state=<state weather.guelph=rainy; temperature=14.7, humidity=91, wind_bearing=279.2, wind_speed=12.0, attribution=Data provided by Environment Canada, forecast=[{'datetime': '2021-06-14T10:39:59.161312-04:00', 'condition': 'rainy', 'precipitation_probability': 60, 'temperature': 22, 'templow': 16}, {'datetime': '2021-06-15T10:39:59.161334-04:00', 'temperature': 23, 'templow': 9, 'condition': 'partlycloudy', 'precipitation_probability': 0}, {'datetime': '2021-06-16T10:39:59.161353-04:00', 'temperature': 21, 'templow': 6, 'condition': 'sunny', 'precipitation_probability': 0}, {'datetime': '2021-06-17T10:39:59.161370-04:00', 'temperature': 26, 'templow': 16, 'condition': 'sunny', 'precipitation_probability': 0}, {'datetime': '2021-06-18T10:39:59.161387-04:00', 'temperature': 24, 'templow': 13, 'condition': 'rainy', 'precipitation_probability': 60}, {'datetime': '2021-06-19T10:39:59.161404-04:00', 'temperature': 22, 'templow': 10, 'condition': 'rainy', 'precipitation_probability': 60}], friendly_name=Guelph @ 2021-06-14T09:21:27.899727-04:00>>
2021-06-14 10:40:07 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ConcentratorInd.Callback(NWK=0xE797, IEEE=5c:02:72:ff:fe:ce:0c:cb, PktCost=1)
2021-06-14 10:40:07 WARNING (MainThread) [zigpy_znp.api] Received an unhandled command: ZDO.ConcentratorInd.Callback(NWK=0xE797, IEEE=5c:02:72:ff:fe:ce:0c:cb, PktCost=1)
2021-06-14 10:40:17 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2021-06-14 10:40:17 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0x0C50, Relays=[0x1338])
2021-06-14 10:40:27 INFO (MainThread) [zigpy_znp.zigbee.application] ZDO device relays: ZDO.SrcRtgInd.Callback(DstAddr=0x0C50, Relays=[0x1338])
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0x0C50, Relays=[0x1338])
2021-06-14 10:40:27 INFO (MainThread) [zigpy_znp.zigbee.application] ZDO device relays: ZDO.SrcRtgInd.Callback(DstAddr=0x0C50, Relays=[0x1338])
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=1024, SrcAddr=0x0C50, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=0, SecurityUse=<Bool.false: 0>, TimeStamp=12649976, TSN=0, Data=b'\x18\xD2\x0A\x00\x00\x21\xB8\x00', MacSrcAddr=0x1338, MsgResultRadius=28)
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.zcl] [0x0c50:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=210 command_id=Command.Report_Attributes>
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.zcl] [0x0c50:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=184>)]]
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.zcl] [0x0c50:1:0x0400] Attribute report received: measured_value=184
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lumi_lumi_sensor_motion_aq2_3958ba06_illuminance, old_state=<state sensor.lumi_lumi_sensor_motion_aq2_3958ba06_illuminance=180.0; unit_of_measurement=lx, friendly_name=LUMI lumi.sensor_motion.aq2 3958ba06 illuminance, device_class=illuminance @ 2021-06-14T10:35:28.456067-04:00>, new_state=<state sensor.lumi_lumi_sensor_motion_aq2_3958ba06_illuminance=184.0; unit_of_measurement=lx, friendly_name=LUMI lumi.sensor_motion.aq2 3958ba06 illuminance, device_class=illuminance @ 2021-06-14T10:40:27.361240-04:00>>
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=1030, SrcAddr=0x0C50, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=0, SecurityUse=<Bool.false: 0>, TimeStamp=12650828, TSN=0, Data=b'\x18\xD3\x0A\x00\x00\x18\x01', MacSrcAddr=0x1338, MsgResultRadius=28)
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.zcl] [0x0c50:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=211 command_id=Command.Report_Attributes>
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.zcl] [0x0c50:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.zcl] [0x0c50:1:0x0406] Attribute report received: occupancy=1
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0C50:1:0x0500]: Updated alarm state: 1
2021-06-14 10:40:27 DEBUG (MainThread) [zhaquirks] 00:15:8d:00:06:ba:58:39 - Received motion event message
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.main_floor_stairway, old_state=<state binary_sensor.main_floor_stairway=off; friendly_name=Main Floor Stairway, device_class=motion @ 2021-06-14T10:36:38.479751-04:00>, new_state=<state binary_sensor.main_floor_stairway=on; friendly_name=Main Floor Stairway, device_class=motion @ 2021-06-14T10:40:27.375443-04:00>>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=group.main_floor_hallway_sensors, old_state=<state group.main_floor_hallway_sensors=off; entity_id=('binary_sensor.dining_room_motion_sensor', 'binary_sensor.front_door_motion', 'binary_sensor.main_floor_stairway'), order=1, friendly_name=Main floor hallway sensors @ 2021-06-14T10:36:43.443066-04:00>, new_state=<state group.main_floor_hallway_sensors=on; entity_id=('binary_sensor.dining_room_motion_sensor', 'binary_sensor.front_door_motion', 'binary_sensor.main_floor_stairway'), order=1, friendly_name=Main floor hallway sensors @ 2021-06-14T10:40:27.376325-04:00>>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=group.laundry_room_sensors, old_state=<state group.laundry_room_sensors=off; entity_id=('binary_sensor.laundry_room_motion', 'binary_sensor.laundry_room_door', 'binary_sensor.main_floor_stairway'), order=2, friendly_name=Laundry room sensors @ 2021-06-14T10:36:38.481044-04:00>, new_state=<state group.laundry_room_sensors=on; entity_id=('binary_sensor.laundry_room_motion', 'binary_sensor.laundry_room_door', 'binary_sensor.main_floor_stairway'), order=2, friendly_name=Laundry room sensors @ 2021-06-14T10:40:27.376647-04:00>>
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.main_floor_hallway_lights] Main floor hallway lights: Restarting
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.laundry_room_light] Laundry Room Light: Restarting
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event automation_triggered[L]: name=Laundry Room Light, entity_id=automation.laundry_room_light, source=state of group.laundry_room_sensors>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=automation.laundry_room_light, old_state=<state automation.laundry_room_light=on; last_triggered=2021-06-14T10:35:28.478606-04:00, mode=restart, current=0, id=1618830618905, friendly_name=Laundry Room Light @ 2021-06-14T09:21:31.442138-04:00>, new_state=<state automation.laundry_room_light=on; last_triggered=2021-06-14T10:40:27.380505-04:00, mode=restart, current=1, id=1618830618905, friendly_name=Laundry Room Light @ 2021-06-14T09:21:31.442138-04:00>>
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.laundry_room_light] Laundry Room Light: Running automation actions
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.laundry_room_light] Laundry Room Light: Executing step call service
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=['light.laundry_room']>
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.Group: 1>, address=0x0005), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=16, Options=<TransmitOptions.NONE: 0>, Radius=0, Data=b'\x01\x10\x01')
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event automation_triggered[L]: name=Main floor hallway lights, entity_id=automation.main_floor_hallway_lights, source=state of group.main_floor_hallway_sensors>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=automation.main_floor_hallway_lights, old_state=<state automation.main_floor_hallway_lights=on; last_triggered=2021-06-14T10:35:28.481403-04:00, mode=restart, current=1, id=1620079155091, friendly_name=Main floor hallway lights @ 2021-06-14T09:21:31.442633-04:00>, new_state=<state automation.main_floor_hallway_lights=on; last_triggered=2021-06-14T10:40:27.385315-04:00, mode=restart, current=1, id=1620079155091, friendly_name=Main floor hallway lights @ 2021-06-14T09:21:31.442633-04:00>>
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.main_floor_hallway_lights] Main floor hallway lights: Running automation actions
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.main_floor_hallway_lights] Main floor hallway lights: Executing step call service
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=['light.main_floor_hallway']>
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.Group: 1>, address=0x0002), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=17, Options=<TransmitOptions.NONE: 0>, Radius=0, Data=b'\x01\x11\x01')
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.laundry_room: turned on: {'on_off': [1, <Status.SUCCESS: 0>]}
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.laundry_room, old_state=<state light.laundry_room=off; min_mireds=153, max_mireds=370, supported_color_modes=['color_temp'], off_brightness=None, friendly_name=Laundry Room, supported_features=43 @ 2021-06-14T10:38:38.507613-04:00>, new_state=<state light.laundry_room=on; min_mireds=153, max_mireds=370, supported_color_modes=['color_temp'], color_mode=unknown, off_brightness=None, friendly_name=Laundry Room, supported_features=43 @ 2021-06-14T10:40:27.404111-04:00>>
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=16)
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.laundry_room_2_7fd30103_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0x12a1] Extending timeout for 0x12 request
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.laundry_room_1_c2120103_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0x94cd] Extending timeout for 0x13 request
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.laundry_room, transition=1.0, brightness=254, color_temp=157>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.laundry_room, transition=1.0, brightness=254, rgb_color=(255, 251, 246)>
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.laundry_room_light] Laundry Room Light: Executing step call service
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=timer, service=cancel, service_data=entity_id=timer.laundry_room>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event timer.cancelled[L]: entity_id=timer.laundry_room>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=timer.laundry_room, old_state=<state timer.laundry_room=idle; duration=0:02:00, editable=False, friendly_name=Laundry Room Lights @ 2021-06-14T10:38:38.001434-04:00>, new_state=<state timer.laundry_room=idle; duration=0:02:00, editable=False, friendly_name=Laundry Room Lights @ 2021-06-14T10:40:27.412776-04:00>>
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.laundry_room_light] Laundry Room Light: Executing step wait for trigger
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.laundry_room_light] Laundry Room Light: Initialized trigger
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x12A1), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=18, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x12\x00\x00\x00')
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.main_floor_hallway: turned on: {'on_off': [1, <Status.SUCCESS: 0>]}
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.dining_room_light: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.front_door_1_a5660103_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0x214c] Extending timeout for 0x17 request
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.kitchen_hallway_1_92f90003_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0x404c] Extending timeout for 0x18 request
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.kitchen_hallway_2_02330103_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0xf83d] Extending timeout for 0x19 request
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.stairway_1_9c280103_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0x3e15] Extending timeout for 0x1a request
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.stairway_2_af7d0103_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0x2b36] Extending timeout for 0x1b request
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.components.zha.entity] light.front_door_2_8bca0103_level_light_color_on_off: polling current state
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy.device] [0x609a] Extending timeout for 0x1c request
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.main_floor_hallway_lights] Main floor hallway lights: Executing step call service
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=timer, service=start, service_data=duration=600, entity_id=['timer.main_floor_hallway']>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event timer.restarted[L]: entity_id=timer.main_floor_hallway>
2021-06-14 10:40:27 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=timer.main_floor_hallway, old_state=<state timer.main_floor_hallway=active; duration=0:10:00, editable=False, finishes_at=2021-06-14T14:45:28+00:00, remaining=0:10:00, friendly_name=Main Floor Lights @ 2021-06-14T10:35:28.575904-04:00>, new_state=<state timer.main_floor_hallway=active; duration=0:10:00, editable=False, finishes_at=2021-06-14T14:50:27+00:00, remaining=0:10:00, friendly_name=Main Floor Lights @ 2021-06-14T10:40:27.435254-04:00>>
2021-06-14 10:40:27 INFO (MainThread) [homeassistant.components.automation.main_floor_hallway_lights] Main floor hallway lights: Executing step delay 0:10:00
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0x12A1, Relays=[0x430D, 0x2499])
2021-06-14 10:40:27 INFO (MainThread) [zigpy_znp.zigbee.application] ZDO device relays: ZDO.SrcRtgInd.Callback(DstAddr=0x12A1, Relays=[0x430D, 0x2499])
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2021-06-14 10:40:27 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x94CD), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=19, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x13\x00\x00\x00')
2021-06-14 10:40:27 ERROR (MainThread) [homeassistant] Error doing job: Task exception was never retrieved
Traceback (most recent call last):
File "/usr/local/lib/python3.8/site-packages/zigpy_znp/api.py", line 580, in callback_handler
await callback_rsp
asyncio.exceptions.CancelledError
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/usr/local/lib/python3.8/site-packages/zigpy_znp/api.py", line 580, in callback_handler
await callback_rsp
File "/usr/local/lib/python3.8/site-packages/async_timeout/__init__.py", line 55, in __aexit__
self._do_exit(exc_type)
File "/usr/local/lib/python3.8/site-packages/async_timeout/__init__.py", line 92, in _do_exit
raise asyncio.TimeoutError
asyncio.exceptions.TimeoutError
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment