-- Logs begin at Sun 2025-06-15 17:57:21 UTC, end at Sun 2025-06-15 18:05:28 UTC. -- Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Authenticated as "31ybs7se5mxilc6x3ybjuyhwjuga" ! Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Setting up new mixer: card:hw:0 mixer:PCM index:0 Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Alsa Mixer info min: -10239 (MilliBel(-9999999)[dB]) -- max: 400 (MilliBel(400)[dB]) HW: true Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Using alsa sink Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Country: "UA" Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Metadata pipe established Jun 15 18:04:04 volumio volumio[994]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Jun 15 18:04:04 volumio volumio[835]: [SpotifyConnect] Volume Spotify: 0 Volumio: 0 Jun 15 18:04:04 volumio volumio[835]: info: Jun 15 18:04:04 volumio volumio[835]: ---------------------------- MPD announces state update: mixer Jun 15 18:04:04 volumio volumio[835]: info: ControllerMpd::getState Jun 15 18:04:04 volumio volumio[835]: info: ControllerMpd::sendMpdCommand status Jun 15 18:04:04 volumio volumio[835]: info: sending command... Jun 15 18:04:04 volumio volumio[835]: info: parsing response... Jun 15 18:04:04 volumio volumio[835]: info: ControllerMpd::parseState Jun 15 18:04:04 volumio volumio[835]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:04:04 volumio volumio[835]: info: sending command... Jun 15 18:04:04 volumio volumio[835]: info: parsing response... Jun 15 18:04:04 volumio volumio[835]: info: ControllerMpd::parseTrackInfo Jun 15 18:04:04 volumio volumio[835]: info: ControllerMpd::pushState Jun 15 18:04:04 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:04 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:04 volumio volumio[835]: info: CorePlayQueue::getTrack 10 Jun 15 18:04:04 volumio volumio[835]: info: STATE SERVICE {"status":"play","position":1,"seek":80018,"duration":247,"samplerate":"192 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Письмо Шопену","artist":null,"album":"Анна Герман - ИЗБРАННОЕ","uri":"NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.flac","trackType":"flac"} Jun 15 18:04:04 volumio volumio[835]: info: CURRENT POSITION 10 Jun 15 18:04:04 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Jun 15 18:04:04 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:04:04 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Jun 15 18:04:04 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:04 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:04 volumio volumio[835]: info: CorePlayQueue::getTrack 10 Jun 15 18:04:04 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:04 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:04 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:04 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:04 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:04 volumio volumio[835]: info: CorePlayQueue::getTrack 10 Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:05 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:05 volumio volumio[835]: info: ------------------------------ 66ms Jun 15 18:04:05 volumio volumio[835]: info: Pushing Favourites {"service":"mpd","uri":"cue://NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.cue@0","favourite":false} Jun 15 18:04:05 volumio volumio[835]: info: Pushing Favourites {"service":"mpd","uri":"cue://NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.cue@0","favourite":false} Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Fetching autoplay context uri Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Event: SessionActive { became_active_at: 1750010645323 } Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : SessionActive! Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 38796052377147609808483117742330187191, audio_type: Track } } Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 38796052377147609808483117742330187191, audio_type: Track } } Jun 15 18:04:05 volumio volumio[835]: [SpotifyConnect] A connect session has begun Jun 15 18:04:05 volumio volumio[835]: info: Acquiring new spotify session Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioStop Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::stop Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 18:04:05 volumio volumio[835]: UNSET VOLATILE Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Jun 15 18:04:05 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:05 volumio volumio[835]: info: CorePlayQueue::getTrack 10 Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:05 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::serviceStop Jun 15 18:04:05 volumio volumio[835]: info: CorePlayQueue::getTrack 10 Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::serviceStop Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::stop Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand stop Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: [SpotifyConnect] Device palyback is active! Jun 15 18:04:05 volumio volumio[835]: info: Pushing Favourites {"service":"mpd","uri":"cue://NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.cue@0","favourite":false} Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:46mTPMluccHCYpCd8fy4th"> Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Resolving uri "spotify:station:playlist:46mTPMluccHCYpCd8fy4th" Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBxaYje8O4rwyYZazBOmZ3XgxdK6eKhx09nihovyQ0uBDYaK4roSgyreBx60reK6JRjeJ0ufCXVC3PPIv4mN0P7kM0L9E2A58wPELt4ncNz-hUDnRPVcybNRKoE-_AyK_EEWyVpwr-vA8yBUR6QXe_pjKYijllV7lHQDC01iUqbF-AnA6XvA0ywSkkWGMLlYrcJpyI5UR289-9dj7mLhhGhjmp0-hlshLCOW11zmj8k-KDezxDgaCJWd40MyHyWqi0lWw", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jun 15 18:04:05 volumio volumio[835]: info: New Spotify Access Token Received Jun 15 18:04:05 volumio volumio[835]: info: Initializing Spotify Web API Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Loading with Spotify URI Jun 15 18:04:05 volumio volumio[835]: info: Initliazing Spotify Browsing Facility Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 18:04:05 volumio volumio[835]: info: [1750010645453] CoreMusicLibrary::Adding element Spotify Jun 15 18:04:05 volumio volumio[835]: info: Jun 15 18:04:05 volumio volumio[835]: ---------------------------- MPD announces state update: player Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::getState Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand status Jun 15 18:04:05 volumio volumio[835]: info: Checking Spotify Web API Jun 15 18:04:05 volumio volumio[835]: [SpotifyConnect] Vollibrespot Active Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 18:04:05 volumio volumio[835]: info: [1750010645479] ControllerSpotify::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:05 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: info: Pushing Favourites {"service":"spop","uri":"","favourite":false} Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::parseState Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::parseTrackInfo Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:05 volumio volumio[835]: info: CorePlayQueue::getTrack 10 Jun 15 18:04:05 volumio volumio[835]: info: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Письмо Шопену","artist":null,"album":"Анна Герман - ИЗБРАННОЕ","uri":"NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.flac","trackType":"flac"} Jun 15 18:04:05 volumio volumio[835]: info: CURRENT POSITION 10 Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::syncState stateService stop Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:04:05 volumio volumio[835]: info: CURRENT POSITION 10 Jun 15 18:04:05 volumio volumio[835]: info: CURRENT POSITION 11 Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::play index undefined Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:05 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:05 volumio volumio[835]: info: CorePlayQueue::getTrack 11 Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Jun 15 18:04:05 volumio volumio[835]: info: CorePlayQueue::getTrack 11 Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand stop Jun 15 18:04:05 volumio volumio[835]: info: ------------------------------ 71ms Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:05 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:05 volumio volumio[835]: info: Pushing Favourites {"service":"spop","uri":"","favourite":false} Jun 15 18:04:05 volumio volumio[835]: info: Pushing Favourites {"service":"spop","uri":"","favourite":false} Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand clear Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: info: Jun 15 18:04:05 volumio volumio[835]: ---------------------------- MPD announces system playlist update Jun 15 18:04:05 volumio volumio[835]: info: Ignoring MPD Status Update Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand load "NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.cue" Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: info: Jun 15 18:04:05 volumio volumio[835]: info: ------------------------------ 30ms Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Reducing normalisation factor to prevent clipping. Please add negative pregain to avoid. Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand play Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: info: Jun 15 18:04:05 volumio volumio[835]: ---------------------------- MPD announces system playlist update Jun 15 18:04:05 volumio volumio[835]: info: Ignoring MPD Status Update Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ------------------------------ 3ms Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Event: Volume { volume_to_mixer: 51773 } Jun 15 18:04:05 volumio volumio[835]: info: Jun 15 18:04:05 volumio volumio[835]: ---------------------------- MPD announces state update: mixer Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::getState Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand status Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: [SpotifyConnect] Volume Spotify: 79.00053406576639 Volumio: 79 Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::parseState Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:04:05 volumio volumio[835]: info: sending command... Jun 15 18:04:05 volumio volumio[835]: info: parsing response... Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Event: Volume { volume_to_mixer: 51773 } Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::parseTrackInfo Jun 15 18:04:05 volumio volumio[835]: info: ControllerMpd::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:05 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:05 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:05 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:05 volumio volumio[835]: info: ------------------------------ 39ms Jun 15 18:04:05 volumio volumio[835]: info: Pushing Favourites {"uri":"NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.flac","favourite":false} Jun 15 18:04:05 volumio volumio[994]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:46mTPMluccHCYpCd8fy4th"> Jun 15 18:04:05 volumio volumio[835]: [SpotifyConnect] Volume Spotify: 79.00053406576639 Volumio: 79 Jun 15 18:04:06 volumio volumio[835]: info: Jun 15 18:04:06 volumio volumio[835]: ---------------------------- MPD announces state update: player Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::getState Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::sendMpdCommand status Jun 15 18:04:06 volumio volumio[835]: info: sending command... Jun 15 18:04:06 volumio volumio[835]: info: Jun 15 18:04:06 volumio volumio[835]: ---------------------------- MPD announces state update: player Jun 15 18:04:06 volumio volumio[835]: info: parsing response... Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::getState Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::sendMpdCommand status Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::parseState Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:04:06 volumio volumio[835]: info: sending command... Jun 15 18:04:06 volumio volumio[835]: info: sending command... Jun 15 18:04:06 volumio volumio[835]: info: parsing response... Jun 15 18:04:06 volumio volumio[835]: info: parsing response... Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::parseState Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::parseTrackInfo Jun 15 18:04:06 volumio volumio[835]: info: sending command... Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::pushState Jun 15 18:04:06 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:06 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:06 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:06 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:06 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:06 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:06 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:06 volumio volumio[835]: info: ------------------------------ 46ms Jun 15 18:04:06 volumio volumio[835]: info: Pushing Favourites {"uri":"NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.flac","favourite":false} Jun 15 18:04:06 volumio volumio[835]: info: parsing response... Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::parseTrackInfo Jun 15 18:04:06 volumio volumio[835]: info: ControllerMpd::pushState Jun 15 18:04:06 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:06 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:06 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:06 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:06 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:06 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:06 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:06 volumio volumio[835]: info: ------------------------------ 69ms Jun 15 18:04:06 volumio volumio[835]: info: Pushing Favourites {"uri":"NAS/Musik/Анна Герман - Избранное/Анна Герман - ИЗБРАННОЕ.flac","favourite":false} Jun 15 18:04:06 volumio volumio[994]: [Vollibrespot] : (137933 ms) loaded Jun 15 18:04:06 volumio volumio[994]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 38796052377147609808483117742330187191, audio_type: Track } } Jun 15 18:04:06 volumio volumio[835]: [SpotifyConnect] Device palyback is inactive Jun 15 18:04:06 volumio avahi-daemon[543]: Invalid response packet from host 192.168.0.245. Jun 15 18:04:08 volumio avahi-daemon[543]: Invalid response packet from host 192.168.0.245. Jun 15 18:04:10 volumio avahi-daemon[543]: Invalid response packet from host 192.168.0.245. Jun 15 18:04:19 volumio volumio[994]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 38796052377147609808483117742330187191, audio_type: Track }, position_ms: 50457 } Jun 15 18:04:19 volumio volumio[835]: [SpotifyConnect] play Jun 15 18:04:20 volumio volumio[994]: [Vollibrespot] : Event: SinkActive Jun 15 18:04:20 volumio volumio[835]: [SpotifyConnect] Por Una Cabeza Jun 15 18:04:20 volumio volumio[835]: [SpotifyConnect] Sink acquired Jun 15 18:04:20 volumio volumio[835]: info: Continuing Spotify Session Jun 15 18:04:20 volumio volumio[835]: info: Checking Spotify Web API Jun 15 18:04:20 volumio volumio[835]: info: Is Connect Playback Jun 15 18:04:20 volumio volumio[835]: info: [1750010660766] ControllerSpotify::pushState Jun 15 18:04:20 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:20 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:20 volumio volumio[835]: info: CoreStateMachine::pushState Jun 15 18:04:20 volumio volumio[835]: info: CoreStateMachine::getState Jun 15 18:04:20 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Jun 15 18:04:20 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:04:20 volumio volumio[835]: info: interfaceApi::pushState Jun 15 18:04:20 volumio volumio[835]: info: Pushing Favourites {"service":"spop","uri":"spotify:track:0T4DVFjijq90G7DJYGjUY7","favourite":false} Jun 15 18:04:23 volumio volumio[835]: info: CoreCommandRouter::volumioPause Jun 15 18:04:23 volumio volumio[835]: info: CoreStateMachine::pause Jun 15 18:04:23 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Jun 15 18:04:23 volumio volumio[835]: info: CoreStateMachine::servicePause Jun 15 18:04:23 volumio volumio[835]: info: CoreCommandRouter::servicePause Jun 15 18:04:23 volumio volumio[835]: info: Spotify Received pause Jun 15 18:04:23 volumio volumio[835]: info: [1750010663396] ControllerSpotify::pushState Jun 15 18:04:23 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:23 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:23 volumio volumio[835]: info: CorePlayQueue::getTrack 11 Jun 15 18:04:23 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop Jun 15 18:04:23 volumio volumio[994]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 38796052377147609808483117742330187191, audio_type: Track }, position_ms: 54117 } Jun 15 18:04:23 volumio volumio[835]: [SpotifyConnect] pause Jun 15 18:04:23 volumio volumio[835]: [SpotifyConnect] Por Una Cabeza Jun 15 18:04:23 volumio volumio[835]: info: [1750010663715] ControllerSpotify::pushState Jun 15 18:04:23 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:23 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:23 volumio volumio[835]: info: CorePlayQueue::getTrack 11 Jun 15 18:04:23 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop Jun 15 18:04:23 volumio volumio[994]: [Vollibrespot] : Event: SinkInactive Jun 15 18:04:23 volumio volumio[994]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 38796052377147609808483117742330187191, audio_type: Track } } Jun 15 18:04:23 volumio volumio[835]: [SpotifyConnect] Sink released Jun 15 18:04:23 volumio volumio[835]: info: [1750010663930] ControllerSpotify::pushState Jun 15 18:04:23 volumio volumio[835]: info: CoreCommandRouter::servicePushState Jun 15 18:04:23 volumio volumio[835]: info: CoreStateMachine::syncState Jun 15 18:04:23 volumio volumio[835]: info: CorePlayQueue::getTrack 11 Jun 15 18:04:23 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop Jun 15 18:04:23 volumio volumio[835]: [SpotifyConnect] Device palyback is inactive Jun 15 18:05:00 volumio volumio[835]: info: CoreCommandRouter::volumioPause Jun 15 18:05:00 volumio volumio[835]: info: CoreStateMachine::pause Jun 15 18:05:14 volumio volumio[835]: info: CoreCommandRouter::volumioPause Jun 15 18:05:14 volumio volumio[835]: info: CoreStateMachine::pause Jun 15 18:05:27 volumio volumio[835]: info: CoreCommandRouter::volumioPrevious Jun 15 18:05:27 volumio volumio[835]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 18:05:27 volumio volumio[835]: TypeError: Cannot read property 'previous' of undefined Jun 15 18:05:27 volumio volumio[835]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1329:18) Jun 15 18:05:27 volumio volumio[835]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:106:27) Jun 15 18:05:27 volumio volumio[835]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:238:31) Jun 15 18:05:27 volumio volumio[835]: at emitTwo (events.js:126:13) Jun 15 18:05:27 volumio volumio[835]: at Socket.emit (events.js:214:7) Jun 15 18:05:27 volumio volumio[835]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 15 18:05:27 volumio volumio[835]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jun 15 18:05:27 volumio volumio[835]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 15 18:05:27 volumio volumio[835]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 18:05:28 volumio sudo[4201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 18:04 Jun 15 18:05:28 volumio sudo[4201]: 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="d0d14b37ffe8e45861e4e0b9816241fbafb4eb91" VOLUMIO_FE_VERSION="81c7100e9c7342b16fc9e0ca5908a26771e08c4f" VOLUMIO_BE_VERSION="645f0e55945bdd625a662a1ee21ba0dfb11bd73e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jun 21 01:30:10 CEST 2018" VOLUMIO_VERSION="2.413" VOLUMIO_HARDWARE="pi"