search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
73.47k stars 30.69k forks source link

Something is blocking Home Assistant from wrapping up the start up phase #53145

Closed kongo09 closed 3 years ago

kongo09 commented 3 years ago

The problem

Upgrading from 2021.6.6 straight to 2021.7.3 produces a warnings in the log in an otherwise well running setup. The "something is blocking" warning asks to report it here, so I do.

What is version of Home Assistant Core has the issue?

2021.7.3

What was the last working version of Home Assistant Core?

2021.6.6

What type of installation are you running?

Home Assistant Supervised

Integration causing the issue

No response

Link to integration documentation on our website

No response

Example YAML snippet

No response

Anything in the logs that might be useful for us?

2021-07-18 14:18:45 WARNING (MainThread) [homeassistant.core] Something is blocking Home Assistant from wrapping up the start up phase. We're going to continue anyway. Please report the following info at https://github.com/home-assistant/core/issues: binary_sensor, vacuum.xiaomi_miio, default_config, shelly, my, sensor.command_line, input_datetime, automation, spotify, switch.fritz, sensor.plex, sun, logbook, system_health, notify, openweathermap, binary_sensor.updater, stt, configurator, binary_sensor.synology_dsm, binary_sensor.mobile_app, light.group, binary_sensor.hassio, synology_dsm, media_player.webostv, search, analytics, input_text, binary_sensor.browser_mod, persistent_notification, ping, camera.browser_mod, history, sensor.browser_mod, rest, nuki, wake_on_lan, speedtestdotnet, template, group, spotcast, updater, device_tracker, notify.webostv, notify.mobile_app, xiaomi_miio, lock.template, sensor.fritzbox_netmonitor, stream, hvv_departures, rest_command, timer, map, browser_mod, counter, auth, sensor.hvv_departures, media_player.plex, fan, webhook, media_player.browser_mod, homeassistant, sensor.utility_meter, webostv, switch.adguard, switch.presence_simulation, hassio, fritz, tts, binary_sensor.cloud, switch.synology_dsm, adguard, media_player.squeezebox, camera.synology_dsm, sensor.fritz, sensor.adguard, blueprint, weather.openweathermap, media_player.cast, plex, lock.nuki, vacuum, http, sensor.hacs, sensor, media_source, sensor.synology_dsm, lovelace, fan.philips_airpurifier_coap, sensor.derivative, tts.cloud, zeroconf, input_boolean, dialogflow, mobile_app, onboarding, sensor.history_stats, api, lock, device_tracker.fritz, input_select, system_log, weather, notify.tts, binary_sensor.fritz, zone, binary_sensor.nuki, sensor.time_date, camera.xiaomi_cloud_map_extractor, cloud, websocket_api, switch, input_number, recorder, trace, device_automation, hacs, binary_sensor.ping, cast, media_player.onkyo, tts.voicerss, logger, image, tts.google_translate, tts.microsoft, media_player, presence_simulation, binary_sensor.hvv_departures, person, utility_meter, sensor.average, multiscrape, config, sensor.mobile_app, network, sensor.speedtestdotnet, script, sensor.hassio, light.browser_mod, light, scene.homeassistant, sensor.spotcast, tag, dhcp, media_player.spotify, squeezebox, camera, sensor.openweathermap, frontend, sensor.multiscrape, scene, ssdp, sensor.template, device_tracker.mobile_app

Additional information

No response

bdraco commented 3 years ago

That error probably isn't very helpful without a thread and task dump. We could probably improve that.

In the mean time if you install the profiler integration you can request them manually.

After installing the profiler integration, restart, then once home assistant gets to the wrapping up Phase and it's stalling call these services from the developer tools panel

SERVICE PROFILER.LOG_THREAD_FRAMES SERVICE PROFILER.LOG_EVENT_LOOP_SCHEDULED

kongo09 commented 3 years ago

Thanks. I'll look into this. With profiler integration, I suppose you're talking about this here: https://www.home-assistant.io/integrations/profiler/

kongo09 commented 3 years ago

Here comes the full log with profiler enabled and after the two service calls.

It doesn't show the original warning of "something is blocking" anymore. Not sure why. From my limited understanding I can see some connectivity problems with Google chromecast / Nest devices which have been ongoing for a long time and are reported here #47923 . There also seems to be a problem with a custom integration of Philips Air Purifiers, which are buggy beasts. I'll see if I can find time to take them out of HA and the redo the profiling. The rest I cannot judge.

