search

ashthespy / Vollibrespot

Spotify Connect daemon for Volumio
MIT License
20 stars 8 forks source link

Skipping every song, not playing for more than a second #9

Closed misterjohannesson closed 2 years ago

misterjohannesson commented 2 years ago

We just recently discovered that ALL our devices with vollibrespot started with the exact same behavior listed here https://community.spotify.com/t5/Desktop-Windows/Spotify-Skipping-every-song-not-playing-for-more-than-a-second/td-p/1197549/page/3

But unlike the solution restarting both the service and the actual device does not solve anything. If we in the web browser play the music, it is all fine - but as soon as we swap over to the device, it just starts skipping forever.

I went into the config and changed the bitrate from 320 to 160, as some people have suggested for when Chrome Cast presents similar behavior. But nothing works. 2022-03-01 13-43-26

ashthespy commented 2 years ago

Would you have some logs to go along with this behaviour?

misterjohannesson commented 2 years ago

As one would expect for just a few seconds there is a lot in the logs.

Feb 02 16:30:05 leverancen systemd[1]: Started Vollibrespot.
Mar 02 09:42:32 leverancen vollibrespot[682]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: Custom { kind: Other, error: "failed to lookup address information: Name or service not known" }', /home/matuschd/buildroot-4/host/share/cargo/git/checkouts/librespot-e4d7c35a023053af/1a224a3/core/src/connection/mod.rs:33:41
Mar 02 09:42:32 leverancen vollibrespot[682]: stack backtrace:
Mar 02 09:42:32 leverancen vollibrespot[682]:    0:   0xa4c780 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    1:   0xa71b30 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    2:   0xa44ba8 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    3:   0xa4ef38 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    4:   0xa4e8d0 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    5:   0xa4f598 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    6:   0xa4f114 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    7:   0xa4ccfc - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    8:   0xa4f05c - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:    9:   0x51a6ac - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   10:   0x51a5b0 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   11:   0x70c17c - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   12:   0x723b40 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   13:   0x53d920 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   14:   0x55bf50 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   15:   0x56fa48 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   16:   0x54d624 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   17:   0x56f2b0 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   18:   0x5774d0 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   19:   0x54735c - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   20:   0x5408ec - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   21:   0x5782f0 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   22:   0x578680 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   23:   0xa4f998 - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   24:   0x5420ac - <unknown>
Mar 02 09:42:32 leverancen vollibrespot[682]:   25: 0xb6c4fc04 - __libc_start_main
Mar 02 09:42:32 leverancen systemd[1]: spotify.service: Main process exited, code=exited, status=101/n/a
Mar 02 09:42:32 leverancen systemd[1]: spotify.service: Failed with result 'exit-code'.
Mar 02 09:42:38 leverancen systemd[1]: spotify.service: Scheduled restart job, restart counter is at 1.
Mar 02 09:42:38 leverancen systemd[1]: Stopped Vollibrespot.
Mar 02 09:42:38 leverancen systemd[1]: Starting Vollibrespot...
Mar 02 09:42:38 leverancen vollibrespot[820]: vollibrespot v0.2.2 UNKNOWN UNKNOWN (librespot 1a224a3 2020-10-25) -- Built On 2021-11-05
Mar 02 09:42:38 leverancen vollibrespot[820]: Reading Config from "/etc/vollibrespot.conf"
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Using Alsa backend with device: default
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443"
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Authenticated as "315fzzu3o2mvm23emfyseyyphoy4" !
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Setting up new mixer: card:hw:0 mixer:Digital index:0
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-9999999)[dB]) -- max: 207 (MilliBel(0)[dB]) HW: true
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Using alsa sink
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Metadata pipe established
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Country: "DK"
Mar 02 09:42:38 leverancen vollibrespot[820]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32767 }
Mar 02 09:42:39 leverancen restore-volume[830]: alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy
Mar 02 09:42:39 leverancen restore-volume[830]: alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy
Mar 02 09:42:39 leverancen restore-volume[830]: alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy
Mar 02 09:42:39 leverancen restore-volume[834]: Simple mixer control 'Softvol',0
Mar 02 09:42:39 leverancen restore-volume[834]:   Capabilities: volume
Mar 02 09:42:39 leverancen restore-volume[834]:   Playback channels: Front Left - Front Right
Mar 02 09:42:39 leverancen restore-volume[834]:   Capture channels: Front Left - Front Right
Mar 02 09:42:39 leverancen restore-volume[834]:   Limits: 0 - 255
Mar 02 09:42:39 leverancen restore-volume[834]:   Front Left: 255 [100%]
Mar 02 09:42:39 leverancen restore-volume[834]:   Front Right: 255 [100%]
Mar 02 09:42:39 leverancen systemd[1]: Started Vollibrespot.
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Fetching autoplay context uri
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Event: SessionActive { became_active_at: 1646210588867 }
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : SessionActive!
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1DX5trt9i14X7j">
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1DX5trt9i14X7j"
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQC09PSUWvekax-ksXW_Cev2NhhJE2u4jMn78M-xdu6ZR2Yy_cjfTXtOd8B2wKwSskHPeXb_9n6UWPqflGnWiWBxg5OXmVZ7zWEoX4m4lYxSyPaiCo1_uowfAF8nUmSruQslCwwVQOIhjrtEGGu79EZjwvRh7NC8OriY5OMCyL9TyUv-KXGjfdVrb0cd2w", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Felina> with Spotify URI <spotify:track:1O1eKFVVUiVChb3uDHXohb>
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : error 404 for uri hm://radio-apollo/v3/stations/spotify:station:playlist:37i9dQZF1DX5trt9i14X7j
Mar 02 09:43:08 leverancen vollibrespot[820]: [Vollibrespot] : ContextPollError: MercuryError
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track }, track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Here> with Spotify URI <spotify:track:2xz4DmSqTS3MagfRnnaMvz>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track }, track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Longevity> with Spotify URI <spotify:track:4Aq7uO12FRC7JnLqY4T7Z7>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Reverse> with Spotify URI <spotify:track:60dqFNs24vy6v1T6yxIjCt>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track }, track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Chasing the Calm> with Spotify URI <spotify:track:2V9jgt9hxaJjXTfy15DQ9T>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track }, track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Ribbon On A Branch> with Spotify URI <spotify:track:66OKhLJ2kAi4VjT8nbhBeX>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Our Secret Universe> with Spotify URI <spotify:track:7smnas9qQmvVSiGID6ZNRt>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track }, track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Sunrise Terracotta> with Spotify URI <spotify:track:5O4fFZjQy4PT6UEbXLo7Aq>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track }, track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Slow Hours> with Spotify URI <spotify:track:6gyeaab6u8qGxz1nbY4Jjh>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Landed On La Luna> with Spotify URI <spotify:track:6aF7MKsIbYTQyRq4ep2l7b>
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track }, track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:09 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track }>
Mar 02 09:43:09 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Sky Sailing> with Spotify URI <spotify:track:5IxJpGoqwY2DZaPs7OQljU>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track }, track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <So Many Details> with Spotify URI <spotify:track:23Jv42blFAJryqKBl77DJ7>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Creature, Pt. 1> with Spotify URI <spotify:track:38KFHXttkrIpnUd0tTvkZj>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track }, track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Chill - 2> with Spotify URI <spotify:track:4D88ve3cG8zd8jTq8gOtV1>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : No context to update from!
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track }, track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Finding Hope> with Spotify URI <spotify:track:1ZxqvIpfcqYDcV4nP6jxms>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <for you> with Spotify URI <spotify:track:5hgX8pSZSOcKhsvzldsgWy>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track }, track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Sol> with Spotify URI <spotify:track:72c1iKsEgEcfJvsNkv02xF>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track }, track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Green Tea> with Spotify URI <spotify:track:0grdHMgh7NY6uUIBZVW3Vb>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Loading <M_L_02> with Spotify URI <spotify:track:6Ko1oKV3IwOaPlVm6gw67D>
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track }, track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track } }
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:10 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track }>
Mar 02 09:43:10 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Shine> with Spotify URI <spotify:track:3FIsHBemdmpvOy9S3xp0bf>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track }, track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Dream Reverb> with Spotify URI <spotify:track:30ZGBj04xvF67kPKGbMXDX>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track }, track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Can't Hide!> with Spotify URI <spotify:track:68FqCq3ETcaCrAmTn9GYcV>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track }, track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <My Friend the Forest> with Spotify URI <spotify:track:7B0ofCHeQu2FoX7P28ZaPp>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Phase 09 - Sombrero> with Spotify URI <spotify:track:4uKzc4cK3OOzVPluOAoBj2>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track }, track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <You, the Stars> with Spotify URI <spotify:track:097XEZLXglnLOCfwZSPlqz>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track }, track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Felina> with Spotify URI <spotify:track:1O1eKFVVUiVChb3uDHXohb>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Here> with Spotify URI <spotify:track:2xz4DmSqTS3MagfRnnaMvz>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track }, track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track } }
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Longevity> with Spotify URI <spotify:track:4Aq7uO12FRC7JnLqY4T7Z7>
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:11 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track }>
Mar 02 09:43:11 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Reverse> with Spotify URI <spotify:track:60dqFNs24vy6v1T6yxIjCt>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track }, track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Chasing the Calm> with Spotify URI <spotify:track:2V9jgt9hxaJjXTfy15DQ9T>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track }, track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Ribbon On A Branch> with Spotify URI <spotify:track:66OKhLJ2kAi4VjT8nbhBeX>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Our Secret Universe> with Spotify URI <spotify:track:7smnas9qQmvVSiGID6ZNRt>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track }, track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Sunrise Terracotta> with Spotify URI <spotify:track:5O4fFZjQy4PT6UEbXLo7Aq>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track }, track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Slow Hours> with Spotify URI <spotify:track:6gyeaab6u8qGxz1nbY4Jjh>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Landed On La Luna> with Spotify URI <spotify:track:6aF7MKsIbYTQyRq4ep2l7b>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track }, track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Sky Sailing> with Spotify URI <spotify:track:5IxJpGoqwY2DZaPs7OQljU>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track }, track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Loading <So Many Details> with Spotify URI <spotify:track:23Jv42blFAJryqKBl77DJ7>
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:12 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track }>
Mar 02 09:43:12 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Creature, Pt. 1> with Spotify URI <spotify:track:38KFHXttkrIpnUd0tTvkZj>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track }, track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Chill - 2> with Spotify URI <spotify:track:4D88ve3cG8zd8jTq8gOtV1>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : No context to update from!
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Finding Hope> with Spotify URI <spotify:track:1ZxqvIpfcqYDcV4nP6jxms>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track }, track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <for you> with Spotify URI <spotify:track:5hgX8pSZSOcKhsvzldsgWy>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track }, track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Sol> with Spotify URI <spotify:track:72c1iKsEgEcfJvsNkv02xF>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Green Tea> with Spotify URI <spotify:track:0grdHMgh7NY6uUIBZVW3Vb>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track }, track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <M_L_02> with Spotify URI <spotify:track:6Ko1oKV3IwOaPlVm6gw67D>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track }, track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Shine> with Spotify URI <spotify:track:3FIsHBemdmpvOy9S3xp0bf>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Dream Reverb> with Spotify URI <spotify:track:30ZGBj04xvF67kPKGbMXDX>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track }, track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Can't Hide!> with Spotify URI <spotify:track:68FqCq3ETcaCrAmTn9GYcV>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track }, track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Loading <My Friend the Forest> with Spotify URI <spotify:track:7B0ofCHeQu2FoX7P28ZaPp>
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:13 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track }>
Mar 02 09:43:13 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Phase 09 - Sombrero> with Spotify URI <spotify:track:4uKzc4cK3OOzVPluOAoBj2>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track }, track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <You, the Stars> with Spotify URI <spotify:track:097XEZLXglnLOCfwZSPlqz>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track }, track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Felina> with Spotify URI <spotify:track:1O1eKFVVUiVChb3uDHXohb>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 269557003810327213794298262740334530513, audio_type: Track }, track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Here> with Spotify URI <spotify:track:2xz4DmSqTS3MagfRnnaMvz>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Longevity> with Spotify URI <spotify:track:4Aq7uO12FRC7JnLqY4T7Z7>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 249749149978012403969282587463950070342, audio_type: Track }, track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Reverse> with Spotify URI <spotify:track:60dqFNs24vy6v1T6yxIjCt>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 89978742872302203894057538268097089377, audio_type: Track }, track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Chasing the Calm> with Spotify URI <spotify:track:2V9jgt9hxaJjXTfy15DQ9T>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Ribbon On A Branch> with Spotify URI <spotify:track:66OKhLJ2kAi4VjT8nbhBeX>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 137188424582356249111858839011998198401, audio_type: Track }, track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Our Secret Universe> with Spotify URI <spotify:track:7smnas9qQmvVSiGID6ZNRt>
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track }>
Mar 02 09:43:14 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track } }
Mar 02 09:43:14 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 202261974795151275141362012686595764147, audio_type: Track }, track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Sunrise Terracotta> with Spotify URI <spotify:track:5O4fFZjQy4PT6UEbXLo7Aq>
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load <SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track }>
Mar 02 09:43:15 leverancen vollibrespot[820]: Skipping to next track
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Loading <Slow Hours> with Spotify URI <spotify:track:6gyeaab6u8qGxz1nbY4Jjh>
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 87023874705231422446790270898083103772, audio_type: Track }, track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : channel error: 2 1
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Unable to load encrypted file.
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 230539083633959480461568451299636590334, audio_type: Track }, track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 307265192226410761102656879667953183967, audio_type: Track }, track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 11580051529580804501403889320680621637, audio_type: Track }, track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 294721928340391130633212268030133154609, audio_type: Track }, track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 160409290943426409981241191179807993249, audio_type: Track }, track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 131723625789366867757097197137323131273, audio_type: Track }, track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 268151608231163479284860792561182830385, audio_type: Track }, track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track } }
Mar 02 09:43:15 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 331787877677860026230915131935308572723, audio_type: Track }, track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 196358798550828981609397122452733756784, audio_type: Track }, track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 6430275589464836843407104393013899115, audio_type: Track }, track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 78909488828192171376357382190623800449, audio_type: Track }, track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 110992985569631678799038917907933663845, audio_type: Track }, track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 200353030051425492969621521760534838257, audio_type: Track }, track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 262198104100749240783823340173612783629, audio_type: Track }, track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 127606425519583351927199951628156871981, audio_type: Track }, track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 266848620411167989131968577274450840315, audio_type: Track }, track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 325697825013449848248479886196562174287, audio_type: Track }, track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
Mar 02 09:43:16 leverancen vollibrespot[820]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 253640752766740274911666339491558205310, audio_type: Track }, track_id: SpotifyId { id: 273705181409592458580551003455449207007, audio_type: Track } }
ashthespy commented 2 years ago 
vollibrespot v0.2.2 UNKNOWN UNKNOWN (librespot 1a224a3 2020-10-25)

