Jan 12 16:00:01 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:00:05 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:00:07 volumio go-librespot[2893]: time="2026-01-12T16:00:07Z" level=trace msg="sent dealer ping" Jan 12 16:00:07 volumio go-librespot[2893]: time="2026-01-12T16:00:07Z" level=trace msg="received dealer pong" Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jan 12 16:00:09 volumio volumio[30849]: info: Share DS successfully unmounted Jan 12 16:00:09 volumio sudo[7425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.154:/volume1/homes /mnt/NAS/DS Jan 12 16:00:09 volumio sudo[7425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:00:09 volumio nfsrahead[7442]: setting /mnt/NAS/DS readahead to 128 Jan 12 16:00:09 volumio sudo[7425]: pam_unix(sudo:session): session closed for user root Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:00:09 volumio volumio[30849]: info: Jan 12 16:00:09 volumio volumio[30849]: ---------------------------- MPD announces state update: update Jan 12 16:00:09 volumio volumio[30849]: info: ControllerMpd::getState Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:00:09 volumio volumio[30849]: info: Jan 12 16:00:09 volumio volumio[30849]: ---------------------------- MPD announces state update: update Jan 12 16:00:09 volumio volumio[30849]: info: ControllerMpd::getState Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:00:09 volumio volumio[30849]: info: Jan 12 16:00:09 volumio volumio[30849]: ---------------------------- MPD announces state update: update Jan 12 16:00:09 volumio volumio[30849]: info: ControllerMpd::getState Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:00:09 volumio volumio[30849]: info: sendMpdCommand status took 11 milliseconds Jan 12 16:00:09 volumio volumio[30849]: info: sendMpdCommand status took 8 milliseconds Jan 12 16:00:09 volumio volumio[30849]: info: sendMpdCommand status took 8 milliseconds Jan 12 16:00:09 volumio volumio[30849]: info: sendMpdCommand status took 7 milliseconds Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::parseState Jan 12 16:00:09 volumio volumio[30849]: info: Command Router : Notfying DB Updatetrue Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::parseState Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::parseState Jan 12 16:00:09 volumio volumio[30849]: info: Command Router : Notfying DB Updatetrue Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::Close All Modals sent Jan 12 16:00:09 volumio volumio[30849]: verbose: ControllerMpd::parseState Jan 12 16:00:09 volumio volumio[30849]: info: ControllerMpd::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::servicePushState Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 16:00:09 volumio volumio[30849]: verbose: CURRENT POSITION 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::syncState stateService stop Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::syncState currentStatus stop Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:09 volumio volumio[30849]: info: No code Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:09 volumio volumio[30849]: info: ControllerMpd::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::servicePushState Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 16:00:09 volumio volumio[30849]: verbose: CURRENT POSITION 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::syncState stateService stop Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::syncState currentStatus stop Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:09 volumio volumio[30849]: info: No code Jan 12 16:00:09 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:09 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:09 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:10 volumio volumio[30849]: info: ------------------------------ 176ms Jan 12 16:00:10 volumio volumio[30849]: info: ------------------------------ 173ms Jan 12 16:00:10 volumio volumio[30849]: info: sendMpdCommand status took 173 milliseconds Jan 12 16:00:10 volumio volumio[30849]: info: sendMpdCommand status took 172 milliseconds Jan 12 16:00:10 volumio volumio[30849]: verbose: ControllerMpd::parseState Jan 12 16:00:10 volumio volumio[30849]: info: Command Router : Notfying DB Updatetrue Jan 12 16:00:10 volumio volumio[30849]: info: CoreCommandRouter::Close All Modals sent Jan 12 16:00:10 volumio volumio[30849]: verbose: ControllerMpd::parseState Jan 12 16:00:10 volumio volumio[30849]: info: ControllerMpd::pushState Jan 12 16:00:10 volumio volumio[30849]: info: CoreCommandRouter::servicePushState Jan 12 16:00:10 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:10 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:10 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:10 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:10 volumio volumio[30849]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 16:00:10 volumio volumio[30849]: verbose: CURRENT POSITION 0 Jan 12 16:00:10 volumio volumio[30849]: info: CoreStateMachine::syncState stateService stop Jan 12 16:00:10 volumio volumio[30849]: info: CoreStateMachine::syncState currentStatus stop Jan 12 16:00:10 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:10 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:10 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:10 volumio volumio[30849]: info: No code Jan 12 16:00:10 volumio volumio[30849]: info: CoreStateMachine::pushState Jan 12 16:00:10 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:00:10 volumio volumio[30849]: info: CoreCommandRouter::volumioPushState Jan 12 16:00:10 volumio volumio[30849]: info: ------------------------------ 237ms Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 12 16:00:10 volumio volumio[30849]: info: [1768233610141] Database update started Jan 12 16:00:10 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 12 16:00:13 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:00:17 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:00:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:00:36 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 16:00:36 volumio volumio[30849]: info: CURURI: music-library Jan 12 16:00:36 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:37 volumio go-librespot[2893]: time="2026-01-12T16:00:37Z" level=trace msg="sent dealer ping" Jan 12 16:00:37 volumio go-librespot[2893]: time="2026-01-12T16:00:37Z" level=trace msg="received dealer pong" Jan 12 16:00:39 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 16:00:39 volumio volumio[30849]: info: CURURI: music-library/NAS Jan 12 16:00:39 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:40 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 16:00:40 volumio volumio[30849]: info: CURURI: music-library/NAS/DS Jan 12 16:00:40 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:42 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 16:00:42 volumio volumio[30849]: info: CURURI: music-library/NAS/DS/5harts Jan 12 16:00:42 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:43 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 16:00:43 volumio volumio[30849]: info: CURURI: music-library/NAS/DS/5harts/Malcolm Music Jan 12 16:00:43 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:46 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 16:00:46 volumio volumio[30849]: info: CURURI: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees Jan 12 16:00:46 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:47 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 16:00:47 volumio volumio[30849]: info: CURURI: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1) Jan 12 16:00:47 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:49 volumio go-librespot[2893]: time="2026-01-12T16:00:49Z" level=trace msg="received accesspoint ping" Jan 12 16:00:50 volumio go-librespot[2893]: time="2026-01-12T16:00:50Z" level=trace msg="received accesspoint pong ack" Jan 12 16:00:50 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:50 volumio volumio[30849]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::ClearQueue Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::stop Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::clearPlayQueue Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::saveQueue Jan 12 16:00:50 volumio volumio[30849]: info: CoreCommandRouter::volumioPushQueue Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::addQueueItems Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::addQueueItems Jan 12 16:00:50 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/01 - New York Mining Disaster 1941.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/01 - New York Mining Disaster 1941.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/02 - To Love Somebody.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/02 - To Love Somebody.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: CoreCommandRouter::volumioPushQueue Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::saveQueue Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::updateTrackBlock Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::getTrackBlock Jan 12 16:00:50 volumio volumio[30849]: info: CoreCommandRouter::volumioPlay Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::play index 1 Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::addQueueItems Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::addQueueItems Jan 12 16:00:50 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/03 - Holiday.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/03 - Holiday.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/04 - Massachusets.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/04 - Massachusets.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/05 - World.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/05 - World.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/06 - Words.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/06 - Words.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/07 - Jumbo.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/07 - Jumbo.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/08 - I've Gotta Get A Message To You.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/08 - I've Gotta Get A Message To You.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/09 - I Started A Joke.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/09 - I Started A Joke.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/10 - First Of May.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/10 - First Of May.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/11 - Saved By The Bell.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/11 - Saved By The Bell.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/12 - Don't Forget To Remember.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/12 - Don't Forget To Remember.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/13 - Lonely Days.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/13 - Lonely Days.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/14 - How Can You Mend A Broken Heart.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/14 - How Can You Mend A Broken Heart.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/15 - My World.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/15 - My World.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/16 - Run To Me.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/16 - Run To Me.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/17 - Jive Talkin'.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/17 - Jive Talkin'.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/18 - Nights On Broadway.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/18 - Nights On Broadway.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/19 - Fanny (Be Tender With My Love).mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/19 - Fanny (Be Tender With My Love).mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/20 - Love So Right.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/20 - Love So Right.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/21 - If I Can't Have You.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/21 - If I Can't Have You.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/22 - Love Me.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/22 - Love Me.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/23 - You Should Be Dancing.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/23 - You Should Be Dancing.mp3 Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::stop Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:50 volumio volumio[30849]: info: CoreCommandRouter::volumioPushQueue Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::saveQueue Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::play index undefined Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:50 volumio volumio[30849]: info: CoreStateMachine::updateTrackBlock Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::getTrackBlock Jan 12 16:00:50 volumio volumio[30849]: info: CorePlayQueue::getTrack 1 Jan 12 16:00:55 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:55 volumio volumio[30849]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::ClearQueue Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::stop Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::clearPlayQueue Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::saveQueue Jan 12 16:00:55 volumio volumio[30849]: info: CoreCommandRouter::volumioPushQueue Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::addQueueItems Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::addQueueItems Jan 12 16:00:55 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/01 - New York Mining Disaster 1941.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/01 - New York Mining Disaster 1941.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/02 - To Love Somebody.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/02 - To Love Somebody.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: CoreCommandRouter::volumioPushQueue Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::saveQueue Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::updateTrackBlock Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::getTrackBlock Jan 12 16:00:55 volumio volumio[30849]: info: CoreCommandRouter::volumioPlay Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::play index 1 Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::addQueueItems Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::addQueueItems Jan 12 16:00:55 volumio volumio[30849]: info: Preload queue cleared Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/03 - Holiday.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/03 - Holiday.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/04 - Massachusets.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/04 - Massachusets.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/05 - World.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/05 - World.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/06 - Words.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/06 - Words.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/07 - Jumbo.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/07 - Jumbo.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/08 - I've Gotta Get A Message To You.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/08 - I've Gotta Get A Message To You.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/09 - I Started A Joke.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/09 - I Started A Joke.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/10 - First Of May.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/10 - First Of May.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/11 - Saved By The Bell.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/11 - Saved By The Bell.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/12 - Don't Forget To Remember.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/12 - Don't Forget To Remember.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/13 - Lonely Days.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/13 - Lonely Days.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/14 - How Can You Mend A Broken Heart.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/14 - How Can You Mend A Broken Heart.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/15 - My World.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/15 - My World.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/16 - Run To Me.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/16 - Run To Me.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/17 - Jive Talkin'.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/17 - Jive Talkin'.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/18 - Nights On Broadway.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/18 - Nights On Broadway.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/19 - Fanny (Be Tender With My Love).mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/19 - Fanny (Be Tender With My Love).mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/20 - Love So Right.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/20 - Love So Right.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/21 - If I Can't Have You.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/21 - If I Can't Have You.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/22 - Love Me.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/22 - Love Me.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Adding Item to queue: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/23 - You Should Be Dancing.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: Using cached record of: music-library/NAS/DS/5harts/Malcolm Music/Bee Gees/Bee Gees - Their Greatest Hits - The Record (Disc 1)/23 - You Should Be Dancing.mp3 Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::stop Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:55 volumio volumio[30849]: info: CoreCommandRouter::volumioPushQueue Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::saveQueue Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::play index undefined Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:00:55 volumio volumio[30849]: info: CoreStateMachine::updateTrackBlock Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::getTrackBlock Jan 12 16:00:55 volumio volumio[30849]: info: CorePlayQueue::getTrack 1 Jan 12 16:01:07 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 16:01:07 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 12 16:01:07 volumio go-librespot[2893]: time="2026-01-12T16:01:07Z" level=trace msg="sent dealer ping" Jan 12 16:01:07 volumio go-librespot[2893]: time="2026-01-12T16:01:07Z" level=trace msg="received dealer pong" Jan 12 16:01:12 volumio volumio[30849]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:01:20 volumio volumio[30849]: info: Received OAUTH Data Jan 12 16:01:20 volumio volumio[30849]: info: Executing Spotify Oauth Login Jan 12 16:01:20 volumio volumio[30849]: info: Saving Spotify Refresh Token Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: BQBi7apEujuMnfErrr3gLp5rzkLjOy6grN85E6Otby7NkgekrxDMIFEoFkhyNHr0R55_FoJ8F1Ns6vpiHrlcx5OpdNT8XhLq-y4ZvaVG_BIx1Ayr3krr8OwmvVDJyx_LjeS5COMTfOEj9k7K4mYbOUd3qpC76Tn220u5EQF89pV0L2pRi28l0i6YaGo_tjsppHkLGh8BYZCPvQ_FwInx5HUQLJo0ARmHFm7SDVD8VYZXLFl9Ki76VTB79XzHvN5XPbRa77DqKpR5I4mV7nNhTRMP-FWfXZcZ Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 12 16:01:20 volumio volumio[30849]: info: New Spotify access token = BQBi7apEujuMnfErrr3gLp5rzkLjOy6grN85E6Otby7NkgekrxDMIFEoFkhyNHr0R55_FoJ8F1Ns6vpiHrlcx5OpdNT8XhLq-y4ZvaVG_BIx1Ayr3krr8OwmvVDJyx_LjeS5COMTfOEj9k7K4mYbOUd3qpC76Tn220u5EQF89pV0L2pRi28l0i6YaGo_tjsppHkLGh8BYZCPvQ_FwInx5HUQLJo0ARmHFm7SDVD8VYZXLFl9Ki76VTB79XzHvN5XPbRa77DqKpR5I4mV7nNhTRMP-FWfXZcZ Jan 12 16:01:20 volumio volumio[30849]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: User informations: {"country":"GB","display_name":"foolish","email":"malcolm@5harts.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/foolish"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/foolish","id":"foolish","images":[],"product":"premium","type":"user","uri":"spotify:user:foolish"} Jan 12 16:01:20 volumio volumio[30849]: info: Creating Spotify config file Jan 12 16:01:20 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:01:20 volumio volumio[30849]: info: Spotify config file written Jan 12 16:01:20 volumio sudo[7596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 12 16:01:20 volumio sudo[7596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 16:01:20 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 12 16:01:20 volumio systemd[1]: go-librespot-daemon.service: Killing process 2899 (go-librespot) with signal SIGKILL. Jan 12 16:01:20 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 12 16:01:20 volumio volumio[30849]: info: Connection to go-librespot Websocket closed Jan 12 16:01:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 12 16:01:20 volumio systemd[1]: go-librespot-daemon.service: Consumed 1.078s CPU time. Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: BQAl8mAmIN1d1IMu3Jo4jqYS67AxSZS0eVd6FW8h6cBm8FRzhYynYirc_jomu2uAoNbhHpBN6fFG9MGIyC-8ut5xGKIsMBNCk-6YmHP5McYbR6EUXqASOWVFm3ytXUq3BhOYHZnh2m5pOWgKX9UCwGDsa0xAE1MgTYkl4DTNWkYUzMnw8R6aFKre50-UA9yfoU9Uggi2WqW2x4bRCSiEevduC3hZpDZW0dKgKZBYigM-S4GPnO1HtEnKJDKvhhRHjLDTmEypZfgJyX4-IEfDoB-Bn_7N6PST Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 12 16:01:20 volumio volumio[30849]: info: New Spotify access token = BQAl8mAmIN1d1IMu3Jo4jqYS67AxSZS0eVd6FW8h6cBm8FRzhYynYirc_jomu2uAoNbhHpBN6fFG9MGIyC-8ut5xGKIsMBNCk-6YmHP5McYbR6EUXqASOWVFm3ytXUq3BhOYHZnh2m5pOWgKX9UCwGDsa0xAE1MgTYkl4DTNWkYUzMnw8R6aFKre50-UA9yfoU9Uggi2WqW2x4bRCSiEevduC3hZpDZW0dKgKZBYigM-S4GPnO1HtEnKJDKvhhRHjLDTmEypZfgJyX4-IEfDoB-Bn_7N6PST Jan 12 16:01:20 volumio volumio[30849]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 12 16:01:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 12 16:01:20 volumio go-librespot[7598]: go-librespot daemon starting... Jan 12 16:01:20 volumio sudo[7596]: pam_unix(sudo:session): session closed for user root Jan 12 16:01:20 volumio go-librespot[7599]: time="2026-01-12T16:01:20Z" level=info msg="running go-librespot 0.4.0" Jan 12 16:01:20 volumio go-librespot[7599]: time="2026-01-12T16:01:20Z" level=debug msg="app state loaded" Jan 12 16:01:20 volumio go-librespot[7599]: time="2026-01-12T16:01:20Z" level=info msg="api server listening on 127.0.0.1:9879" Jan 12 16:01:20 volumio volumio[30849]: SPOTIFY: User informations: {"country":"GB","display_name":"foolish","email":"malcolm@5harts.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/foolish"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/foolish","id":"foolish","images":[],"product":"premium","type":"user","uri":"spotify:user:foolish"} Jan 12 16:01:20 volumio volumio[30849]: info: Spotify Successfully logged in Jan 12 16:01:20 volumio sudo[7605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 16:01:20 volumio sudo[7607]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 16:01:20 volumio sudo[7607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 16:01:20 volumio sudo[7605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 16:01:20 volumio sudo[7607]: pam_unix(sudo:session): session closed for user root Jan 12 16:01:20 volumio volumio[30849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 16:01:20 volumio volumio[30849]: info: [1768233680808] CoreMusicLibrary::Adding element Spotify Jan 12 16:01:20 volumio sudo[7605]: pam_unix(sudo:session): session closed for user root Jan 12 16:01:20 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 16:01:20 volumio volumio[30849]: Cannot find translation for source Podcast Jan 12 16:01:20 volumio volumio[30849]: Cannot find translation for source Spotify Jan 12 16:01:20 volumio go-librespot[7599]: time="2026-01-12T16:01:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 12 16:01:20 volumio go-librespot[7599]: time="2026-01-12T16:01:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 12 16:01:20 volumio go-librespot[7599]: time="2026-01-12T16:01:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 12 16:01:21 volumio go-librespot[7599]: time="2026-01-12T16:01:21Z" level=info msg="zeroconf server listening on port 37727" Jan 12 16:01:21 volumio volumio[30849]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 15; 2311DRK48G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 12 16:01:21 volumio go-librespot[7599]: time="2026-01-12T16:01:21Z" level=debug msg="obtained new client token: AAA+c4q7n0Mc/WsO8II4viN02fFijjytx3sBXcgJWxRYxMmbEw7QM7dfxjjgovoopV6oRyB3mUUP0EJV2FFiCJi9WJ4hRq4RQTwFrp/Z7u+Vj2yqvRPCFV15Rb4BJC9eHvaDftYav7ZxwPAFGkGnXb29ucoJwD3aktK6bgASmhmHyKoQfGX2qu5n/qIvcY+8lMUCJ5JJaKZ1jP07+lpW0Dp9Tz0Up9WkJzol/rqq5GpA6CNiSG3ydcM=" Jan 12 16:01:21 volumio go-librespot[7599]: time="2026-01-12T16:01:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 12 16:01:21 volumio go-librespot[7599]: time="2026-01-12T16:01:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 12 16:01:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 12 16:01:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::volumioGetState Jan 12 16:01:21 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 16:01:21 volumio volumio[30849]: info: Received Get System Info Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 16:01:21 volumio volumio[30849]: info: Discovery: Getting this device information Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::volumioGetState Jan 12 16:01:21 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::volumioGetState Jan 12 16:01:21 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:01:21 volumio volumio[30849]: info: Listing playlists Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 16:01:21 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 16:01:22 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 16:01:22 volumio volumio[30849]: info: Received Get System Info Jan 12 16:01:22 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 16:01:22 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 16:01:22 volumio volumio[30849]: info: Discovery: Getting this device information Jan 12 16:01:22 volumio volumio[30849]: info: CoreCommandRouter::volumioGetState Jan 12 16:01:22 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:01:22 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 16:01:22 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 16:01:23 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 16:01:23 volumio volumio[30849]: info: Received Get System Info Jan 12 16:01:23 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 16:01:23 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 16:01:23 volumio volumio[30849]: info: Discovery: Getting this device information Jan 12 16:01:23 volumio volumio[30849]: info: CoreCommandRouter::volumioGetState Jan 12 16:01:23 volumio volumio[30849]: info: CorePlayQueue::getTrack 0 Jan 12 16:01:23 volumio volumio[30849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 16:01:23 volumio volumio[30849]: info: Initializing connection to go-librespot Websocket Jan 12 16:01:23 volumio volumio[30849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 12 16:01:23 volumio volumio[30849]: info: go-librespot daemon successfully initialized Jan 12 16:01:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 12 16:01:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 12 16:01:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 12 16:01:24 volumio go-librespot[7612]: go-librespot daemon starting... Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=info msg="running go-librespot 0.4.0" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=debug msg="app state loaded" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=info msg="api server listening on 127.0.0.1:9879" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=info msg="zeroconf server listening on port 34243" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=debug msg="obtained new client token: AAA9ZhWzd6wXrThur4jk1ZlIaqNpqRFKTbXeDTaf7/TacerURK9gMUfnlgNHYZw68rF7AxCtOGP+4t2rcBWgjt3RRhfYtMBU7ibXMAZO7M7TAhPhcLTpEE27fhyg1ySdlSNollG9uV+8pWVrkj8zfHA45LegPdyH9SX8sbAulTup7/NPiZP0kRvOHqaOh/CNdjdDMWvULImmEYhQP1gOio7r5LV7bBfkRRy6fVjQPAmTuufKdf1nA9nhhw==" Jan 12 16:01:24 volumio go-librespot[7613]: time="2026-01-12T16:01:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 12 16:01:26 volumio volumio[30849]: info: Initializing connection to go-librespot Websocket Jan 12 16:01:26 volumio go-librespot[7613]: time="2026-01-12T16:01:26Z" level=debug msg="new websocket client" Jan 12 16:01:26 volumio volumio[30849]: info: Connection to go-librespot Websocket established Jan 12 16:01:26 volumio volumio[30849]: info: Initializing connection to go-librespot Websocket Jan 12 16:01:26 volumio go-librespot[7613]: time="2026-01-12T16:01:26Z" level=debug msg="new websocket client" Jan 12 16:01:26 volumio volumio[30849]: info: Connection to go-librespot Websocket established Jan 12 16:01:28 volumio go-librespot[7613]: time="2026-01-12T16:01:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.120:42438->104.199.65.9:4070: read: connection reset by peer" Jan 12 16:01:28 volumio volumio[30849]: info: Connection to go-librespot Websocket closed Jan 12 16:01:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 12 16:01:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 12 16:01:28 volumio volumio[30849]: info: Connection to go-librespot Websocket closed Jan 12 16:01:29 volumio volumio[30849]: info: Getting Spotify volume Jan 12 16:01:29 volumio volumio[30849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:01:29 volumio volumio[30849]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 12 16:01:29 volumio volumio[30849]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 12 16:01:29 volumio volumio[30849]: errno: -111, Jan 12 16:01:29 volumio volumio[30849]: code: 'ECONNREFUSED', Jan 12 16:01:29 volumio volumio[30849]: syscall: 'connect', Jan 12 16:01:29 volumio volumio[30849]: address: '127.0.0.1', Jan 12 16:01:29 volumio volumio[30849]: port: 9879, Jan 12 16:01:29 volumio volumio[30849]: response: undefined Jan 12 16:01:29 volumio volumio[30849]: } Jan 12 16:01:29 volumio volumio[30849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:01:30 volumio sudo[7652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 16:00' Jan 12 16:01:30 volumio sudo[7652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"