latest update failed #1344

userMak · 2023-07-07T08:16:09Z

userMak
Jul 7, 2023

Just installed the latest beta .40 version and the addon crashed. It didn't start at all. I tried to restore from backup to .39 which was working but I can't. The addon doesn't start. I get the following after the restore. I don't have other logs since the addon is not available.
Any ideas how I can restore or make it work?

Logger: homeassistant.components.hassio
Source: components/hassio/websocket_api.py:125
Integration: Home Assistant Supervisor (documentation, issues)
First occurred: 11:07:48 (4 occurrences)
Last logged: 11:12:19

Failed to to call /addons/d5369777_music_assistant_beta/start - Image ghcr.io/music-assistant/server:2.0.0b39 does not exist for addon_d5369777_music_assistant_beta
Failed to to call /store -
Failed to to call /host/info -

agdamsbo · 2023-07-07T08:22:44Z

agdamsbo
Jul 7, 2023

I believe I have a similar problem. I'm om the latest HA OS 2023.7.1with MA b40. After installing I get 502: Bad gateway when opening the add-on. I have a log though, which looks OK?

2023-07-07 10:18:31 INFO music_assistant -- Starting Music Assistant Server (d68a39c7666d44a997d379fdbbaa14ce) 
2023-07-07 10:18:32 INFO music_assistant.webserver -- Starting server on 192.168.1.23:8095 
2023-07-07 10:18:32 INFO music_assistant.streams -- Detected ffmpeg version 5.1.3 with libsoxr support 
2023-07-07 10:18:32 INFO music_assistant.streams -- Starting server on 192.168.1.23:8096 
2023-07-07 10:18:33 INFO music_assistant -- Loaded music provider URL 2023-07-07 10:18:33 INFO music_assistant -- Loaded metadata provider TheAudioDB Metadata provider 
2023-07-07 10:18:33 INFO music_assistant -- Loaded metadata provider MusicBrainz Metadata provider 
2023-07-07 10:18:33 INFO music_assistant -- Loaded metadata provider fanart.tv Metadata provider 
2023-07-07 10:18:33 WARNING asyncio -- Executing <Task pending name='Task-20' coro=<MusicAssistant._load_providers.<locals>.load_provider() running at /usr/local/lib/python3.11/site-packages/music_assistant/server/server.py:443> wait_for=<Future pending cb=[Task.task_wakeup()] created at /usr/local/lib/python3.11/asyncio/base_events.py:427> cb=[TaskGroup._on_task_done()] created at /usr/local/lib/python3.11/asyncio/taskgroups.py:163> took 0.126 seconds 
2023-07-07 10:18:33 INFO music_assistant.providers.slimproto -- Started SLIMProto server on port 3483 
2023-07-07 10:18:33 INFO music_assistant.providers.slimproto.cli -- Registering jsonrpc endpoints on the webserver 
2023-07-07 10:18:33 INFO music_assistant.providers.slimproto.cli -- Starting (telnet) CLI on port 9090 
2023-07-07 10:18:33 INFO music_assistant -- Loaded player provider Slimproto 
2023-07-07 10:18:34 INFO music_assistant -- Loaded player provider Airplay 
2023-07-07 10:18:34 INFO music_assistant.players -- Player registered: aa:aa:6b:c9:a7:89/Stuelyd 
2023-07-07 10:18:34 INFO music_assistant.players -- Player registered: aa:aa:1b:5a:bf:c4/Køkkenlyd authorized 
2023-07-07 10:18:42 WARNING asyncio -- Executing <Task pending name='Task-20' coro=<MusicAssistant._load_providers.<locals>.load_provider() running at /usr/local/lib/python3.11/site-packages/music_assistant/server/server.py:443> wait_for=<Future pending cb=[shield.<locals>._outer_done_callback() at /usr/local/lib/python3.11/asyncio/tasks.py:898, Task.task_wakeup()] created at /usr/local/lib/python3.11/asyncio/base_events.py:427> cb=[TaskGroup._on_task_done()] created at /usr/local/lib/python3.11/asyncio/taskgroups.py:163> took 0.130 seconds authorized 
2023-07-07 10:18:44 INFO music_assistant.providers.spotify -- Successfully logged in to Spotify as 31c4y3bhd5b46v2hdfbmaaq6qeuq 
2023-07-07 10:18:44 INFO music_assistant -- Loaded music provider Spotify