That is a rather strange version - where/what/how are you using this?

Either way channel errors are mostly to do with the particular Spotify server you are connected to (ap-gew4.spotify.com:443). Not much can be done from this side. Do note that we use an outdated API to communicate to Spotify's servers.

misterjohannesson commented 2 years ago

Right - I just wanted to open the issue and inform you. We have been using this setup to install this service on our barebone raspberry pi OS after the inspiration of hifiberry OS. It has been running for weeks, and then one day, all devices started with this behavior.

If the old API somehow only now connects to this server, there might be a case of updating. Anyway, I will try to open a support case for Spotify. I know they will dodge quickly and tell me that it is the cause of this library and needed to seek out any information.

If I get anything useful from Spotify, I will report back.

ashthespy commented 2 years ago

Anyway, I will try to open a support case for Spotify.

I hope you realise this is based on a reverse engineering - and in no way officially supported by Spotify. Have a look at their docs for official means. https://developer.spotify.com/use-cases/hardware/home-audio/

misterjohannesson commented 2 years ago

Just to close this one-off This issue was also mentioned here https://github.com/librespot-org/librespot/issues/972 Apparently, the issue only targeted users in Denmark.

The solution was found here:

I just tried to force the ap-gew4.spotify.com domain to point to the IP of ap-gew1.spotify.com, by setting this in /etc/hosts:

104.199.65.124  ap-gew4.spotify.com

That works!! Sooo.... Is Spotify having an issue? Or are Spotify changing something?

And this also solved the issue here.

ashthespy commented 2 years ago

You could also just uncomment and set # ap-port = 443 to some random value, so it fails and reverts to ap.spotify.com. This would be in volspotify.tmpl

martix90 commented 2 years ago

Hi @ashthespy I am also experiencing this issue since about this week (living in Germany).The logs really look exactly identical. The workaround with ap-port did not help. I'm using the version 0.24 (as I've seen 0.25 does not change that much). So it seems it is something they are rolling out currently? I do not expect a fix or something - just as an information :)