-- Logs begin at Wed 2025-06-04 03:16:24 UTC, end at Thu 2025-06-12 11:14:01 UTC. -- Jun 12 11:13:00 volumio volumio[28203]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 147336 } Jun 12 11:13:00 volumio volumio[1003]: [SpotifyConnect] play Jun 12 11:13:00 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jun 12 11:13:00 volumio volumio[28203]: [Vollibrespot] : Event: SinkActive Jun 12 11:13:00 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:00 volumio volumio[1003]: info: [1749726780837] ControllerSpotify::pushState Jun 12 11:13:00 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:00 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:00 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:00 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:00 volumio volumio[1003]: [SpotifyConnect] Sink acquired Jun 12 11:13:00 volumio volumio[1003]: info: Acquiring new spotify session Jun 12 11:13:00 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:13:00 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:13:00 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:13:00 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:13:00 volumio volumio[1003]: [SpotifyConnect] Spotify Received stop Jun 12 11:13:01 volumio volumio[28203]: [Vollibrespot] : Pause Jun 12 11:13:01 volumio volumio[28203]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 148068 } Jun 12 11:13:01 volumio volumio[1003]: [SpotifyConnect] Pause Jun 12 11:13:01 volumio volumio[1003]: [SpotifyConnect] pause Jun 12 11:13:01 volumio volumio[1003]: info: Checking Spotify Web API Jun 12 11:13:01 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Active Jun 12 11:13:01 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 11:13:01 volumio volumio[1003]: info: [1749726781350] ControllerSpotify::pushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:01 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:01 volumio volumio[1003]: info: [1749726781562] ControllerSpotify::pushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:01 volumio volumio[28203]: [Vollibrespot] : Event: SinkInactive Jun 12 11:13:01 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:01 volumio volumio[1003]: [SpotifyConnect] Sink released Jun 12 11:13:01 volumio volumio[1003]: info: [1749726781897] ControllerSpotify::pushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:01 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:01 volumio volumio[1003]: [SpotifyConnect] Device palyback is inactive Jun 12 11:13:02 volumio volumio[28203]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 148375 } Jun 12 11:13:02 volumio volumio[1003]: [SpotifyConnect] play Jun 12 11:13:02 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jun 12 11:13:02 volumio volumio[28203]: [Vollibrespot] : Event: SinkActive Jun 12 11:13:02 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:02 volumio volumio[1003]: info: [1749726782864] ControllerSpotify::pushState Jun 12 11:13:02 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:02 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:02 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:02 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:02 volumio volumio[1003]: [SpotifyConnect] Sink acquired Jun 12 11:13:02 volumio volumio[1003]: info: Acquiring new spotify session Jun 12 11:13:02 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:13:02 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:13:02 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:13:02 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:13:02 volumio volumio[1003]: [SpotifyConnect] Spotify Received stop Jun 12 11:13:03 volumio volumio[28203]: [Vollibrespot] : Pause Jun 12 11:13:03 volumio volumio[28203]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 149109 } Jun 12 11:13:03 volumio volumio[1003]: [SpotifyConnect] Pause Jun 12 11:13:03 volumio volumio[1003]: [SpotifyConnect] pause Jun 12 11:13:03 volumio volumio[1003]: info: Checking Spotify Web API Jun 12 11:13:03 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Active Jun 12 11:13:03 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 11:13:03 volumio volumio[1003]: info: [1749726783372] ControllerSpotify::pushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:03 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:03 volumio volumio[1003]: info: [1749726783598] ControllerSpotify::pushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:03 volumio volumio[28203]: [Vollibrespot] : Event: SinkInactive Jun 12 11:13:03 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:03 volumio volumio[1003]: [SpotifyConnect] Sink released Jun 12 11:13:03 volumio volumio[1003]: info: [1749726783923] ControllerSpotify::pushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:03 volumio volumio[1003]: [SpotifyConnect] Device palyback is inactive Jun 12 11:13:04 volumio volumio[28203]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 149420 } Jun 12 11:13:04 volumio volumio[1003]: [SpotifyConnect] play Jun 12 11:13:04 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jun 12 11:13:05 volumio volumio[28203]: [Vollibrespot] : Event: SinkActive Jun 12 11:13:05 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:05 volumio volumio[1003]: info: [1749726785136] ControllerSpotify::pushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:05 volumio volumio[1003]: [SpotifyConnect] Sink acquired Jun 12 11:13:05 volumio volumio[1003]: info: Acquiring new spotify session Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:13:05 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:13:05 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:13:05 volumio volumio[1003]: [SpotifyConnect] Spotify Received stop Jun 12 11:13:05 volumio volumio[28203]: [Vollibrespot] : Pause Jun 12 11:13:05 volumio volumio[28203]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 150144 } Jun 12 11:13:05 volumio volumio[1003]: [SpotifyConnect] Pause Jun 12 11:13:05 volumio volumio[1003]: [SpotifyConnect] pause Jun 12 11:13:05 volumio volumio[1003]: info: Checking Spotify Web API Jun 12 11:13:05 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Active Jun 12 11:13:05 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 11:13:05 volumio volumio[1003]: info: [1749726785646] ControllerSpotify::pushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:05 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:05 volumio volumio[1003]: info: [1749726785868] ControllerSpotify::pushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:05 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:06 volumio volumio[28203]: [Vollibrespot] : Event: SinkInactive Jun 12 11:13:06 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:06 volumio volumio[1003]: [SpotifyConnect] Sink released Jun 12 11:13:06 volumio volumio[1003]: info: [1749726786206] ControllerSpotify::pushState Jun 12 11:13:06 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:06 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:06 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:06 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:06 volumio volumio[1003]: [SpotifyConnect] Device palyback is inactive Jun 12 11:13:16 volumio volumio[28203]: [Vollibrespot] : Event: SessionInactive { became_inactive_at: 1749726796262 } Jun 12 11:13:16 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:16 volumio volumio[1003]: [SpotifyConnect] Connect Session has ended Jun 12 11:13:16 volumio volumio[1003]: [SpotifyConnect] Device palyback is inactive Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Fetching autoplay context uri Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Event: SessionActive { became_active_at: 1749726799637 } Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : SessionActive! Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:19 volumio volumio[1003]: [SpotifyConnect] A connect session has begun Jun 12 11:13:19 volumio volumio[1003]: info: Acquiring new spotify session Jun 12 11:13:19 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:13:19 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:13:19 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:13:19 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:13:19 volumio volumio[1003]: [SpotifyConnect] Spotify Received stop Jun 12 11:13:19 volumio volumio[1003]: [SpotifyConnect] Device palyback is active! Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Loading with Spotify URI Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:3QRTAnPp40nrKYgrDO0lxT"> Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Resolving uri "spotify:station:artist:3QRTAnPp40nrKYgrDO0lxT" Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCciSbR-fPr0xDzrjqZzEB4ZDpWzu8h8f7Phk6rgKn-oPwrbLePLo4alOtjmI3D2wGxDZ_93LaBkH9c4FQMcDoJ58sMAx4PD3nassO0CfpO4jIfdfkRTr_OWy_Bzj7-BbfM0o2M6JTaAPgK0PNI4uTA-e-yo8oNYAXDB5NNDyIJIgJVQxNMw0XUic7uTicDUDaedDnVmeSSUIeIgxUsTm2glQ2hPstejWBiCuf86Q8mw1UwBPCahZzICZkS3TLeNjoUOA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Pause Jun 12 11:13:19 volumio volumio[28203]: [Vollibrespot] : Player::pause called from invalid state Jun 12 11:13:19 volumio volumio[1003]: info: New Spotify Access Token Received Jun 12 11:13:19 volumio volumio[1003]: info: Initializing Spotify Web API Jun 12 11:13:19 volumio volumio[1003]: [SpotifyConnect] Pause Jun 12 11:13:19 volumio volumio[1003]: [SpotifyConnect] Setting VLS device_id: c6e56cf6b68c07041768b06e2f29a439e7724b0e Jun 12 11:13:20 volumio volumio[28203]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E4zEbURIDkLv2"> Jun 12 11:13:20 volumio volumio[1003]: info: Checking Spotify Web API Jun 12 11:13:20 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Active Jun 12 11:13:20 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 11:13:20 volumio volumio[1003]: info: [1749726800141] ControllerSpotify::pushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:20 volumio volumio[28203]: [Vollibrespot] : (263588 ms) loaded Jun 12 11:13:20 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jun 12 11:13:20 volumio volumio[28203]: [Vollibrespot] : Event: SinkActive Jun 12 11:13:20 volumio volumio[1003]: [SpotifyConnect] Sink acquired Jun 12 11:13:20 volumio volumio[1003]: info: Acquiring new spotify session Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:13:20 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:13:20 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:13:20 volumio volumio[1003]: [SpotifyConnect] Spotify Received stop Jun 12 11:13:20 volumio volumio[28203]: [Vollibrespot] : Pause Jun 12 11:13:20 volumio volumio[28203]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 153340 } Jun 12 11:13:20 volumio volumio[1003]: [SpotifyConnect] Pause Jun 12 11:13:20 volumio volumio[1003]: [SpotifyConnect] pause Jun 12 11:13:20 volumio volumio[1003]: info: Checking Spotify Web API Jun 12 11:13:20 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Active Jun 12 11:13:20 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 11:13:20 volumio volumio[1003]: info: [1749726800921] ControllerSpotify::pushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:20 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:21 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:21 volumio volumio[1003]: info: [1749726801133] ControllerSpotify::pushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:21 volumio volumio[28203]: [Vollibrespot] : Event: SinkInactive Jun 12 11:13:21 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:21 volumio volumio[1003]: [SpotifyConnect] Sink released Jun 12 11:13:21 volumio volumio[1003]: info: [1749726801200] ControllerSpotify::pushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:21 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:21 volumio volumio[1003]: [SpotifyConnect] Device palyback is inactive Jun 12 11:13:22 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jun 12 11:13:22 volumio volumio[28203]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 153368 } Jun 12 11:13:22 volumio volumio[1003]: [SpotifyConnect] play Jun 12 11:13:22 volumio volumio[28203]: [Vollibrespot] : Event: SinkActive Jun 12 11:13:22 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:22 volumio volumio[1003]: info: [1749726802609] ControllerSpotify::pushState Jun 12 11:13:22 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:22 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:22 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:22 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:22 volumio volumio[1003]: [SpotifyConnect] Sink acquired Jun 12 11:13:22 volumio volumio[1003]: info: Acquiring new spotify session Jun 12 11:13:22 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:13:22 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:13:22 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:13:22 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:13:22 volumio volumio[1003]: [SpotifyConnect] Spotify Received stop Jun 12 11:13:23 volumio volumio[28203]: [Vollibrespot] : Pause Jun 12 11:13:23 volumio volumio[28203]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 154072 } Jun 12 11:13:23 volumio volumio[1003]: [SpotifyConnect] Pause Jun 12 11:13:23 volumio volumio[1003]: info: Checking Spotify Web API Jun 12 11:13:23 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Active Jun 12 11:13:23 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 11:13:23 volumio volumio[1003]: info: [1749726803119] ControllerSpotify::pushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:23 volumio volumio[1003]: [SpotifyConnect] pause Jun 12 11:13:23 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:23 volumio volumio[1003]: info: [1749726803371] ControllerSpotify::pushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:23 volumio volumio[28203]: [Vollibrespot] : Event: SinkInactive Jun 12 11:13:23 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:23 volumio volumio[1003]: [SpotifyConnect] Sink released Jun 12 11:13:23 volumio volumio[1003]: info: [1749726803707] ControllerSpotify::pushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:23 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:23 volumio volumio[1003]: [SpotifyConnect] Device palyback is inactive Jun 12 11:13:24 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jun 12 11:13:24 volumio volumio[28203]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 154416 } Jun 12 11:13:24 volumio volumio[1003]: [SpotifyConnect] play Jun 12 11:13:24 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:24 volumio volumio[1003]: info: [1749726804950] ControllerSpotify::pushState Jun 12 11:13:24 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:24 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:24 volumio volumio[28203]: [Vollibrespot] : Event: SinkActive Jun 12 11:13:24 volumio volumio[1003]: [SpotifyConnect] Sink acquired Jun 12 11:13:24 volumio volumio[1003]: info: Acquiring new spotify session Jun 12 11:13:24 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:13:24 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:13:24 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:13:24 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:13:24 volumio volumio[1003]: [SpotifyConnect] Spotify Received stop Jun 12 11:13:25 volumio volumio[28203]: [Vollibrespot] : Pause Jun 12 11:13:25 volumio volumio[28203]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track }, position_ms: 155109 } Jun 12 11:13:25 volumio volumio[1003]: [SpotifyConnect] Pause Jun 12 11:13:25 volumio volumio[1003]: [SpotifyConnect] pause Jun 12 11:13:25 volumio volumio[1003]: info: Checking Spotify Web API Jun 12 11:13:25 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Active Jun 12 11:13:25 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 11:13:25 volumio volumio[1003]: info: [1749726805460] ControllerSpotify::pushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:25 volumio volumio[1003]: [SpotifyConnect] Hold Me - 2019 Remaster Jun 12 11:13:25 volumio volumio[1003]: info: [1749726805644] ControllerSpotify::pushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:26 volumio volumio[28203]: [Vollibrespot] : Event: SinkInactive Jun 12 11:13:26 volumio volumio[28203]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:13:26 volumio volumio[1003]: [SpotifyConnect] Sink released Jun 12 11:13:26 volumio volumio[1003]: info: [1749726806050] ControllerSpotify::pushState Jun 12 11:13:26 volumio volumio[1003]: info: CoreCommandRouter::servicePushState Jun 12 11:13:26 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:26 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:26 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:13:26 volumio volumio[1003]: [SpotifyConnect] Device palyback is inactive Jun 12 11:13:30 volumio volumio[1003]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 11:13:30 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 11:13:30 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Jun 12 11:13:30 volumio volumio[1003]: info: CoreCommandRouter::volumioGetQueue Jun 12 11:13:30 volumio volumio[1003]: info: CoreStateMachine::getQueue Jun 12 11:13:30 volumio volumio[1003]: info: CorePlayQueue::getQueue Jun 12 11:13:30 volumio volumio[1003]: info: Listing playlists Jun 12 11:13:30 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 11:13:33 volumio volumio[1003]: info: Starting Uninstall of plugin music_service - spop Jun 12 11:13:33 volumio volumio[1003]: info: Uninstalling plugin spop Jun 12 11:13:33 volumio volumio[1003]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jun 12 11:13:33 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 11:13:33 volumio volumio[1003]: [SpotifyConnect] Closing metadata listener Jun 12 11:13:33 volumio volumio[1003]: info: Disabling plugin spop Jun 12 11:13:33 volumio volumio[1003]: info: Checking if uninstall.sh is present Jun 12 11:13:33 volumio sudo[28345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Jun 12 11:13:33 volumio volumio[1003]: info: Executing uninstall.sh Jun 12 11:13:33 volumio sudo[28345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 11:13:33 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Jun 12 11:13:33 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jun 12 11:13:33 volumio sudo[28345]: pam_unix(sudo:session): session closed for user root Jun 12 11:13:33 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Daemon service stoped! Jun 12 11:13:33 volumio sudo[28352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Jun 12 11:13:33 volumio sudo[28352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 11:13:33 volumio sudo[28352]: pam_unix(sudo:session): session closed for user root Jun 12 11:13:33 volumio volumio[1003]: info: Uninstall script completed Jun 12 11:13:33 volumio volumio[1003]: info: Removing plugin spop from configuration Jun 12 11:13:33 volumio volumio[1003]: info: Successfully removed spop configuration files Jun 12 11:13:33 volumio volumio[1003]: info: Plugin folders cleanup Jun 12 11:13:33 volumio volumio[1003]: info: Scanning into folder /volumio/app/plugins/ Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category audio_interface Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category miscellanea Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category music_service Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category plugins.json Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category system_controller Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category user_interface Jun 12 11:13:33 volumio volumio[1003]: info: Scanning into folder /data/plugins/ Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category miscellanea Jun 12 11:13:33 volumio volumio[1003]: info: Scanning category music_service Jun 12 11:13:33 volumio volumio[1003]: info: Cleaning folder for spop Jun 12 11:13:34 volumio volumio[1003]: info: Scanning category system_controller Jun 12 11:13:34 volumio volumio[1003]: info: Scanning category user_interface Jun 12 11:13:34 volumio volumio[1003]: info: Plugin folders cleanup completed Jun 12 11:13:34 volumio volumio[1003]: info: [{"prettyName":"System information","name":"Systeminfo","category":"miscellanea","version":"0.1.4","icon":"fa fa-cube","enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"miscellanea","version":"1.2.1","icon":"fa-hand-pointer-o","enabled":false,"active":false},{"prettyName":"Youtube2","name":"youtube2","category":"music_service","version":"0.1.1a-20210627","icon":"fa-youtube","enabled":false,"active":false},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_controller","version":"0.8.1","icon":"fa-microchip","enabled":true,"active":true},{"prettyName":"Rotary Encoder II","name":"rotaryencoder2","category":"user_interface","version":"1.0.7","icon":"fa-circle-o-notch","enabled":true,"active":true}] Jun 12 11:13:40 volumio volumio[1003]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Jun 12 11:13:41 volumio volumio[1003]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Jun 12 11:13:41 volumio volumio[1003]: info: Folder /tmp/plugins removed Jun 12 11:13:41 volumio volumio[1003]: info: Checking if plugin already exists Jun 12 11:13:41 volumio volumio[1003]: info: Rename folder Jun 12 11:13:41 volumio volumio[1003]: info: Folder /tmp/downloaded_plugin.zip removed Jun 12 11:13:41 volumio volumio[1003]: info: Move to category Jun 12 11:13:42 volumio volumio[1003]: info: Checking if install.sh is present Jun 12 11:13:42 volumio volumio[1003]: info: Executing install.sh Jun 12 11:13:42 volumio sudo[28399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/volspotconnect2/install.sh Jun 12 11:13:42 volumio sudo[28399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 11:13:42 volumio volumio[1003]: info: Launching a bash shell Jun 12 11:13:42 volumio volumio[1003]: info: Installing volspotconnect2 dependencies Jun 12 11:13:42 volumio volumio[1003]: info: Detected cpu architecture as armv7l Jun 12 11:13:43 volumio volumio[1003]: info: Latest version: v0.2.5 Requested version: v0.2.2 Jun 12 11:13:43 volumio volumio[1003]: info: Supported device (arch = armv7l), downloading required packages for vollibrespot v0.2.2 Jun 12 11:13:43 volumio volumio[1003]: info: Downloading file Jun 12 11:13:45 volumio volumio[1003]: info: Extracting... Jun 12 11:13:45 volumio volumio[1003]: info: -rw-r--r-- 1 root root 4289193 Jun 12 11:13 vollibrespot-armv7l.tar.xz Jun 12 11:13:45 volumio volumio[1003]: info: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Jun 12 11:13:45 volumio sudo[28435]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/tar -xvf volspotconnect2.service.tar -C / Jun 12 11:13:45 volumio sudo[28435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 11:13:45 volumio volumio[1003]: info: etc/systemd/system/volspotconnect2.service Jun 12 11:13:45 volumio sudo[28435]: pam_unix(sudo:session): session closed for user root Jun 12 11:13:45 volumio sudo[28443]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/music_service/volspotconnect2/onstart1.sh Jun 12 11:13:45 volumio sudo[28443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 11:13:45 volumio sudo[28443]: pam_unix(sudo:session): session closed for user root Jun 12 11:13:46 volumio sudo[28399]: pam_unix(sudo:session): session closed for user root Jun 12 11:13:46 volumio volumio[1003]: info: volspotconnect2 installed Jun 12 11:13:46 volumio volumio[1003]: Plugin install end detected on script Jun 12 11:13:46 volumio volumio[1003]: info: Install script completed Jun 12 11:13:46 volumio volumio[1003]: info: Adding reference to registry Jun 12 11:13:46 volumio volumio[1003]: info: Done installing plugin. Jun 12 11:13:46 volumio volumio[1003]: info: Folder /tmp/plugins removed Jun 12 11:13:46 volumio volumio[1003]: info: Folder /tmp/downloaded_plugin.zip removed Jun 12 11:13:46 volumio volumio[1003]: info: Folder /data/temp removed Jun 12 11:13:48 volumio volumio[1003]: info: Enabling plugin volspotconnect2 Jun 12 11:13:48 volumio volumio[1003]: info: Loading plugin "volspotconnect2"... Jun 12 11:13:48 volumio volumio[1003]: info: PLUGIN START: volspotconnect2 Jun 12 11:13:48 volumio volumio[1003]: [SpotifyConnect] Creating VLS config file Jun 12 11:13:48 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 11:13:48 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 11:13:48 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 11:13:48 volumio volumio[1003]: [SpotifyConnect] Starting metadata listener Jun 12 11:13:48 volumio sudo[28459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jun 12 11:13:48 volumio sudo[28459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 11:13:48 volumio systemd[1]: Starting Volspotconnect2 Daemon... Jun 12 11:13:48 volumio systemd[1]: Started Volspotconnect2 Daemon. Jun 12 11:13:48 volumio sudo[28459]: pam_unix(sudo:session): session closed for user root Jun 12 11:13:48 volumio volumio[28464]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Jun 12 11:13:48 volumio volumio[28464]: Reading Config from "volspotify.toml" Jun 12 11:13:48 volumio volumio[28464]: [Vollibrespot] : Using Alsa backend with device: softvolume Jun 12 11:13:48 volumio volumio[1003]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 12 11:13:48 volumio volumio[1003]: [Metrics] SpotifyConnect: 0s 74.93ms Jun 12 11:13:48 volumio volumio[1003]: info: Done. Jun 12 11:13:48 volumio volumio[28464]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Authenticated as "31bpqdxpqwizuu7thyegitlutkcm" ! Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Setting up new mixer: card:hw:2 mixer:SoftMaster index:0 Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(0)[dB]) -- max: 99 (MilliBel(0)[dB]) HW: false Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Using Alsa linear volume Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Using alsa sink Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Country: "JP" Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Metadata pipe established Jun 12 11:13:59 volumio volumio[28464]: [Vollibrespot] : Event: Volume { volume_to_mixer: 3276 } Jun 12 11:13:59 volumio volumio[1003]: [SpotifyConnect] 4.998855573357748 Jun 12 11:13:59 volumio volumio[1003]: [SpotifyConnect] Volume: Spotify:4.998855573357748 Volumio: 5 Jun 12 11:13:59 volumio volumio[1003]: info: CoreStateMachine::pushState Jun 12 11:13:59 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Jun 12 11:13:59 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Fetching autoplay context uri Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Event: SessionActive { became_active_at: 1749726840277 } Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : SessionActive! Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 74069756015162350568622165384083678826, audio_type: Track } } Jun 12 11:14:00 volumio volumio[1003]: [SpotifyConnect] A connect session has begun Jun 12 11:14:00 volumio volumio[1003]: [SpotifyConnect] Device palyback is active! Jun 12 11:14:00 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Jun 12 11:14:00 volumio volumio[1003]: [SpotifyConnect] Currently active: spop Jun 12 11:14:00 volumio volumio[1003]: [SpotifyConnect] Stopping currently active service Jun 12 11:14:00 volumio volumio[1003]: info: CoreCommandRouter::volumioStop Jun 12 11:14:00 volumio volumio[1003]: info: CoreStateMachine::stop Jun 12 11:14:00 volumio volumio[1003]: info: CoreStateMachine::serviceStop Jun 12 11:14:00 volumio volumio[1003]: info: CoreCommandRouter::serviceStop Jun 12 11:14:00 volumio volumio[1003]: error: WARNING: No stop method for service spop Jun 12 11:14:00 volumio volumio[1003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 11:14:00 volumio volumio[1003]: TypeError: Cannot read property 'then' of undefined Jun 12 11:14:00 volumio volumio[1003]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Jun 12 11:14:00 volumio volumio[1003]: at emitNone (events.js:106:13) Jun 12 11:14:00 volumio volumio[1003]: at SpotConnEvents.emit (events.js:208:7) Jun 12 11:14:00 volumio volumio[1003]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Jun 12 11:14:00 volumio volumio[1003]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Jun 12 11:14:00 volumio volumio[1003]: at emitTwo (events.js:126:13) Jun 12 11:14:00 volumio volumio[1003]: at Socket.emit (events.js:214:7) Jun 12 11:14:00 volumio volumio[1003]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Loading with Spotify URI Jun 12 11:14:00 volumio volumio[1003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:3QRTAnPp40nrKYgrDO0lxT"> Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Resolving uri "spotify:station:artist:3QRTAnPp40nrKYgrDO0lxT" Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQAXro5MwZs6EVk4IoLco4uDyunXb6Z3lqV1Z2NQOvFXDfmDXOlKCYvwpNv1-QZtqmSBxaV64ijPYXW_i1K4WBCJAEYdrI5POXb6PHfoYo9DdonJlEXLBE-mkOxrqZnXqWJx2WSRtiKzzGQfu85t9z73raL-TDyP4mZ5L-nBLkdcZ-MC43tDfVD8HxMTUu0hf3wEGcsVjbDlIIC1hPtaxbXLapVgtT6JjddpDBC7_Ti67KghvP1V1XUuiI2x0wps2_GWBg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jun 12 11:14:00 volumio volumio[28464]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E4zEbURIDkLv2"> Jun 12 11:14:01 volumio volumio[28464]: [Vollibrespot] : (263588 ms) loaded Jun 12 11:14:01 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jun 12 11:14:01 volumio volumio[28464]: [Vollibrespot] : Event: SinkActive Jun 12 11:14:01 volumio sudo[28526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 11:13 Jun 12 11:14:01 volumio sudo[28526]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"