2021-07-18 22:09:09 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration average which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration presence_simulation which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration xiaomi_cloud_map_extractor which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_3) [homeassistant.loader] We found a custom integration multiscrape which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration shelly which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_3) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration philips_airpurifier_coap which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_3) [homeassistant.loader] We found a custom integration browser_mod which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:09 WARNING (SyncWorker_5) [homeassistant.loader] We found a custom integration spotcast which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 22:09:14 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [4043073640] Received invalid command: browser_mod/connect
2021-07-18 22:09:29 WARNING (MainThread) [homeassistant.components.fan] Setup of fan platform philips_airpurifier_coap is taking over 10 seconds.
2021-07-18 22:09:29 WARNING (MainThread) [homeassistant.components.fan] Setup of fan platform philips_airpurifier_coap is taking over 10 seconds.
2021-07-18 22:09:32 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.staubsauger_karte is taking over 10 seconds
2021-07-18 22:10:16 ERROR (Thread-35) [pychromecast.socket_client] [Leo's Zimmer(192.168.0.65):8009] Failed to connect to service ServiceInfo(type='mdns', data='Nest-Audio-7a86e1cad3ca9faea7acb4aab2f71232._googlecast._tcp.local.'), retrying in 5.0s
2021-07-18 22:10:17 WARNING (Thread-36) [pychromecast.socket_client] [Alle Lautsprecher(192.168.0.65):32219] Heartbeat timeout, resetting connection
2021-07-18 22:10:19 ERROR (MainThread) [homeassistant.components.fan] Setup of platform philips_airpurifier_coap is taking longer than 60 seconds. Startup will proceed without waiting any longer.
2021-07-18 22:10:19 ERROR (MainThread) [homeassistant.components.fan] Setup of platform philips_airpurifier_coap is taking longer than 60 seconds. Startup will proceed without waiting any longer.
2021-07-18 22:10:19 WARNING (MainThread) [homeassistant.components.homeassistant.triggers.numeric_state] Error initializing 'Notify mobile for Schlafzimmer Air Purifier maintenance' trigger: In 'numeric_state' condition: attribute 'filter_pre_remaining_raw' (of entity fan.air_ac2729) does not exist
2021-07-18 22:10:19 WARNING (MainThread) [homeassistant.components.homeassistant.triggers.numeric_state] Error initializing 'Notify mobile for Kinderzimmer Air Purifier maintenance' trigger: In 'numeric_state' condition: attribute 'filter_pre_remaining_raw' (of entity fan.air_ac2889) does not exist
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1512, in _monitor
record = self.dequeue(True)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_0]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 77, in _worker
work_item.run()
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/src/homeassistant/homeassistant/components/speedtestdotnet/__init__.py", line 179, in update_data
self.api.download()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 1569, in download
prod_thread.join(timeout=0.001)
File "/usr/local/lib/python3.9/threading.py", line 1037, in join
self._wait_for_tstate_lock(timeout=max(timeout, 0))
File "/usr/local/lib/python3.9/threading.py", line 1051, in _wait_for_tstate_lock
self._stop()
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_3]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_4]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_5]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_6]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_7]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_8]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Recorder]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 614, in run
self._run_event_loop()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 623, in _run_event_loop
self._process_one_event_or_recover(event)
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 632, in _process_one_event_or_recover
self._process_one_event(event)
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 752, in _process_one_event
self._commit_event_session_or_retry()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 815, in _commit_event_session_or_retry
self._commit_event_session()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 834, in _commit_event_session
self.event_session.flush()
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/orm/session.py", line 3286, in flush
self._flush(objects)
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/orm/session.py", line 3386, in _flush
flush_context.execute()
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/orm/unitofwork.py", line 453, in execute
n.execute_aggregate(self, set_)
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/orm/unitofwork.py", line 555, in execute_aggregate
self.execute(uow)
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/orm/unitofwork.py", line 630, in execute
util.preloaded.orm_persistence.save_obj(
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/orm/persistence.py", line 242, in save_obj
_emit_insert_statements(
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/orm/persistence.py", line 1219, in _emit_insert_statements
result = connection._execute_20(
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 1582, in _execute_20
return meth(self, args_10style, kwargs_10style, execution_options)
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/sql/elements.py", line 324, in _execute_on_connection
return connection._execute_clauseelement(
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 1451, in _execute_clauseelement
ret = self._execute_context(
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/engine/base.py", line 1770, in _execute_context
self.dialect.do_execute(
File "/usr/local/lib/python3.9/site-packages/sqlalchemy/engine/default.py", line 717, in do_execute
cursor.execute(statement, parameters)
File "/usr/local/lib/python3.9/site-packages/MySQLdb/cursors.py", line 206, in execute
res = self._query(query)
File "/usr/local/lib/python3.9/site-packages/MySQLdb/cursors.py", line 319, in _query
db.query(q)
File "/usr/local/lib/python3.9/site-packages/MySQLdb/connections.py", line 259, in query
_mysql.connection.query(self, query)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1512, in _monitor
record = self.dequeue(True)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_googlecast._tcp-246]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-4]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 252, in run
self._poll_hosts()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 268, in _poll_hosts
device_status = get_device_status(host, timeout=4, context=self._context)
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 109, in get_device_status
status = _get_status(
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 88, in _get_status
with urllib.request.urlopen(req, timeout=timeout, context=context) as response:
File "/usr/local/lib/python3.9/urllib/request.py", line 214, in urlopen
return opener.open(url, data, timeout)
File "/usr/local/lib/python3.9/urllib/request.py", line 517, in open
response = self._open(req, data)
File "/usr/local/lib/python3.9/urllib/request.py", line 534, in _open
result = self._call_chain(self.handle_open, protocol, protocol +
File "/usr/local/lib/python3.9/urllib/request.py", line 494, in _call_chain
result = func(*args)
File "/usr/local/lib/python3.9/urllib/request.py", line 1389, in https_open
return self.do_open(http.client.HTTPSConnection, req,
File "/usr/local/lib/python3.9/urllib/request.py", line 1350, in do_open
r = h.getresponse()
File "/usr/local/lib/python3.9/http/client.py", line 1345, in getresponse
response.begin()
File "/usr/local/lib/python3.9/http/client.py", line 307, in begin
version, status, reason = self._read_status()
File "/usr/local/lib/python3.9/http/client.py", line 268, in _read_status
line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
File "/usr/local/lib/python3.9/ssl.py", line 1241, in recv_into
return self.read(nbytes, buffer)
File "/usr/local/lib/python3.9/ssl.py", line 1099, in read
return self._sslobj.read(len, buffer)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [FirmwareManage]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 36, in _start_loop
self.loop()
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 56, in loop
self._loop_root.stopped.wait(self._loop_delay)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [CoAP]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/coap.py", line 104, in _loop
data_tmp, addr = self._socket.recvfrom(1024)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_http._tcp-250]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-10]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3452, in _thread_main
self.loop_forever(retry_first_connection=True)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 1798, in loop_forever
self._reconnect_wait()
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3473, in _reconnect_wait
time.sleep(min(remaining, 1))
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Update loop]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/__init__.py", line 337, in _update_loop
self.stopped.wait(5)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-12]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-13]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 618, in run_once
return 0
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-35]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-36]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [DHCPWatcher]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/scapy/sendrecv.py", line 1175, in _run
sockets = select_func(list(sniff_sockets.keys()), remain)
File "/usr/local/lib/python3.9/site-packages/scapy/supersocket.py", line 259, in select
inp, _, _ = select(sockets, [], [], remain)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-37]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 1541, in producer
timeit.time.sleep(0.001)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-38]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 1554, in consumer
thread.join(timeout=0.001)
File "/usr/local/lib/python3.9/threading.py", line 1037, in join
self._wait_for_tstate_lock(timeout=max(timeout, 0))
File "/usr/local/lib/python3.9/threading.py", line 1051, in _wait_for_tstate_lock
self._stop()
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-57]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 814, in run
self.result.append(len(f.read(10240)))
File "/usr/local/lib/python3.9/http/client.py", line 456, in read
return memoryview(b)[:n].tobytes()
File "/usr/local/lib/python3.9/http/client.py", line 508, in readinto
return n
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-59]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 814, in run
self.result.append(len(f.read(10240)))
File "/usr/local/lib/python3.9/http/client.py", line 456, in read
return memoryview(b)[:n].tobytes()
File "/usr/local/lib/python3.9/http/client.py", line 508, in readinto
return n
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-60]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 814, in run
self.result.append(len(f.read(10240)))
File "/usr/local/lib/python3.9/http/client.py", line 456, in read
return memoryview(b)[:n].tobytes()
File "/usr/local/lib/python3.9/http/client.py", line 508, in readinto
return n
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-61]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 814, in run
self.result.append(len(f.read(10240)))
File "/usr/local/lib/python3.9/http/client.py", line 456, in read
return memoryview(b)[:n].tobytes()
File "/usr/local/lib/python3.9/http/client.py", line 508, in readinto
return n
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-62]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 814, in run
self.result.append(len(f.read(10240)))
File "/usr/local/lib/python3.9/http/client.py", line 456, in read
return memoryview(b)[:n].tobytes()
File "/usr/local/lib/python3.9/http/client.py", line 508, in readinto
return n
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-63]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 814, in run
self.result.append(len(f.read(10240)))
File "/usr/local/lib/python3.9/http/client.py", line 456, in read
return memoryview(b)[:n].tobytes()
File "/usr/local/lib/python3.9/http/client.py", line 508, in readinto
return n
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-64]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 814, in run
self.result.append(len(f.read(10240)))
File "/usr/local/lib/python3.9/http/client.py", line 456, in read
return memoryview(b)[:n].tobytes()
File "/usr/local/lib/python3.9/http/client.py", line 508, in readinto
return n
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 22:10:34 ERROR (SyncWorker_4) [homeassistant] Error doing job: Task was destroyed but it is pending!
2021-07-18 22:10:34 ERROR (SyncWorker_4) [homeassistant] Error doing job: Task was destroyed but it is pending!
2021-07-18 22:10:34 ERROR (SyncWorker_4) [homeassistant] Error doing job: Task was destroyed but it is pending!
2021-07-18 22:10:34 ERROR (SyncWorker_4) [homeassistant] Error doing job: Task was destroyed but it is pending!
2021-07-18 22:10:34 ERROR (MainThread) [coap] Connection loss was not expected.
2021-07-18 22:10:34 ERROR (MainThread) [coap] Connection loss was not expected.
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91516.482222461 _weakref_handle((<weakref at 0xf2210d98; to 'TCPConnector' at 0xf220d910>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91517.090529697 _async_create_timer.<locals>.fire_time_event(91517.090524827) at /usr/src/homeassistant/homeassistant/core.py:1760>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91516.482312849 _weakref_handle((<weakref at 0xf09c9640; to 'TCPConnector' at 0xf08ddcb8>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91517.18487313368 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf0ae47c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91518.634401169 timeout._cancel_task()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91519.94014185837 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf1a70da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91518.629900643 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xea655b80>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91520.17221129716 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf08e1730>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91519.94720630765 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec0297c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91520.24427311703 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe5f97bf8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91518.94434950833 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec5e0538>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91521 _weakref_handle((<weakref at 0xf2210d98; to 'TCPConnector' at 0xf220d910>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91520.24411166635 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe5f97970>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91521.0695684625 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeac70c40>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91527 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91521.43512960305 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa75100>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91521.09048900974 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xebd6aa90>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91520.7524101935 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeb0e9070>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91526.124929223 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xe31101f0>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91526.181057656 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xe3110478>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91527.03976577515 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf0e714a8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91535.692632892 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xebfe6988>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91540.73324210933 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf1753a48>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92077.67777283896 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe2fe3b68>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91541.36437605302 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa75220>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91526.68740173591 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec4b9ad8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91545.72874043284 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91533 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91540.33627432011 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeb08a850>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91576.09048803318 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Date & Time (ISO): 2021-07-18T22:11:00>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91526.12547827 timeout._cancel_task()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91572 WebSocketResponse._send_heartbeat()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91540.6882050496 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeb0a8580>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98677.67755947121 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaf51da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91539.93067671655 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec03a100>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91547.21747198423 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91576.09049725243 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xea71cd60>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91540.337497871 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeb08aa90>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=108856.09048857281 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xea8baad8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91718.09833666815 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xecaf25c8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=94969.489502052 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xf10362b0>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92436.58115531139 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_events of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=112456.09048975063 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xea71c4f0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=168634.58073540378 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method SunListener._handle_sun_event of SunListener(hass=<homeassistant.core.HomeAssistant object at 0xf58824c0>, j...unsub_point_in_time_listener at 0xea71c808>, _unsub_config=<function EventBus._async_listen_filterable_job.<locals>.remove_listener at 0xea71aa48>)>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91541.50093408137 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeac4d7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91541.7407641227 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xea65dc88>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91540.579118344 RequestHandler._process_keepalive()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92334.09049319923 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xeaa69e50>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91540.579187028 RequestHandler._process_keepalive()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=126856.090489061 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xea71c580>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91546.76734665791 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91590.5855607365 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method Store._async_callback_delayed_write of <homeassistant.helpers.storage.Store object at 0xf10492c8>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92320.09049282344 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xed2c5400>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=177875.76137996744 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf258d268>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92375.58171267786 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe68517c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=113176.09048971231 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xea71cbb0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91725.17422486498 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe712cf10>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92320.0904927371 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xed2c55c8>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91530 _weakref_handle((<weakref at 0xf09c9640; to 'TCPConnector' at 0xf08ddcb8>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91721 TimeoutHandle.__call__()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91652.284538077 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_sun_position of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91527.00515484286 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf0e67658>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=94456.0904936261 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xeb008460>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91539.35342122361 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup_entry.<locals>._discovery at 0xed8d8e80>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91539.09048987066 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xee1fa220>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91551.643054936 RequestHandler._process_keepalive()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=95101.0904896163 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.components.speedtestdotnet.SpeedTestDataCoordinator object at 0xf09ce058>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91771 TimeoutHandle.__call__()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91540.33643143337 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeb08a928>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91534 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=94712.2126762263 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup.<locals>.update_info_data at 0xf09a7a48>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=173656.09049496852 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xea8bacd0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91558.638700215 timeout._cancel_task()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=173656.0904939737 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xea71c388>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=95076.39342994319 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe3251d60>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92006.16407007212 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf2f5dbf8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=95020.5533180898 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xf1acf4a8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91576.09048907785 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Time: 22:11>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=95020.55507056462 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xf1ab3f10>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92018.09049235817 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.components.openweathermap.weather_update_coordinator.WeatherUpdateCoordinator object at 0xf0891dc0>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=177818.09048985352 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xf0915490>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91526.12441963912 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xea62bf10>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=181477.67767762986 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe2fe3a00>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91711 TimeoutHandle.__call__()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100622.090532954 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xf1040bc8>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=91817 TimeoutHandle.__call__()>
2021-07-18 22:11:00 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=92375.76123956793 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method Analytics.send_analytics of <homeassistant.components.analytics.analytics.Analytics object at 0xf1ecee08>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 22:11:53 WARNING (Thread-36) [pychromecast.socket_client] [Alle Lautsprecher(192.168.0.65):32219] Heartbeat timeout, resetting connection
2021-07-18 22:11:53 WARNING (Thread-35) [pychromecast.socket_client] [Leo's Zimmer(192.168.0.65):8009] Heartbeat timeout, resetting connection
bdraco commented 3 years ago 
2021-07-18 22:10:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 77, in _worker
work_item.run()
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/src/homeassistant/homeassistant/components/speedtestdotnet/__init__.py", line 179, in update_data
self.api.download()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 1569, in download
prod_thread.join(timeout=0.001)
File "/usr/local/lib/python3.9/threading.py", line 1037, in join
self._wait_for_tstate_lock(timeout=max(timeout, 0))
File "/usr/local/lib/python3.9/threading.py", line 1051, in _wait_for_tstate_lock
self._stop()

Looks like speedtest but not sure if you got the profile to happen before startup finished based on your comments

kongo09 commented 3 years ago

I called the profiler service calls just after the wrap-up of the start finished. Did I do this correctly?

bdraco commented 3 years ago

From the thread it looks like speed test was already doing an update by calling update_data, but its not supposed to do that until after the STARTED event (https://github.com/home-assistant/core/blob/dev/homeassistant/components/speedtestdotnet/__init__.py#L97) so either we have a bug in speedtestdotnet, or the thread dump happened after startup is finished. I can't tell for sure, but it would be helpful if you tried to do a few of them so we can narrow it down.

kongo09 commented 3 years ago

Just that I understand correctly, you want me to call the service calls of the profiler before the wrapup is finished?

bdraco commented 3 years ago

Just that I understand correctly, you want me to call the service calls of the profiler before the wrapup is finished?

Yes

kongo09 commented 3 years ago

Here it comes. I called both services 3 times. The third might be after the wrapup.

2021-07-18 23:48:18 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration presence_simulation which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration average which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration xiaomi_cloud_map_extractor which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration spotcast which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_3) [homeassistant.loader] We found a custom integration multiscrape which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration philips_airpurifier_coap which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration browser_mod which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:18 WARNING (SyncWorker_4) [homeassistant.loader] We found a custom integration shelly which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2021-07-18 23:48:27 ERROR (SyncWorker_4) [homeassistant] Error doing job: Task was destroyed but it is pending!
2021-07-18 23:48:34 WARNING (MainThread) [homeassistant.components.fan] Setup of fan platform philips_airpurifier_coap is taking over 10 seconds.
2021-07-18 23:48:34 WARNING (MainThread) [homeassistant.components.fan] Setup of fan platform philips_airpurifier_coap is taking over 10 seconds.
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97374.14436828507 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97374.225932312 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xea9b8058>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97374.36526189829 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebff1d60>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97375.339561419 Debouncer._schedule_timer.<locals>.<lambda>() at /usr/src/homeassistant/homeassistant/helpers/debounce.py:125>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97375.82883194023 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebd7c388>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97375.5539676 _weakref_handle((<weakref at 0xf1e42488; to 'TCPConnector' at 0xf1e3d568>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97375.80749219388 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebe33e38>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97377.17944216859 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeadb3268>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97375.88813496352 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec722418>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97377.46742064328 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa83da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97375.962533858 _weakref_handle((<weakref at 0xed4f2258; to 'TCPConnector' at 0xed87ed30>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.010365784 _ZoneTaskContext._on_timeout()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97378.696608411 Debouncer._schedule_timer.<locals>.<lambda>() at /usr/src/homeassistant/homeassistant/helpers/debounce.py:125>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97376.09049019356 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xeaa6cfa0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97377.0887660921 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf0bde190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97379.18870821316 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97384.142597899 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xe708a3a0>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97393.01252221844 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf03c49b8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97396.09048832314 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Date & Time (ISO): 2021-07-18T23:48:00>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97424.92408920491 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup_entry.<locals>._discovery at 0xecba1190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97383.781823754 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xecc311d8>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97386.99289160183 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf1339658>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97381.95826243174 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.21534137 RequestHandler._process_keepalive()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97425.57600125363 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebefbbf8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97379 _weakref_handle((<weakref at 0xed4f2258; to 'TCPConnector' at 0xed87ed30>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97397.20523114147 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeab256a0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97393.01229497684 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeccfdb20>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97397.20544179944 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeab25898>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97387 _weakref_handle((<weakref at 0xf1e42488; to 'TCPConnector' at 0xf1e3d568>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97396.71918627017 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebd9d340>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97381.94390784366 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa831d8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97430 WebSocketResponse._send_heartbeat()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97424.34376427965 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebff1850>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100496.090536255 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xf0bdcb38>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97657 TimeoutHandle.__call__()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97668 TimeoutHandle.__call__()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97663.40536260702 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xecd43460>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98056.09049102802 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xec23a7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100128.019142782 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xed89f148>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.871662391 RequestHandler._process_keepalive()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97963.0904953097 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.components.openweathermap.weather_update_coordinator.WeatherUpdateCoordinator object at 0xf03c5c70>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100656.60189339606 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup.<locals>.update_info_data at 0xf0c24da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97393.01272342395 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec9b3da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97395.1029577138 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xedbeb808>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97383 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97536.4043996615 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method Store._async_callback_delayed_write of <homeassistant.helpers.storage.Store object at 0xf0beca00>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.217017843 RequestHandler._process_keepalive()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.218401321 RequestHandler._process_keepalive()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.21986926 RequestHandler._process_keepalive()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97657.89290362 _GlobalTaskContext._on_timeout()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97838.0459078689 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_sun_position of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97396.09049206747 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecf53298>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.010617687 _ZoneTaskContext._on_timeout()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100966.0412166068 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xee0ea538>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97459.221573503 timeout._cancel_task()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97419.221706908 timeout._cancel_task()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97416.25255366284 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_enable_report_state.<locals>.initial_report at 0xf0fc8100>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97954.89634295396 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf2b1de80>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=103240.09048772718 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_events of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97396.09048865913 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Time: 23:48>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98267.09049719007 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecc01958>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98267.09049340694 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecc01ad8>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97670.39145463354 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe70681d8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97417 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=183764.0904906536 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xf01f1460>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.873732209 RequestHandler._process_keepalive()>
2021-07-18 23:48:38 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100965.83481304215 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xee0ea7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1512, in _monitor
record = self.dequeue(True)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_0]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_3]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_4]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 77, in _worker
work_item.run()
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/src/homeassistant/homeassistant/components/nuki/__init__.py", line 45, in _update_devices
device.update(level)
File "/usr/local/lib/python3.9/site-packages/pynuki/device.py", line 88, in update
for l in self._bridge._get_devices(self.device_type)
File "/usr/local/lib/python3.9/site-packages/pynuki/bridge.py", line 199, in _get_devices
for l in self.list(device_type=device_type):
File "/usr/local/lib/python3.9/site-packages/pynuki/bridge.py", line 128, in list
data = self.__rq("list")
File "/usr/local/lib/python3.9/site-packages/pynuki/bridge.py", line 103, in __rq
result = requests.get(
File "/usr/local/lib/python3.9/site-packages/requests/api.py", line 76, in get
return request('get', url, params=params, **kwargs)
File "/usr/local/lib/python3.9/site-packages/requests/api.py", line 61, in request
return session.request(method=method, url=url, **kwargs)
File "/usr/local/lib/python3.9/site-packages/requests/sessions.py", line 542, in request
resp = self.send(prep, **send_kwargs)
File "/usr/local/lib/python3.9/site-packages/requests/sessions.py", line 655, in send
r = adapter.send(request, **kwargs)
File "/usr/local/lib/python3.9/site-packages/requests/adapters.py", line 439, in send
resp = conn.urlopen(
File "/usr/local/lib/python3.9/site-packages/urllib3/connectionpool.py", line 699, in urlopen
httplib_response = self._make_request(
File "/usr/local/lib/python3.9/site-packages/urllib3/connectionpool.py", line 440, in _make_request
httplib_response = conn.getresponse()
File "/usr/local/lib/python3.9/http/client.py", line 1345, in getresponse
response.begin()
File "/usr/local/lib/python3.9/http/client.py", line 307, in begin
version, status, reason = self._read_status()
File "/usr/local/lib/python3.9/http/client.py", line 268, in _read_status
line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_5]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 77, in _worker
work_item.run()
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/local/lib/python3.9/site-packages/pychromecast/__init__.py", line 86, in get_chromecast_from_cast_info
return Chromecast(
File "/usr/local/lib/python3.9/site-packages/pychromecast/__init__.py", line 311, in __init__
dev_status = get_device_status(host, services, zconf)
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 109, in get_device_status
status = _get_status(
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 88, in _get_status
with urllib.request.urlopen(req, timeout=timeout, context=context) as response:
File "/usr/local/lib/python3.9/urllib/request.py", line 214, in urlopen
return opener.open(url, data, timeout)
File "/usr/local/lib/python3.9/urllib/request.py", line 517, in open
response = self._open(req, data)
File "/usr/local/lib/python3.9/urllib/request.py", line 534, in _open
result = self._call_chain(self.handle_open, protocol, protocol +
File "/usr/local/lib/python3.9/urllib/request.py", line 494, in _call_chain
result = func(*args)
File "/usr/local/lib/python3.9/urllib/request.py", line 1389, in https_open
return self.do_open(http.client.HTTPSConnection, req,
File "/usr/local/lib/python3.9/urllib/request.py", line 1346, in do_open
h.request(req.get_method(), req.selector, req.data, headers,
File "/usr/local/lib/python3.9/http/client.py", line 1253, in request
self._send_request(method, url, body, headers, encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1299, in _send_request
self.endheaders(body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1248, in endheaders
self._send_output(message_body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1008, in _send_output
self.send(msg)
File "/usr/local/lib/python3.9/http/client.py", line 948, in send
self.connect()
File "/usr/local/lib/python3.9/http/client.py", line 1422, in connect
self.sock = self._context.wrap_socket(self.sock,
File "/usr/local/lib/python3.9/ssl.py", line 500, in wrap_socket
return self.sslsocket_class._create(
File "/usr/local/lib/python3.9/ssl.py", line 1040, in _create
self.do_handshake()
File "/usr/local/lib/python3.9/ssl.py", line 1309, in do_handshake
self._sslobj.do_handshake()
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_6]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_7]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 77, in _worker
work_item.run()
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/src/homeassistant/homeassistant/components/onkyo/media_player.py", line 512, in update
status = self.command(f"zone{self._zone}.power=query")
File "/usr/src/homeassistant/homeassistant/components/onkyo/media_player.py", line 282, in command
result = self._receiver.command(command)
File "/usr/local/lib/python3.9/site-packages/eiscp/core.py", line 480, in command
response = self.raw(iscp_message)
File "/usr/local/lib/python3.9/site-packages/eiscp/core.py", line 470, in raw
return filter_for_message(self.get, iscp_message)
File "/usr/local/lib/python3.9/site-packages/eiscp/core.py", line 260, in filter_for_message
candidate = getter_func(0.05)
File "/usr/local/lib/python3.9/site-packages/eiscp/core.py", line 437, in get
ready = select.select([self.command_socket], [], [], timeout or 0)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_8]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Recorder]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 585, in run
if hass_started.result() is shutdown_task:
File "/usr/local/lib/python3.9/concurrent/futures/_base.py", line 440, in result
self._condition.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 312, in wait
waiter.acquire()
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1512, in _monitor
record = self.dequeue(True)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [FirmwareManage]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 36, in _start_loop
self.loop()
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 56, in loop
self._loop_root.stopped.wait(self._loop_delay)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [CoAP]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/coap.py", line 104, in _loop
data_tmp, addr = self._socket.recvfrom(1024)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_http._tcp-254]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-8]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3452, in _thread_main
self.loop_forever(retry_first_connection=True)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 1798, in loop_forever
self._reconnect_wait()
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3473, in _reconnect_wait
time.sleep(min(remaining, 1))
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Update loop]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/__init__.py", line 337, in _update_loop
self.stopped.wait(5)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_googlecast._tcp-257]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-10]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 252, in run
self._poll_hosts()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 268, in _poll_hosts
device_status = get_device_status(host, timeout=4, context=self._context)
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 109, in get_device_status
status = _get_status(
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 88, in _get_status
with urllib.request.urlopen(req, timeout=timeout, context=context) as response:
File "/usr/local/lib/python3.9/urllib/request.py", line 214, in urlopen
return opener.open(url, data, timeout)
File "/usr/local/lib/python3.9/urllib/request.py", line 517, in open
response = self._open(req, data)
File "/usr/local/lib/python3.9/urllib/request.py", line 534, in _open
result = self._call_chain(self.handle_open, protocol, protocol +
File "/usr/local/lib/python3.9/urllib/request.py", line 494, in _call_chain
result = func(*args)
File "/usr/local/lib/python3.9/urllib/request.py", line 1389, in https_open
return self.do_open(http.client.HTTPSConnection, req,
File "/usr/local/lib/python3.9/urllib/request.py", line 1346, in do_open
h.request(req.get_method(), req.selector, req.data, headers,
File "/usr/local/lib/python3.9/http/client.py", line 1253, in request
self._send_request(method, url, body, headers, encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1299, in _send_request
self.endheaders(body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1248, in endheaders
self._send_output(message_body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1008, in _send_output
self.send(msg)
File "/usr/local/lib/python3.9/http/client.py", line 948, in send
self.connect()
File "/usr/local/lib/python3.9/http/client.py", line 1415, in connect
super().connect()
File "/usr/local/lib/python3.9/http/client.py", line 919, in connect
self.sock = self._create_connection(
File "/usr/local/lib/python3.9/socket.py", line 831, in create_connection
sock.connect(sa)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-12]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-13]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:00 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-35]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97406.99361807166 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf1339658>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97407.18188739374 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeadb3268>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97407.02614012365 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe6105d18>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97407.47152325202 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa83da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97407.3337860077 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf0bde190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97407.11253298621 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe580f7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97407.581891353 _weakref_handle((<weakref at 0xf1e42488; to 'TCPConnector' at 0xf1e3d568>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97411 _weakref_handle((<weakref at 0xed4f2258; to 'TCPConnector' at 0xed87ed30>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97409.37255068177 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebff1d60>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97408.058176662 _weakref_handle((<weakref at 0xed4f2258; to 'TCPConnector' at 0xed87ed30>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97416.453225381 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xe613f718>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97413 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97412.42376001968 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe6227190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97412.45872931817 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xedbbe778>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97415.81536445889 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebe33e38>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97410.89716319596 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec722418>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97419.221706908 timeout._cancel_task()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97536.4043996615 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method Store._async_callback_delayed_write of <homeassistant.helpers.storage.Store object at 0xf0beca00>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97413.78575961 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xe6e9f220>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97423.0136075278 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf03c49b8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97416.25255366284 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_enable_report_state.<locals>.initial_report at 0xf0fc8100>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98267.09049719007 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecc01958>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97424.34376427965 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebff1850>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.1916175259 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97412.08772625489 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec2404a8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97411.09049059729 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xeaa6cfa0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.010617687 _ZoneTaskContext._on_timeout()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97963.0904953097 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.components.openweathermap.weather_update_coordinator.WeatherUpdateCoordinator object at 0xf03c5c70>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100128.019142782 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xed89f148>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98056.09049102802 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xec23a7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100966.0412166068 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xee0ea538>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.218401321 RequestHandler._process_keepalive()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97426.71973531459 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebd9d340>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97426.0904889427 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecf53298>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97670.39145463354 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe70681d8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97657 TimeoutHandle.__call__()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97838.0459078689 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_sun_position of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.010365784 _ZoneTaskContext._on_timeout()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97417 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97447.529113176 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xed2fd298>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97425.57600125363 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebefbbf8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.217017843 RequestHandler._process_keepalive()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97411.94585890695 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa831d8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97954.89634295396 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf2b1de80>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97456.09048825891 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Time: 23:49>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97423.01342642031 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeccfdb20>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97687.518578939 _GlobalTaskContext._on_timeout()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.20557906097 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeab256a0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97425.11017588568 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xedbeb808>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.20570421088 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeab25898>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.873732209 RequestHandler._process_keepalive()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97459.221573503 timeout._cancel_task()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97424.92408920491 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup_entry.<locals>._discovery at 0xecba1190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100496.090536255 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xf0bdcb38>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100965.83481304215 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xee0ea7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97430.32883243084 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.871662391 RequestHandler._process_keepalive()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100656.60189339606 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup.<locals>.update_info_data at 0xf0c24da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97419 _weakref_handle((<weakref at 0xf1e42488; to 'TCPConnector' at 0xf1e3d568>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97668 TimeoutHandle.__call__()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=103240.09048772718 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_events of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98287.0904894966 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xe6f01970>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.21534137 RequestHandler._process_keepalive()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97456.09048967769 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Date & Time (ISO): 2021-07-18T23:49:00>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.21986926 RequestHandler._process_keepalive()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97663.40536260702 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xecd43460>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97429.9596211757 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=183764.0904906536 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xf01f1460>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97423.01370653546 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec9b3da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97415.83733403537 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebd7c388>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97416.384038707 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xebfd7778>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98267.09049340694 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecc01ad8>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:10 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97462 WebSocketResponse._send_heartbeat()>
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1512, in _monitor
record = self.dequeue(True)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_0]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_3]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 77, in _worker
work_item.run()
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/src/homeassistant/homeassistant/components/fritz/common.py", line 207, in scan_devices
for known_host in self._update_info():
File "/usr/src/homeassistant/homeassistant/components/fritz/common.py", line 192, in _update_info
return self.fritz_hosts.get_hosts_info()
File "/usr/local/lib/python3.9/site-packages/fritzconnection/lib/fritzhosts.py", line 91, in get_hosts_info
host = self.get_generic_host_entry(index)
File "/usr/local/lib/python3.9/site-packages/fritzconnection/lib/fritzhosts.py", line 46, in get_generic_host_entry
return self._action("GetGenericHostEntry", NewIndex=index)
File "/usr/local/lib/python3.9/site-packages/fritzconnection/lib/fritzhosts.py", line 32, in _action
return self.fc.call_action(SERVICE, actionname, arguments=arguments, **kwargs)
File "/usr/local/lib/python3.9/site-packages/fritzconnection/core/fritzconnection.py", line 227, in call_action
return self.soaper.execute(service, action_name, arguments)
File "/usr/local/lib/python3.9/site-packages/fritzconnection/core/soaper.py", line 235, in execute
with self.session.post(
File "/usr/local/lib/python3.9/site-packages/requests/sessions.py", line 590, in post
return self.request('POST', url, data=data, json=json, **kwargs)
File "/usr/local/lib/python3.9/site-packages/requests/sessions.py", line 542, in request
resp = self.send(prep, **send_kwargs)
File "/usr/local/lib/python3.9/site-packages/requests/sessions.py", line 655, in send
r = adapter.send(request, **kwargs)
File "/usr/local/lib/python3.9/site-packages/requests/adapters.py", line 439, in send
resp = conn.urlopen(
File "/usr/local/lib/python3.9/site-packages/urllib3/connectionpool.py", line 699, in urlopen
httplib_response = self._make_request(
File "/usr/local/lib/python3.9/site-packages/urllib3/connectionpool.py", line 440, in _make_request
httplib_response = conn.getresponse()
File "/usr/local/lib/python3.9/http/client.py", line 1345, in getresponse
response.begin()
File "/usr/local/lib/python3.9/http/client.py", line 307, in begin
version, status, reason = self._read_status()
File "/usr/local/lib/python3.9/http/client.py", line 268, in _read_status
line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
File "/usr/local/lib/python3.9/socket.py", line 704, in readinto
return self._sock.recv_into(b)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_4]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_5]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_6]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_7]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_8]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Recorder]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 585, in run
if hass_started.result() is shutdown_task:
File "/usr/local/lib/python3.9/concurrent/futures/_base.py", line 440, in result
self._condition.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 312, in wait
waiter.acquire()
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1517, in _monitor
self.handle(record)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [FirmwareManage]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 36, in _start_loop
self.loop()
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 56, in loop
self._loop_root.stopped.wait(self._loop_delay)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [CoAP]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/coap.py", line 104, in _loop
data_tmp, addr = self._socket.recvfrom(1024)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_http._tcp-254]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-8]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3452, in _thread_main
self.loop_forever(retry_first_connection=True)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 1798, in loop_forever
self._reconnect_wait()
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3473, in _reconnect_wait
time.sleep(min(remaining, 1))
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Update loop]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/__init__.py", line 337, in _update_loop
self.stopped.wait(5)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_googlecast._tcp-257]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-10]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 252, in run
self._poll_hosts()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 268, in _poll_hosts
device_status = get_device_status(host, timeout=4, context=self._context)
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 109, in get_device_status
status = _get_status(
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 88, in _get_status
with urllib.request.urlopen(req, timeout=timeout, context=context) as response:
File "/usr/local/lib/python3.9/urllib/request.py", line 214, in urlopen
return opener.open(url, data, timeout)
File "/usr/local/lib/python3.9/urllib/request.py", line 517, in open
response = self._open(req, data)
File "/usr/local/lib/python3.9/urllib/request.py", line 534, in _open
result = self._call_chain(self.handle_open, protocol, protocol +
File "/usr/local/lib/python3.9/urllib/request.py", line 494, in _call_chain
result = func(*args)
File "/usr/local/lib/python3.9/urllib/request.py", line 1389, in https_open
return self.do_open(http.client.HTTPSConnection, req,
File "/usr/local/lib/python3.9/urllib/request.py", line 1346, in do_open
h.request(req.get_method(), req.selector, req.data, headers,
File "/usr/local/lib/python3.9/http/client.py", line 1253, in request
self._send_request(method, url, body, headers, encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1299, in _send_request
self.endheaders(body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1248, in endheaders
self._send_output(message_body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1008, in _send_output
self.send(msg)
File "/usr/local/lib/python3.9/http/client.py", line 948, in send
self.connect()
File "/usr/local/lib/python3.9/http/client.py", line 1422, in connect
self.sock = self._context.wrap_socket(self.sock,
File "/usr/local/lib/python3.9/ssl.py", line 500, in wrap_socket
return self.sslsocket_class._create(
File "/usr/local/lib/python3.9/ssl.py", line 1040, in _create
self.do_handshake()
File "/usr/local/lib/python3.9/ssl.py", line 1309, in do_handshake
self._sslobj.do_handshake()
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-12]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-13]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-35]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:16 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-36]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97420.071752537 _weakref_handle((<weakref at 0xed4f2258; to 'TCPConnector' at 0xed87ed30>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.09048969549 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xeaa6cfa0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97420.90043063044 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec722418>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.94659163944 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa831d8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97422.33630581603 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf0bde190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.010365784 _ZoneTaskContext._on_timeout()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.010617687 _ZoneTaskContext._on_timeout()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97423.0136075278 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf03c49b8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97423.01342642031 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeccfdb20>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.18353473456 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeadb3268>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97424.34376427965 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebff1850>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97425.57600125363 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebefbbf8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97423.78662631 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xe72387d8>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97421.596597368 _weakref_handle((<weakref at 0xf1e42488; to 'TCPConnector' at 0xf1e3d568>, '_cleanup_closed')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97425.81614352415 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebe33e38>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97424.92408920491 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup_entry.<locals>._discovery at 0xecba1190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97426.71973531459 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebd9d340>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97425.11017588568 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xedbeb808>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97426.99381718536 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf1339658>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97424.37539591854 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebff1d60>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97423.01370653546 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec9b3da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.871662391 RequestHandler._process_keepalive()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97425.83801991632 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xebd7c388>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97430.32880453716 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97456.09048967769 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Date & Time (ISO): 2021-07-18T23:49:00>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.20557906097 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeab256a0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.217017843 RequestHandler._process_keepalive()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97426.0904889427 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecf53298>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.20570421088 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeab25898>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427 _weakref_handle((<weakref at 0xed4f2258; to 'TCPConnector' at 0xed87ed30>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.19161799992 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97429.857311008 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xeaa0ae98>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98287.0904894966 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xe6f01970>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.218401321 RequestHandler._process_keepalive()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100966.0412166068 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xee0ea538>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=183764.0904906536 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xf01f1460>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98267.09049719007 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecc01958>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97668 TimeoutHandle.__call__()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.21534137 RequestHandler._process_keepalive()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98056.09049102802 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_utc_time_change.<locals>.pattern_time_change_listener at 0xec23a7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100965.83481304215 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function _get_services.<locals>.clear_services at 0xee0ea7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97442.0883710682 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xec2404a8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97717 TimeoutHandle.__call__()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97443.21986926 RequestHandler._process_keepalive()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97442.45900832028 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xedbbe778>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97663.40536260702 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xecd43460>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97670.39145463354 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe70681d8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97954.89634295396 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xf2b1de80>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97473 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97473 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97442.42464996573 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe6227190>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100496.090536255 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xf0bdcb38>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97657 TimeoutHandle.__call__()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.1129734306 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe580f7c0>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.873732209 RequestHandler._process_keepalive()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97838.0459078689 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_sun_position of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100656.60189339606 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <function async_setup.<locals>.update_info_data at 0xf0c24da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97437.02703342887 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xe6105d18>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=103240.09048772718 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method Sun.update_events of <Entity Sun: below_horizon>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97447.529113176 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xed2fd298>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97536.4043996615 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method Store._async_callback_delayed_write of <homeassistant.helpers.storage.Store object at 0xf0beca00>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=98267.09049340694 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.helpers.update_coordinator.DataUpdateCoordinator object at 0xecc01ad8>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97427.47254647086 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <function async_track_time_interval.<locals>.interval_listener at 0xeaa83da8>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97433 ClientWebSocketResponse._send_heartbeat()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97963.0904953097 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Coroutinefunction <bound method DataUpdateCoordinator._handle_refresh_interval of <homeassistant.components.openweathermap.weather_update_coordinator.WeatherUpdateCoordinator object at 0xf03c5c70>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97456.09048825891 async_track_point_in_utc_time.<locals>.run_action(<Job HassJobType.Callback <bound method TimeDateSensor.point_in_time_listener of <Entity Time: 23:49>>>) at /usr/src/homeassistant/homeassistant/helpers/event.py:1176>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=100128.019142782 _set_result_unless_cancelled(<Future pending cb=[<TaskWakeupMethWrapper object at 0xed89f148>()]>, None) at /usr/local/lib/python3.9/asyncio/futures.py:308>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97435 _weakref_handle((<weakref at 0xf1e42488; to 'TCPConnector' at 0xf1e3d568>, '_cleanup')) at /usr/local/lib/python3.9/site-packages/aiohttp/helpers.py:535>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97509.336068503 timeout._cancel_task()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97469.331740573 timeout._cancel_task()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97429.375873024 _release_waiter(<Future pending cb=[<TaskWakeupMethWrapper object at 0xeaa0a940>()]>) at /usr/local/lib/python3.9/asyncio/tasks.py:416>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97429.95962127375 _ServiceBrowserBase._async_send_ready_queries_schedule_next()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97687.518578939 _GlobalTaskContext._on_timeout()>
2021-07-18 23:49:23 CRITICAL (MainThread) [homeassistant.components.profiler] Scheduled: <TimerHandle when=97475 WebSocketResponse._send_heartbeat()>
2021-07-18 23:49:24 ERROR (MainThread) [homeassistant.components.fan] Setup of platform philips_airpurifier_coap is taking longer than 60 seconds. Startup will proceed without waiting any longer.
2021-07-18 23:49:24 ERROR (MainThread) [homeassistant.components.fan] Setup of platform philips_airpurifier_coap is taking longer than 60 seconds. Startup will proceed without waiting any longer.
2021-07-18 23:49:25 WARNING (MainThread) [homeassistant.components.homeassistant.triggers.numeric_state] Error initializing 'Notify mobile for Schlafzimmer Air Purifier maintenance' trigger: In 'numeric_state' condition: attribute 'filter_pre_remaining_raw' (of entity fan.air_ac2729) does not exist
2021-07-18 23:49:25 WARNING (MainThread) [homeassistant.components.homeassistant.triggers.numeric_state] Error initializing 'Notify mobile for Kinderzimmer Air Purifier maintenance' trigger: In 'numeric_state' condition: attribute 'filter_pre_remaining_raw' (of entity fan.air_ac2889) does not exist
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1512, in _monitor
record = self.dequeue(True)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_0]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_1]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_3]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_4]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_5]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_6]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_7]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 75, in _worker
work_item = work_queue.get(block=True)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [SyncWorker_8]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 77, in _worker
work_item.run()
File "/usr/local/lib/python3.9/concurrent/futures/thread.py", line 52, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/src/homeassistant/homeassistant/components/speedtestdotnet/__init__.py", line 169, in update_data
self.api.get_best_server()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 1473, in get_best_server
h.request("GET", path, headers=headers)
File "/usr/local/lib/python3.9/http/client.py", line 1253, in request
self._send_request(method, url, body, headers, encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1299, in _send_request
self.endheaders(body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1248, in endheaders
self._send_output(message_body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1008, in _send_output
self.send(msg)
File "/usr/local/lib/python3.9/http/client.py", line 948, in send
self.connect()
File "/usr/local/lib/python3.9/site-packages/speedtest.py", line 426, in connect
self.sock = socket.create_connection(
File "/usr/local/lib/python3.9/socket.py", line 831, in create_connection
sock.connect(sa)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Recorder]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 614, in run
self._run_event_loop()
File "/usr/src/homeassistant/homeassistant/components/recorder/__init__.py", line 621, in _run_event_loop
while event := self.queue.get():
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-2]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1512, in _monitor
record = self.dequeue(True)
File "/usr/local/lib/python3.9/logging/handlers.py", line 1461, in dequeue
return self.queue.get(block)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [FirmwareManage]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 36, in _start_loop
self.loop()
File "/usr/local/lib/python3.9/site-packages/pyShelly/loop.py", line 56, in loop
self._loop_root.stopped.wait(self._loop_delay)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [CoAP]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/coap.py", line 104, in _loop
data_tmp, addr = self._socket.recvfrom(1024)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_http._tcp-254]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-8]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3452, in _thread_main
self.loop_forever(retry_first_connection=True)
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 1798, in loop_forever
self._reconnect_wait()
File "/usr/local/lib/python3.9/site-packages/paho/mqtt/client.py", line 3473, in _reconnect_wait
time.sleep(min(remaining, 1))
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Update loop]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/pyShelly/__init__.py", line 337, in _update_loop
self.stopped.wait(5)
File "/usr/local/lib/python3.9/threading.py", line 574, in wait
signaled = self._cond.wait(timeout)
File "/usr/local/lib/python3.9/threading.py", line 316, in wait
gotit = waiter.acquire(True, timeout)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [zeroconf-ServiceBrowser-_googlecast._tcp-257]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/zeroconf/_services/browser.py", line 533, in run
event = self.queue.get()
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-10]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 252, in run
self._poll_hosts()
File "/usr/local/lib/python3.9/site-packages/pychromecast/discovery.py", line 268, in _poll_hosts
device_status = get_device_status(host, timeout=4, context=self._context)
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 109, in get_device_status
status = _get_status(
File "/usr/local/lib/python3.9/site-packages/pychromecast/dial.py", line 88, in _get_status
with urllib.request.urlopen(req, timeout=timeout, context=context) as response:
File "/usr/local/lib/python3.9/urllib/request.py", line 214, in urlopen
return opener.open(url, data, timeout)
File "/usr/local/lib/python3.9/urllib/request.py", line 517, in open
response = self._open(req, data)
File "/usr/local/lib/python3.9/urllib/request.py", line 534, in _open
result = self._call_chain(self.handle_open, protocol, protocol +
File "/usr/local/lib/python3.9/urllib/request.py", line 494, in _call_chain
result = func(*args)
File "/usr/local/lib/python3.9/urllib/request.py", line 1389, in https_open
return self.do_open(http.client.HTTPSConnection, req,
File "/usr/local/lib/python3.9/urllib/request.py", line 1346, in do_open
h.request(req.get_method(), req.selector, req.data, headers,
File "/usr/local/lib/python3.9/http/client.py", line 1253, in request
self._send_request(method, url, body, headers, encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1299, in _send_request
self.endheaders(body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1248, in endheaders
self._send_output(message_body, encode_chunked=encode_chunked)
File "/usr/local/lib/python3.9/http/client.py", line 1008, in _send_output
self.send(msg)
File "/usr/local/lib/python3.9/http/client.py", line 948, in send
self.connect()
File "/usr/local/lib/python3.9/http/client.py", line 1415, in connect
super().connect()
File "/usr/local/lib/python3.9/http/client.py", line 919, in connect
self.sock = self._create_connection(
File "/usr/local/lib/python3.9/socket.py", line 831, in create_connection
sock.connect(sa)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-12]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-13]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-35]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [Thread-36]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 527, in run
if self.run_once(timeout=POLL_TIME_BLOCKING) == 1:
File "/usr/local/lib/python3.9/site-packages/pychromecast/socket_client.py", line 558, in run_once
can_read, _, _ = select.select(rlist, [], [], timeout)
2021-07-18 23:49:30 CRITICAL (MainThread) [homeassistant.components.profiler] Thread [DHCPWatcher]: File "/usr/local/lib/python3.9/threading.py", line 912, in _bootstrap
self._bootstrap_inner()
File "/usr/local/lib/python3.9/threading.py", line 954, in _bootstrap_inner
self.run()
File "/usr/local/lib/python3.9/threading.py", line 892, in run
self._target(*self._args, **self._kwargs)
File "/usr/local/lib/python3.9/site-packages/scapy/sendrecv.py", line 1175, in _run
sockets = select_func(list(sniff_sockets.keys()), remain)
File "/usr/local/lib/python3.9/site-packages/scapy/supersocket.py", line 259, in select
inp, _, _ = select(sockets, [], [], remain)
bdraco commented 3 years ago

Thanks. Everything looks good in those. Please try to get one the next time it startup stalls