"RequestTimeout" and "Failed to connect to service HostServiceInfo" Bug

[elibroftw]

Original Nest Hub Max issue has been resolved, but still encountering the request bug. Let's see if a zeroconf upgrade will fix the issue

Latest Update

music_caster (scenario 3).log
music_caster (scenario 4).log

I improved track position syncing which fixed one bug where the device would replay instead of advancing to the next track, however a second issue arose. Sometimes the device will restart and before it does the hint is that "the image flickers." A third issue is that sometimes the device will play the audio but will still be on the home screen instead of showing the audio metadata.

Three different "playing" states were observed

1a. track 1 MC shows progress, hear on Nest Max and NM shows track info
1b. track 3 MC shows progress, MC auto reconnected to NM, hear on NM and NM shows track info
2. track 2 MC shows progress, NM quiet, NM returned to home screen

The logs say that a change device occurred but I don't know the device UUID or name that was already connected nor the friendly name of the new device we are connecting to.

3. track 2 MC shows progress, hear on NM Max but NM does NOT show track info (shows home screen)

The logs also show home-assistant-libs/pychromecast#1030 but I suspect this is a side effect of the nest device restarting

---

Got an email about this

I suspect the problem started when Google updated the OS on the Nest Hub max to the “Fushia OS”, but this is just my guess:

https://arstechnica.com/gadgets/2022/08/googles-fuchsia-os-lands-on-its-second-device-the-nest-hub-max/

music_caster.log

Also attached is a screenshot of Music Caster while the Nest Max was “stuck” at the end of the first song.

Hope this helps! Please let me know how I can help with troubleshooting further.

Here is a video. This was taken 30 seconds before the end of the first song. When the song finished playing, a black screen with a blue “cast” icon appeared in the middle of the Nest Max, stuck there for 3-5 minutes. After that, the Nest Max returned to its home screen.

https://youtu.be/_yL7pVJ019I

[elibroftw]

Similar Results: https://www.google.com/search?q=%22Failed+to+connect+to+service+HostServiceInfo%22

RequestTimeout

2025-03-08 10:32:02,459 INFO (2186) play(): track_end = 11754.11, track_start = 11393.11, track_length = 361.00
2025-03-08 10:32:02,459 INFO (1660) after_play(): autoplay=True, switching_device=False
2025-03-08 10:32:02,477 ERROR (416) initialize_connection(): [(169.101.200.157):8009] Failed to connect to service HostServiceInfo(host='169.101.200.157', port=8009), retrying in 5.0s
2025-03-08 10:32:02,477 ERROR (416) initialize_connection(): [(169.101.200.157):8009] Failed to connect to service HostServiceInfo(host='169.101.200.157', port=8009), retrying in 5.0s
2025-03-08 10:34:03,469 INFO (550) update_volume(): set to 24.0 from volume_slider
2025-03-08 10:34:03,469 INFO (250) set_volume(): Receiver:setting volume to 0.24
2025-03-08 10:38:04,017 INFO (4372) <module>(): calling next track because monotonic time is greater than track_end
2025-03-08 10:38:04,018 INFO (2600) next_track(): from_timeout=True
2025-03-08 10:38:04,018 INFO (2602) next_track(): current track = 01.MP3
2025-03-08 10:38:04,018 INFO (2630) next_track(): will move the next 1 tracks from music_queue and then next_queue into the done queue
2025-03-08 10:38:04,018 INFO (2115) play(): 11.MP3 @0, autoplay=True, switching_device=False
2025-03-08 10:38:04,052 INFO (2136) play(): calling cast.wait on device Kitchen display / 3389afaa-11b8-023c-fb42-1531558244a3
2025-03-08 10:38:04,066 ERROR (416) initialize_connection(): [(169.101.200.157):8009] Failed to connect to service HostServiceInfo(host='169.101.200.157', port=8009), retrying in 5.0s
2025-03-08 10:38:04,066 ERROR (416) initialize_connection(): [(169.101.200.157):8009] Failed to connect to service HostServiceInfo(host='169.101.200.157', port=8009), retrying in 5.0s
2025-03-08 10:38:09,054 ERROR (2174) play(): play failed to cast RequestTimeout('Execution of wait timed out after 5 s.')
2025-03-08 10:38:09,054 INFO (1301) cast_try_reconnect(): stop discovery
2025-03-08 10:38:09,060 INFO (2115) play(): 11.MP3 @0, autoplay=True, switching_device=False
2025-03-08 10:38:09,108 INFO (2136) play(): calling cast.wait on device Kitchen display / 3389afaa-11b8-023c-fb42-1531558244a3
2025-03-08 10:38:09,240 ERROR (416) initialize_connection(): [(169.101.200.157):8009] Failed to connect to service HostServiceInfo(host='169.101.200.157', port=8009), retrying in 5.0s
2025-03-08 10:38:09,240 ERROR (416) initialize_connection(): [(169.101.200.157):8009] Failed to connect to service HostServiceInfo(host='169.101.200.157', port=8009), retrying in 5.0s
2025-03-08 10:38:14,108 ERROR (2174) play(): play failed to cast RequestTimeout('Execution of wait timed out after 5 s.')
2025-03-08 10:38:14,108 ERROR (394) log_translation_error(): failed to translate `ERROR` to 
Traceback (most recent call last):
  File "music_caster.py", line 2137, in play
  File "pychromecast\__init__.py", line 505, in wait
pychromecast.error.RequestTimeout: Execution of wait timed out after 5 s.

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "music_caster.py", line 2137, in play
  File "pychromecast\__init__.py", line 505, in wait
pychromecast.error.RequestTimeout: Execution of wait timed out after 5 s.

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "utils.py", line 404, in get_translation
KeyError: 23
2025-03-08 10:38:14,109 ERROR (394) log_translation_error(): failed to translate `Could not connect to cast device` to 
Traceback (most recent call last):
  File "music_caster.py", line 2137, in play
  File "pychromecast\__init__.py", line 505, in wait
pychromecast.error.RequestTimeout: Execution of wait timed out after 5 s.

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "music_caster.py", line 2137, in play
  File "pychromecast\__init__.py", line 505, in wait
pychromecast.error.RequestTimeout: Execution of wait timed out after 5 s.

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "utils.py", line 404, in get_translation
KeyError: 13
2025-03-08 10:38:14,110 INFO (1413) change_device(): change_device(local)

[elibroftw]

Related to home-assistant/core#122744