All of a sudden Spotify not working on Phoniebox

[blutkretsche]

Hi All,

First of all a big thanks to this community. I'm following this project already for quite some time and managed to build 2 Phonieboxes for my 2 boys during that time.
Those boxes are heavily used and became a must for every day life here. Which is actually why I am under pressure as they are both not working with Spotify since yesterday. They have been working with Spotify for several weeks and there was no change done by me.
Already tried to find similar issues and even thought that Spotify is down. Local files and Webradio is working fine, but when I want to swipe a card with a Spotify album or playlist, it just won't start to play.
Rescanning library did not help, updating mopidy-spotify or iris also not. I even renewed the mopidy authentication (e.g. client-id) - no change.
I don't think it's a bug in the Phoniebox software, which is why I start a discussion to see if there is any other idea you guys might have. Thanks a lot in advance.

System (2 boxes both same setup)
OS Distribution: Raspbian GNU/Linux 10 (buster)
Release: 10
Throttling OK
Temperature 42.9'C
Phoniebox Setup
Version2.2 - 305325d - master
EditionPlus edition (feat. Spotify integration)
Mopidy Server Status Mopidy.Server: Connected Mopidy.Service: active

output of journalctl -u mopidy

-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Thu 2021-06-24 12:48:18 BST. --
Jun 24 12:28:54 leosphoniebox systemd[1]: Starting Mopidy music server...
Jun 24 12:28:54 leosphoniebox systemd[1]: Started Mopidy music server.
Jun 24 12:29:00 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.main Starting Mopidy 3.1.1
Jun 24 12:29:00 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.config Loading config from builtin defaults
Jun 24 12:29:00 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy.conf
Jun 24 12:29:00 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy-local.conf
Jun 24 12:29:00 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.config Loading config from file:///etc/mopidy/mopidy.conf
Jun 24 12:29:00 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.config Loading config from command line options
Jun 24 12:29:00 leosphoniebox mopidy[3413]: ERROR [MainThread] mopidy.internal.log Loading logging config '/etc/mopidy/logging.conf' failed. 'formatters'
Jun 24 12:29:01 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.main Enabled extensions: file, m3u, local, iris, softwaremixer, stream, mpd, spotify, http
Jun 24 12:29:01 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.main Disabled extensions: none
Jun 24 12:29:02 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.commands Starting Mopidy mixer: SoftwareMixer
Jun 24 12:29:02 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.commands Mixer volume set to 30
Jun 24 12:29:02 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.commands Starting Mopidy audio
Jun 24 12:29:02 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.commands Starting Mopidy backends: FileBackend, M3UBackend, StreamBackend, SpotifyBackend, LocalBackend
Jun 24 12:29:02 leosphoniebox mopidy[3413]: INFO [Audio-2] mopidy.audio.actor Audio output set to "alsasink"
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [SpotifyBackend-6] mopidy_spotify.web Logged into Spotify Web API as 9z8wmequvyco8radzdj4k8574
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [SpotifyBackend-6] mopidy_spotify.playlists Refreshed 0 Spotify playlists
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.commands Starting Mopidy core
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.commands Starting Mopidy frontends: HttpFrontend, IrisFrontend, MpdFrontend
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [HttpFrontend-12] mopidy.http.actor HTTP server running at [::ffff:0.0.0.0]:6680
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [IrisFrontend-13] mopidy_iris.core Starting Iris 3.58.0
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy_mpd.actor MPD server running at [::ffff:0.0.0.0]:6600
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MainThread] mopidy.commands Starting GLib mainloop
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MpdSession-15] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51674
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MpdSession-16] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51678
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MpdSession-17] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51682
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MpdSession-18] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51686
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MpdSession-19] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51690
Jun 24 12:29:04 leosphoniebox mopidy[3413]: INFO [MpdSession-20] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51694
Jun 24 12:29:05 leosphoniebox mopidy[3413]: INFO [MpdSession-21] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51698
Jun 24 12:29:05 leosphoniebox mopidy[3413]: INFO [MpdSession-22] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51702
Jun 24 12:29:07 leosphoniebox mopidy[3413]: INFO [MpdSession-23] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51706
Jun 24 12:29:08 leosphoniebox mopidy[3413]: INFO [MpdSession-24] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51712
Jun 24 12:29:09 leosphoniebox mopidy[3413]: INFO [MpdSession-25] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51716
Jun 24 12:29:09 leosphoniebox mopidy[3413]: INFO [MpdSession-26] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51720
Jun 24 12:29:10 leosphoniebox mopidy[3413]: INFO [MpdSession-27] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51724
Jun 24 12:29:12 leosphoniebox mopidy[3413]: INFO [MpdSession-28] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51728
Jun 24 12:29:13 leosphoniebox mopidy[3413]: INFO [MpdSession-29] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51734
Jun 24 12:29:14 leosphoniebox mopidy[3413]: INFO [MpdSession-30] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51738
Jun 24 12:29:15 leosphoniebox mopidy[3413]: INFO [MpdSession-31] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51742
Jun 24 12:29:32 leosphoniebox mopidy[3413]: INFO [MpdSession-32] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51746
Jun 24 12:29:33 leosphoniebox mopidy[3413]: INFO [MpdSession-33] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51752
Jun 24 12:29:35 leosphoniebox mopidy[3413]: INFO [MpdSession-34] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51756
Jun 24 12:29:36 leosphoniebox mopidy[3413]: INFO [MpdSession-35] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51760
Jun 24 12:29:37 leosphoniebox mopidy[3413]: INFO [MpdSession-36] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51764
Jun 24 12:29:38 leosphoniebox mopidy[3413]: INFO [MpdSession-37] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51770
Jun 24 12:29:40 leosphoniebox mopidy[3413]: INFO [MpdSession-38] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51774
Jun 24 12:29:41 leosphoniebox mopidy[3413]: INFO [MpdSession-39] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51778
Jun 24 12:29:42 leosphoniebox mopidy[3413]: INFO [MpdSession-40] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51782
Jun 24 12:29:43 leosphoniebox mopidy[3413]: INFO [MpdSession-41] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51786
Jun 24 12:29:45 leosphoniebox mopidy[3413]: INFO [MpdSession-42] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51792
Jun 24 12:29:46 leosphoniebox mopidy[3413]: INFO [MpdSession-43] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51796
Jun 24 12:29:47 leosphoniebox mopidy[3413]: INFO [MpdSession-44] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51800
Jun 24 12:29:48 leosphoniebox mopidy[3413]: INFO [MpdSession-45] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51804
Jun 24 12:29:48 leosphoniebox mopidy[3413]: ERROR [SpotifyEventLoop] spotify.session Spotify login error: <ErrorType.UNABLE_TO_CONTACT_SERVER: 8>
Jun 24 12:29:50 leosphoniebox mopidy[3413]: INFO [MpdSession-46] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51808
Jun 24 12:29:51 leosphoniebox mopidy[3413]: INFO [MpdSession-47] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51812
Jun 24 12:29:52 leosphoniebox mopidy[3413]: INFO [MpdSession-48] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51816
Jun 24 12:29:53 leosphoniebox mopidy[3413]: INFO [MpdSession-49] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51820
Jun 24 12:29:55 leosphoniebox mopidy[3413]: INFO [MpdSession-50] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51824
Jun 24 12:29:56 leosphoniebox mopidy[3413]: INFO [MpdSession-51] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51828
Jun 24 12:29:57 leosphoniebox mopidy[3413]: INFO [MpdSession-52] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:51832

[blutkretsche]

Alright guys,
I fixed it myself by adding new/more local mp3 files and then pressed the scan music library button. Finally it all works including Spotify.

Thanks anyway.