Oct 15 12:57:00 torre-onkyo mpd[2146115]: 2026-10-15T12:57:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 12:57:00 torre-onkyo systemd[1]: Started mpd.service - Music Player Daemon. Oct 15 12:57:00 torre-onkyo sudo[2146058]: pam_unix(sudo:session): session closed for user root Oct 15 12:57:00 torre-onkyo sudo[2146065]: pam_unix(sudo:session): session closed for user root Oct 15 12:57:00 torre-onkyo volumio[1317]: error: MPD error: The expression evaluated to a falsy value: Oct 15 12:57:00 torre-onkyo volumio[1317]: assert.ok(self.idling) Oct 15 12:57:00 torre-onkyo volumio[1317]: error: The expression evaluated to a falsy value: Oct 15 12:57:00 torre-onkyo volumio[1317]: assert.ok(self.idling) Oct 15 12:57:00 torre-onkyo volumio[1317]: error: updateQueue error: null Oct 15 12:57:03 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 15 12:57:03 torre-onkyo volumio[1317]: info: CoreStateMachine::getcurrentVolume Oct 15 12:57:03 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 12:57:03 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:03 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:03 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 12:57:03 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:03 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:03.638-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_PAUSED positionMs=31950 volume=100 Oct 15 12:57:03 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:03.639-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:07 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPlay Oct 15 12:57:07 torre-onkyo volumio[1317]: info: CoreStateMachine::play index undefined Oct 15 12:57:07 torre-onkyo volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 12:57:07 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:07 torre-onkyo volumio[1317]: info: CoreStateMachine::startPlaybackTimer Oct 15 12:57:07 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:07 torre-onkyo volumio[1317]: info: ControllerMpd::resume Oct 15 12:57:07 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand play Oct 15 12:57:07 torre-onkyo volumio[1317]: info: sendMpdCommand play took 1 milliseconds Oct 15 12:57:10 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPlay Oct 15 12:57:10 torre-onkyo volumio[1317]: info: CoreStateMachine::play index undefined Oct 15 12:57:10 torre-onkyo volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 12:57:10 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:10 torre-onkyo volumio[1317]: info: CoreStateMachine::startPlaybackTimer Oct 15 12:57:10 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:10 torre-onkyo volumio[1317]: info: ControllerMpd::resume Oct 15 12:57:10 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand play Oct 15 12:57:10 torre-onkyo volumio[1317]: info: sendMpdCommand play took 0 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Preload queue cleared Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::ClearQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::stop Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::updateTrackBlock Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrackBlock Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::stPlaybackTimer Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::serviceStop Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::serviceStop Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::stop Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::clearPlayQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::saveQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::addQueueItems Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::addQueueItems Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Preload queue cleared Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/01. Incubus - Redefine.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/01. Incubus - Redefine.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/02. Incubus - Vitamin.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/02. Incubus - Vitamin.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/03. Incubus - New Skin.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/03. Incubus - New Skin.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/04. Incubus - Idiot Box.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/04. Incubus - Idiot Box.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/05. Incubus - Glass.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/05. Incubus - Glass.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/06. Incubus - Magic Medicine.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/06. Incubus - Magic Medicine.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/07. Incubus - A Certain Shade of Green.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/07. Incubus - A Certain Shade of Green.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/08. Incubus - Favorite Things.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/08. Incubus - Favorite Things.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.077-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.077-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::saveQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::updateTrackBlock Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrackBlock Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPlay Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::play index 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::addQueueItems Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::addQueueItems Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Preload queue cleared Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/10. Incubus - Nebula.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/10. Incubus - Nebula.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/11. Incubus - Deep Inside.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/11. Incubus - Deep Inside.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/12. Incubus - Calgone.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/12. Incubus - Calgone.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Adding Item to queue: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/13. Incubus - Segue 1.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Using cached record of: music-library/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/13. Incubus - Segue 1.flac Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::stop Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::saveQueue Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::play index undefined Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::updateTrackBlock Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrackBlock Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::startPlaybackTimer Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand stop took 9 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand stop took 1 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces system playlist update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Ignoring MPD Status Update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand clear took 1 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" Oct 15 12:57:14 torre-onkyo volumio[1317]: error: updateQueue error: null Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces system playlist update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Ignoring MPD Status Update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 1ms Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand add "INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" took 0 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces system playlist update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Ignoring MPD Status Update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 1ms Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand play took 1 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 0ms Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces system playlist update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Ignoring MPD Status Update Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 0ms Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces state update: player Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::getState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand status Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces state update: player Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::getState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand status Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand status took 28 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces state update: player Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::getState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand status Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Oct 15 12:57:14 torre-onkyo volumio[1317]: ---------------------------- MPD announces state update: player Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::getState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand status Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand status took 28 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseTrackInfo Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::servicePushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"673 Kbps","isStreaming":false,"title":"Summer Romance (Anti-Gravity Love Song)","artist":"Incubus","album":"S.C.I.E.N.C.E.","uri":"INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac","trackType":"flac"} Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: CURRENT POSITION 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState stateService play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState currentStatus stop Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 31ms Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand status took 1 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand status took 1 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseState Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseTrackInfo Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::servicePushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"Summer Romance (Anti-Gravity Love Song)","artist":"Incubus","album":"S.C.I.E.N.C.E.","uri":"INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac","trackType":"flac"} Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: CURRENT POSITION 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState stateService play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState currentStatus play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Received an update from plugin. extracting info from payload Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.120-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_PLAYING positionMs=456 volume=100 Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.120-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_PLAYING positionMs=456 volume=100 Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.121-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.121-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 33ms Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseTrackInfo Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: ControllerMpd::parseTrackInfo Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::servicePushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"679 Kbps","isStreaming":false,"title":"Summer Romance (Anti-Gravity Love Song)","artist":"Incubus","album":"S.C.I.E.N.C.E.","uri":"INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac","trackType":"flac"} Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: CURRENT POSITION 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState stateService play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState currentStatus play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Received an update from plugin. extracting info from payload Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ControllerMpd::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::servicePushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"679 Kbps","isStreaming":false,"title":"Summer Romance (Anti-Gravity Love Song)","artist":"Incubus","album":"S.C.I.E.N.C.E.","uri":"INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac","trackType":"flac"} Oct 15 12:57:14 torre-onkyo volumio[1317]: verbose: CURRENT POSITION 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState stateService play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::syncState currentStatus play Oct 15 12:57:14 torre-onkyo volumio[1317]: info: Received an update from plugin. extracting info from payload Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreStateMachine::pushState Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:57:14 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioPushState Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.126-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_PLAYING positionMs=844 volume=100 Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.126-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_PLAYING positionMs=844 volume=100 Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.126-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_PLAYING positionMs=844 volume=100 Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.126-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.127-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.127-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.127-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" state=STATUS_PLAYING positionMs=844 volume=100 Oct 15 12:57:14 torre-onkyo volumio5-onboarding[1828]: time=2026-10-15T12:57:14.127-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.58:42280,00:00:00:00:00:00%03 @ 0xc0005d64b0" id="mnt/INTERNAL/Musica/Incubus - S.C.I.E.N.C.E. (1997)/09. Incubus - Summer Romance (Anti-Gravity Love Song).flac" title="Summer Romance (Anti-Gravity Love Song)" Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 12ms Oct 15 12:57:14 torre-onkyo volumio[1317]: info: ------------------------------ 12ms Oct 15 12:58:06 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 12:58:06 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 12:58:11 torre-onkyo volumio[1317]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 12:58:29 torre-onkyo volumio[1317]: info: Received OAUTH Data Oct 15 12:58:29 torre-onkyo volumio[1317]: info: Executing Spotify Oauth Login Oct 15 12:58:29 torre-onkyo volumio[1317]: info: Saving Spotify Refresh Token Oct 15 12:58:29 torre-onkyo sudo[2146352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 12:58:29 torre-onkyo sudo[2146352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 12:58:29 torre-onkyo sudo[2146352]: pam_unix(sudo:session): session closed for user root Oct 15 12:58:29 torre-onkyo sudo[2146354]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 12:58:29 torre-onkyo sudo[2146354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 12:58:29 torre-onkyo sudo[2146354]: pam_unix(sudo:session): session closed for user root Oct 15 12:58:29 torre-onkyo volumio[1317]: verbose: New Socket.io Connection to 192.168.100.6 from 192.168.100.73 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 15 12:58:29 torre-onkyo volumio[1317]: info: New Spotify access tokenBQBbJOjgIl... Oct 15 12:58:29 torre-onkyo volumio[1317]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 15 12:58:29 torre-onkyo volumio[1317]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioGetState Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 12:58:30 torre-onkyo volumio[1317]: SPOTIFY: User informations: {"account_id":"seCIRBZwZq","country":"CL","display_name":"Alejandro Astudillo","email":"alejandroastudillo@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/12133742025"},"followers":{"href":null,"total":12},"href":"https://api.spotify.com/v1/users/12133742025","id":"12133742025","images":[{"height":300,"url":"https://scontent-cdg4-2.xx.fbcdn.net/v/t1.6435-1/58378788_10219516943933856_5139842244215308288_n.jpg?stp=dst-jpg_s240x240_tt6&_nc_cat=100&ccb=1-7&_nc_sid=08baa4&_nc_ohc=BzDr6FUav-YQ7kNvwEgBovs&_nc_oc=AdryCTOjwwK21t9PAgQivtUwE8pETQEAPx82h9mBFigPV0ynn9SeS8ObFciWYN_n84mKmCkmXeZuwQXg3s_bh1Uu&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=EIdYRkJy4GLqjye0lTv0Ng&_nc_tpa=Q5bMBQIeSTeq4i9ES0sK0htQepNgj6MJOH0YIZsnPyia5gKWi0IFLwfAoVJ35xbpaZFYjOYiXx1k&oh=00_AQOrRoWk4OMwXZf_Q-D7GB8OaTiK8al1UuiFLjrBtzj41Q&oe=6AF8575E","width":300},{"height":64,"url":"https://scontent-cdg4-2.xx.fbcdn.net/v/t1.6435-1/58378788_10219516943933856_5139842244215308288_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=100&ccb=1-7&_nc_sid=28885b&_nc_ohc=BzDr6FUav-YQ7kNvwEgBovs&_nc_oc=AdryCTOjwwK21t9PAgQivtUwE8pETQEAPx82h9mBFigPV0ynn9SeS8ObFciWYN_n84mKmCkmXeZuwQXg3s_bh1Uu&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=EIdYRkJy4GLqjye0lTv0Ng&_nc_tpa=Q5bMBQK6fr_8VakcHtWgeOsHJhZImPl3hKFncWIMEY1c2-XZyjvUWW2_A0Pe0w7VLAAl-njXlykj&oh=00_AQONClJ0Y7sGYpOebFcGsNint25Lkty8EQ6w7w5LeG21qw&oe=6AF8575E","width":64}],"product":"premium","type":"user","uri":"spotify:user:12133742025"} Oct 15 12:58:30 torre-onkyo volumio[1317]: info: Creating Spotify config file Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 12:58:30 torre-onkyo volumio[1317]: info: Spotify config file written Oct 15 12:58:30 torre-onkyo sudo[2146360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 15 12:58:30 torre-onkyo sudo[2146360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 12:58:30 torre-onkyo systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Oct 15 12:58:30 torre-onkyo systemd[1]: go-librespot-daemon.service: Killing process 1179300 (go-librespot) with signal SIGKILL. Oct 15 12:58:30 torre-onkyo systemd[1]: go-librespot-daemon.service: Deactivated successfully. Oct 15 12:58:30 torre-onkyo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 12:58:30 torre-onkyo systemd[1]: go-librespot-daemon.service: Consumed 57.501s CPU time. Oct 15 12:58:30 torre-onkyo volumio[1317]: info: Connection to go-librespot Websocket closed Oct 15 12:58:30 torre-onkyo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 12:58:30 torre-onkyo sudo[2146360]: pam_unix(sudo:session): session closed for user root Oct 15 12:58:30 torre-onkyo go-librespot[2146362]: go-librespot daemon starting... Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=info msg="running go-librespot 0.7.1" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="app state loaded" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="stored credentials not found" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=info msg="zeroconf server listening on port 46723" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 15 12:58:30 torre-onkyo volumio[1317]: info: New Spotify access tokenBQDfRLVtEx... Oct 15 12:58:30 torre-onkyo volumio[1317]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="obtained new client token: AAEZOyAJgMTbl9s8vAMK4vdoO3kPXZYyIP1LPvlBsi0hG2mhuzh8dlGNGCsJekfrEx1D8g3JxYNGf2Zl8HPZLps9SD1emDhMFP/XkaU7oJMv+ZyFdFXil/s5mfgTLGGWiFSviqYUWgpimSl+RZt7xogkU+0QuTvY6/RWdoauvw6EnqPiDHhhxsThZM1QCkUuVLI34lFfdO8DBT1pe9OG3vHdgm6igsvj1qWiqFIOek3zxYfOmE7ufuHR" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Oct 15 12:58:30 torre-onkyo volumio[1317]: SPOTIFY: User informations: {"account_id":"seCIRBZwZq","country":"CL","display_name":"Alejandro Astudillo","email":"alejandroastudillo@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/12133742025"},"followers":{"href":null,"total":12},"href":"https://api.spotify.com/v1/users/12133742025","id":"12133742025","images":[{"height":300,"url":"https://scontent-cdg4-2.xx.fbcdn.net/v/t1.6435-1/58378788_10219516943933856_5139842244215308288_n.jpg?stp=dst-jpg_s240x240_tt6&_nc_cat=100&ccb=1-7&_nc_sid=08baa4&_nc_ohc=BzDr6FUav-YQ7kNvwFVO5Hm&_nc_oc=AdokEVrptXysNjVAfp4Rdk5htQQgzrrR_NNq3gWOYhpbRmxSDSdwgSQjSoCwtAGdjlNW21Iwz1n37A-wNRQJlrPC&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=vX0qLXtdDxLsNpfCU067KQ&_nc_tpa=Q5bMBQK2MWrRv7s-NIhZqLf-RdS2sMlmhI6zrg-JALo4dBHjnNktKf61yvSJ-3udCNS6fKkyOYeq&oh=00_AQPPFyES7fAuBfHAH639jZkPhEhhvbRcmWmbsLlP3g71Jw&oe=6AF8575E","width":300},{"height":64,"url":"https://scontent-cdg4-2.xx.fbcdn.net/v/t1.6435-1/58378788_10219516943933856_5139842244215308288_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=100&ccb=1-7&_nc_sid=28885b&_nc_ohc=BzDr6FUav-YQ7kNvwFVO5Hm&_nc_oc=AdokEVrptXysNjVAfp4Rdk5htQQgzrrR_NNq3gWOYhpbRmxSDSdwgSQjSoCwtAGdjlNW21Iwz1n37A-wNRQJlrPC&_nc_zt=24&_nc_ht=scontent-cdg4-2.xx&edm=AP4hL3IEAAAA&_nc_gid=vX0qLXtdDxLsNpfCU067KQ&_nc_tpa=Q5bMBQI_ei8kYBIxs_wZIwJRDjRfDVrnhPmFt25BK_QJgkyAzIWjCzakKXurO7EfDIx4wQANiEUi&oh=00_AQN7cD4uR1i3LX9nvqZ2uzOQoKdBQ7laBNHeGFLNZ9Wy0Q&oe=6AF8575E","width":64}],"product":"premium","type":"user","uri":"spotify:user:12133742025"} Oct 15 12:58:30 torre-onkyo volumio[1317]: info: Spotify Successfully logged in Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 12:58:30 torre-onkyo volumio[1317]: info: [1792079910954] CoreMusicLibrary::Adding element Spotify Oct 15 12:58:30 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 12:58:30 torre-onkyo volumio[1317]: Cannot find translation for source Spotify Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="completed keyexchange" Oct 15 12:58:30 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:30-03:00" level=debug msg="completed challenge" Oct 15 12:58:31 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:31-03:00" level=info msg="authenticated AP" username="12*******25" Oct 15 12:58:31 torre-onkyo go-librespot[2146363]: time="2026-10-15T12:58:31-03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 12:58:31 torre-onkyo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 12:58:31 torre-onkyo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 12:58:32 torre-onkyo volumio[1317]: info: Received Get System Info Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 12:58:32 torre-onkyo volumio[1317]: info: Discovery: Getting this device information Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioGetState Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 12:58:32 torre-onkyo volumio[1317]: info: Received Get System Info Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 12:58:32 torre-onkyo volumio[1317]: info: Discovery: Getting this device information Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::volumioGetState Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CorePlayQueue::getTrack 8 Oct 15 12:58:32 torre-onkyo volumio[1317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 12:58:33 torre-onkyo volumio[1317]: info: Initializing connection to go-librespot Websocket Oct 15 12:58:33 torre-onkyo volumio[1317]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 12:58:33 torre-onkyo volumio[1317]: info: go-librespot daemon successfully initialized Oct 15 12:58:34 torre-onkyo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 15 12:58:34 torre-onkyo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 12:58:34 torre-onkyo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 12:58:34 torre-onkyo go-librespot[2146378]: go-librespot daemon starting... Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=info msg="running go-librespot 0.7.1" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=debug msg="app state loaded" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=debug msg="stored credentials not found" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=info msg="zeroconf server listening on port 38843" Oct 15 12:58:34 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:34-03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 15 12:58:35 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:35-03:00" level=debug msg="obtained new client token: AAHfc1iJhFyrixLKZ9WiRfJWnlpEPQ9tOgLA1kO1EyZbUFurnnntIXSCGGIGZorBSM3N60wFk2oc2wJCFIAySBV7v6G6tQWSLjHEWNPx0elXRY222iw+MAIj+vts0qRE/87tJ2Zh6su7u+a/Rr2tOPXoD7OLmYNSq+hcNRcygHOt3pES8xY+1nLLwe+HQX4fYJVwCoqhIKTALTxFp8u65buMJVw0UeH8WyQo/61vgZ8k/O+FLkVhs1LI" Oct 15 12:58:35 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:35-03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Oct 15 12:58:35 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:35-03:00" level=debug msg="completed keyexchange" Oct 15 12:58:35 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:35-03:00" level=debug msg="completed challenge" Oct 15 12:58:35 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:35-03:00" level=info msg="authenticated AP" username="12*******25" Oct 15 12:58:35 torre-onkyo go-librespot[2146379]: time="2026-10-15T12:58:35-03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 12:58:35 torre-onkyo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 12:58:35 torre-onkyo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 12:58:36 torre-onkyo volumio[1317]: info: Initializing connection to go-librespot Websocket Oct 15 12:58:36 torre-onkyo volumio[1317]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 12:58:36 torre-onkyo volumio[1317]: info: Initializing connection to go-librespot Websocket Oct 15 12:58:36 torre-onkyo volumio[1317]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 12:58:38 torre-onkyo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 15 12:58:38 torre-onkyo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 12:58:38 torre-onkyo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 12:58:38 torre-onkyo go-librespot[2146407]: go-librespot daemon starting... Oct 15 12:58:38 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:38-03:00" level=info msg="running go-librespot 0.7.1" Oct 15 12:58:38 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:38-03:00" level=debug msg="app state loaded" Oct 15 12:58:38 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:38-03:00" level=debug msg="stored credentials not found" Oct 15 12:58:38 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:38-03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=info msg="zeroconf server listening on port 38333" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 15 12:58:39 torre-onkyo volumio[1317]: info: Initializing connection to go-librespot Websocket Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="new websocket client" Oct 15 12:58:39 torre-onkyo volumio[1317]: info: Connection to go-librespot Websocket established Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="obtained new client token: AAGcW9qaZeQGtpTGSdOYteQm/SXLbU81bx8oberOoJEKMc6ZpvA8oTHg6Ig8tbQMq/Oo0s03/83r/50ssbnuh2MvU7VaOytoZWv0aWvEL8WL2rjLlVqJ2Hrt8oUQ1DdDhfUp+SBLOFD+HbxA4EG1zSYjjXakxa7XIhIQUxra9+wYoKVhpsLcleTdIyFVbO2gmcsbLn6wdNhqJNakJQOTF/4RsAuwWxQl3ZlbHgQldAAZY4MjaTsntw==" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="completed keyexchange" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=debug msg="completed challenge" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=info msg="authenticated AP" username="12*******25" Oct 15 12:58:39 torre-onkyo go-librespot[2146408]: time="2026-10-15T12:58:39-03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 12:58:39 torre-onkyo volumio[1317]: info: Connection to go-librespot Websocket closed Oct 15 12:58:39 torre-onkyo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 12:58:39 torre-onkyo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 12:58:42 torre-onkyo volumio[1317]: info: Getting Spotify volume Oct 15 12:58:42 torre-onkyo volumio[1317]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 12:58:42 torre-onkyo volumio[1317]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 12:58:42 torre-onkyo volumio[1317]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 15 12:58:42 torre-onkyo volumio[1317]: errno: -111, Oct 15 12:58:42 torre-onkyo volumio[1317]: code: 'ECONNREFUSED', Oct 15 12:58:42 torre-onkyo volumio[1317]: syscall: 'connect', Oct 15 12:58:42 torre-onkyo volumio[1317]: address: '127.0.0.1', Oct 15 12:58:42 torre-onkyo volumio[1317]: port: 9879, Oct 15 12:58:42 torre-onkyo volumio[1317]: response: undefined Oct 15 12:58:42 torre-onkyo volumio[1317]: } Oct 15 12:58:42 torre-onkyo volumio[1317]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 12:58:42 torre-onkyo sudo[2146435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 12:57' Oct 15 12:58:42 torre-onkyo sudo[2146435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"