0 replies

qtetrode · 2023-07-07T09:40:32Z

qtetrode
Jul 7, 2023

I believe have the same problem too. After updating to version 40 I also receive a 502 Bad gateway error. I reinstalled the addon, but that had no effect. I do get a notification of a new discovery of Music Assitant, but when I want to submit, I get the following error:

Discovered Music Assistant Server
Do you want to add the Music Assistant Server 192.168.1.251 to Home Assistant?

Unknown error occurred

Debug log shows no issues.

However, the log of HA Core shows the following:

Logger: homeassistant.config_entries
Source: custom_components/mass/__init__.py:58
Integration: Music Assistant (BETA) ([documentation](https://github.com/music-assistant/hass-music-assistant), [issues](https://github.com/music-assistant/hass-music-assistant/issues))
First occurred: 10:42:02 (1 occurrences)
Last logged: 10:42:02

Error setting up entry Music Assistant for mass
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/config_entries.py", line 390, in async_setup
    result = await component.async_setup_entry(hass, self)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/config/custom_components/mass/__init__.py", line 58, in async_setup_entry
    mass = MusicAssistantClient(entry.data[CONF_URL], http_session)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/music_assistant/client/client.py", line 48, in __init__
    self.connection = WebsocketsConnection(server_url, aiohttp_session)
                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/music_assistant/client/connection.py", line 38, in __init__
    self.ws_server_url = get_websocket_url(server_url)
                         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/music_assistant/client/connection.py", line 26, in get_websocket_url
    raise RuntimeError(f"{url} is not a valid url")
RuntimeError: 192.168.1.251 is not a valid url

I think this might be a clue

0 replies

OzGav · 2023-07-07T10:47:01Z

OzGav
Jul 7, 2023
Maintainer

This has been reported on discord. To help with fault finding How did you setup the integration and add-on ? Did you setup from HACS and that install created the add-on or did you have the add-on already present and installed the integration later ?

2 replies

agdamsbo Jul 7, 2023

I am really unsure how I did it. Do I have any way to tell from the system? Also, I restored my backup of b39, but I still get the same 502 error initially. Then tried to reload the integration and restart the addon with logs set to debug, and it comes back up.

2023-07-07 13:11:12 DEBUG music_assistant.providers.slimproto  -- Socket client connected: ('127.0.0.1', 35162)
2023-07-07 13:11:12 DEBUG aioslimproto.client  -- HELO received: b'\x0c\x00\xaa\xaa\x1bZ\xbf\xc4\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=44100,pcm'
2023-07-07 13:11:12 DEBUG aioslimproto.client  -- Player connected: aa:aa:1b:5a:bf:c4
2023-07-07 13:11:12 DEBUG music_assistant.providers.slimproto  -- Socket client connected: ('127.0.0.1', 35172)
2023-07-07 13:11:12 INFO  music_assistant.players  -- Player registered: aa:aa:1b:5a:bf:c4/Køkkenlyd
2023-07-07 13:11:12 DEBUG music_assistant.event  -- player_added aa:aa:1b:5a:bf:c4
2023-07-07 13:11:12 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:12 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:12 DEBUG aioslimproto.client  -- HELO received: b'\x0c\x00\xaa\xaak\xc9\xa7\x89\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=44100,pcm'
2023-07-07 13:11:12 DEBUG aioslimproto.client  -- Player connected: aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 INFO  music_assistant.players  -- Player registered: aa:aa:6b:c9:a7:89/Stuelyd
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_added aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 DEBUG music_assistant.event  -- queue_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:13 DEBUG music_assistant.event  -- queue_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:13 DEBUG music_assistant.event  -- queue_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 DEBUG music_assistant.event  -- queue_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:13 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:16 DEBUG music_assistant.providers.spotify  -- Processing GET/me took 3.78 seconds
2023-07-07 13:11:16 INFO  music_assistant.providers.spotify  -- Successfully logged in to Spotify as 31c4y3bhd5b46v2hdfbmaaq6qeuq
2023-07-07 13:11:16 INFO  music_assistant  -- Loaded music provider Spotify
2023-07-07 13:11:16 DEBUG music_assistant.event  -- providers_updated 
2023-07-07 13:11:16 DEBUG music_assistant.event  -- sync_tasks_updated 
2023-07-07 13:11:16 DEBUG music_assistant.providers.spotify  -- Start sync of artist items.
2023-07-07 13:11:16 DEBUG music_assistant  -- Starting Zeroconf broadcast...
2023-07-07 13:11:26 WARNING asyncio  -- Executing <Task finished name='Task-1' coro=<run.<locals>.new_coro() done, defined at /usr/local/lib/python3.11/site-packages/aiorun.py:227> exception=EventLoopBlocked()> took 10.677 seconds
2023-07-07 13:11:26 ERROR asyncio  -- Task exception was never retrieved
future: <Task finished name='Task-1' coro=<run.<locals>.new_coro() done, defined at /usr/local/lib/python3.11/site-packages/aiorun.py:227> exception=EventLoopBlocked()>
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zeroconf/_utils/asyncio.py", line 86, in run_coro_with_timeout
    return asyncio.run_coroutine_threadsafe(aw, loop).result(
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/concurrent/futures/_base.py", line 458, in result
    raise TimeoutError()
TimeoutError
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/aiorun.py", line 237, in new_coro
    await coro
  File "/usr/local/lib/python3.11/site-packages/music_assistant/__main__.py", line 106, in start_mass
    await mass.start()
  File "/usr/local/lib/python3.11/site-packages/music_assistant/server/server.py", line 123, in start
    self._setup_discovery()
  File "/usr/local/lib/python3.11/site-packages/music_assistant/server/server.py", line 512, in _setup_discovery
    self.zeroconf.register_service(info)
  File "/usr/local/lib/python3.11/site-packages/zeroconf/_core.py", line 623, in register_service
    run_coro_with_timeout(
  File "/usr/local/lib/python3.11/site-packages/zeroconf/_utils/asyncio.py", line 90, in run_coro_with_timeout
    raise EventLoopBlocked from ex
zeroconf._exceptions.EventLoopBlocked
2023-07-07 13:11:26 DEBUG music_assistant.providers.spotify  -- Processing GET/me/following took 10.71 seconds
2023-07-07 13:11:27 DEBUG aioslimproto.client.Køkkenlyd  -- Socket disconnected: ('127.0.0.1', 35162)
2023-07-07 13:11:27 DEBUG aioslimproto.client.Stuelyd  -- Socket disconnected: ('127.0.0.1', 35172)
2023-07-07 13:11:27 DEBUG music_assistant.event  -- queue_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.event  -- queue_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.providers.slimproto  -- Socket client connected: ('127.0.0.1', 60776)
2023-07-07 13:11:27 DEBUG aioslimproto.client  -- HELO received: b'\x0c\x00\xaa\xaa\x1bZ\xbf\xc4\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00@\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=44100,pcm'
2023-07-07 13:11:27 DEBUG aioslimproto.client  -- Player connected: aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.providers.slimproto  -- Socket client connected: ('127.0.0.1', 60786)
2023-07-07 13:11:27 DEBUG aioslimproto.client  -- HELO received: b'\x0c\x00\xaa\xaak\xc9\xa7\x89\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00@\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=44100,pcm'
2023-07-07 13:11:27 DEBUG aioslimproto.client  -- Player connected: aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.event  -- queue_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.event  -- queue_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.event  -- queue_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.event  -- queue_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:1b:5a:bf:c4
2023-07-07 13:11:27 DEBUG music_assistant.websocket_api  -- [547270807568] Connection from 172.30.32.2
2023-07-07 13:11:27 DEBUG music_assistant.event  -- player_updated aa:aa:6b:c9:a7:89
2023-07-07 13:11:27 DEBUG music_assistant.websocket_api  -- [547270807568] Handling command players/all
2023-07-07 13:11:27 DEBUG music_assistant.providers.spotify  -- Start sync of album items.
2023-07-07 13:11:27 DEBUG music_assistant.websocket_api  -- [547270807568] Handling command music/albumartists
2023-07-07 13:11:27 DEBUG music_assistant.websocket_api  -- [547270807568] Handling command players/queue/all
2023-07-07 13:11:27 DEBUG music_assistant.websocket_api  -- [547270807568] Handling command providers/available
2023-07-07 13:11:27 DEBUG music_assistant.websocket_api  -- [547270807568] Handling command music/item_by_uri
2023-07-07 13:11:27 DEBUG music_assistant.providers.spotify  -- Processing GET/me/albums took 0.26 seconds
2023-07-07 13:11:27 DEBUG music_assistant.providers.spotify  -- Start sync of track items.
2023-07-07 13:11:28 DEBUG music_assistant.providers.spotify  -- Processing GET/me/tracks took 0.07 seconds
2023-07-07 13:11:28 DEBUG music_assistant.providers.spotify  -- Start sync of playlist items.
2023-07-07 13:11:28 DEBUG music_assistant.websocket_api  -- [547270807568] Handling command providers
2023-07-07 13:11:28 DEBUG music_assistant.providers.spotify  -- Processing GET/me/playlists took 0.11 seconds
2023-07-07 13:11:28 DEBUG music_assistant.websocket_api  -- [547270807568] Handling command music/synctasks
2023-07-07 13:11:28 DEBUG music_assistant.event  -- sync_tasks_updated 
2023-07-07 13:11:28 DEBUG music_assistant.metadata  -- Start scan for missing artist metadata
2023-07-07 13:11:28 DEBUG music_assistant.metadata  -- Finished scan for missing artist metadata

qtetrode Jul 7, 2023

I removed the old broken MAS first and installed the beta version according to the installation steps.

andeekaii · 2023-07-07T11:19:16Z

andeekaii
Jul 7, 2023

I also have the same issue. I believe I installed the integration first and then the add-on was created by the integration as this was recommended in the installation steps - "If you are running Home Assistant OS or Home Assistant supervisor, you can skip this step as the Home Assistant integration will take care of installing the Music Assistant Server for you as add-on."

I haven't tried to roll back yet as the users above have reported that it doesn't work.

0 replies

marcelveldt · 2023-07-07T12:57:49Z

marcelveldt
Jul 7, 2023
Maintainer

beta 41 is available which fixes the issue

1 reply

andeekaii Jul 7, 2023

Can confirm that it works now, thank you :)

userMak · 2023-07-07T14:36:34Z

userMak
Jul 7, 2023
Author

beta 41 is available which fixes the issue

Yes, now it is working for me also..
May I ask, if it is easy to add an option to clear the queue each time we turn off the player? (squeezelite)

1 reply

marcelveldt Jul 7, 2023
Maintainer

please create a separate feature request for that, bit hard for me to track feature requests within issue reports

qtetrode · 2023-07-07T15:16:47Z

qtetrode
Jul 7, 2023

Can also confirm it works. Thx!

…

________________________________ From: Marcel van der Veldt ***@***.***> Sent: Friday, July 7, 2023 14:57 To: music-assistant/hass-music-assistant Cc: qtetrode; Comment Subject: Re: [music-assistant/hass-music-assistant] latest update failed (Discussion #1344) beta 41 is available which fixes the issue — Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you commented.Message ID: ***@***.***>

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Music Assistant

latest update failed #1344

{{title}}

Replies: 7 comments 4 replies

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

latest update failed #1344

Replies: 7 comments · 4 replies

OzGav Jul 7, 2023 Maintainer

marcelveldt Jul 7, 2023 Maintainer

userMak Jul 7, 2023 Author

marcelveldt Jul 7, 2023 Maintainer

Replies: 7 comments 4 replies

OzGav
Jul 7, 2023
Maintainer

marcelveldt
Jul 7, 2023
Maintainer

userMak
Jul 7, 2023
Author

marcelveldt Jul 7, 2023
Maintainer