-- Logs begin at Mon 2024-01-01 05:16:32 EST, end at Mon 2024-01-01 07:29:08 EST. -- Jan 01 07:28:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13691. Jan 01 07:28:00 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:00 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:00 volumio go-librespot[16676]: go-librespot daemon starting... Jan 01 07:28:00 volumio go-librespot[16676]: time="2024-01-01T07:28:00-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:00 volumio go-librespot[16676]: time="2024-01-01T07:28:00-05:00" level=debug msg="app state loaded" Jan 01 07:28:00 volumio go-librespot[16676]: time="2024-01-01T07:28:00-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:00 volumio go-librespot[16676]: time="2024-01-01T07:28:00-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:00-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:02 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:02 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13692. Jan 01 07:28:03 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:04 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:04 volumio go-librespot[16685]: go-librespot daemon starting... Jan 01 07:28:04 volumio go-librespot[16685]: time="2024-01-01T07:28:04-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:04 volumio go-librespot[16685]: time="2024-01-01T07:28:04-05:00" level=debug msg="app state loaded" Jan 01 07:28:04 volumio go-librespot[16685]: time="2024-01-01T07:28:04-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:04 volumio go-librespot[16685]: time="2024-01-01T07:28:04-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:04-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:05 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:05 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:05 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 01 07:28:05 volumio volumio[1043]: info: CURURI: artists://Amy Winehouse/Frank Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::ClearQueue Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::clearPlayQueue Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::addQueueItems Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::addQueueItems Jan 01 07:28:06 volumio volumio[1043]: info: Preload queue cleared Jan 01 07:28:06 volumio volumio[1043]: info: Adding Item to queue: artists://Amy%20Winehouse/Frank Jan 01 07:28:06 volumio volumio[1043]: info: Exploding uri artists://Amy%20Winehouse/Frank in service mpd Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::updateTrackBlock Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrackBlock Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::play index 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand stop took 0 milliseconds Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:06 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand clear took 1 milliseconds Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:06 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:06 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:06 volumio volumio[1043]: error: updateQueue error: null Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:06 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 3 milliseconds Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:06 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:06 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand play took 3 milliseconds Jan 01 07:28:06 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:06 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:06 volumio volumio[1043]: info: Jan 01 07:28:06 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:06 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand status took 6 milliseconds Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand status took 5 milliseconds Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand status took 6 milliseconds Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand clearerror took 4 milliseconds Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand clearerror took 4 milliseconds Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand clearerror took 3 milliseconds Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 01 07:28:06 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:06 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:06 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:06 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: No code Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:06 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: No code Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:06 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: No code Jan 01 07:28:06 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 31ms Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 31ms Jan 01 07:28:06 volumio volumio[1043]: info: ------------------------------ 30ms Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:06 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13693. Jan 01 07:28:07 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:07 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:07 volumio go-librespot[16709]: go-librespot daemon starting... Jan 01 07:28:07 volumio go-librespot[16709]: time="2024-01-01T07:28:07-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:07 volumio go-librespot[16709]: time="2024-01-01T07:28:07-05:00" level=debug msg="app state loaded" Jan 01 07:28:07 volumio go-librespot[16709]: time="2024-01-01T07:28:07-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:07 volumio go-librespot[16709]: time="2024-01-01T07:28:07-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:07-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:08 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:08 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13694. Jan 01 07:28:10 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:10 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:10 volumio go-librespot[16719]: go-librespot daemon starting... Jan 01 07:28:10 volumio go-librespot[16719]: time="2024-01-01T07:28:10-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:10 volumio go-librespot[16719]: time="2024-01-01T07:28:10-05:00" level=debug msg="app state loaded" Jan 01 07:28:10 volumio go-librespot[16719]: time="2024-01-01T07:28:10-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:10 volumio go-librespot[16719]: time="2024-01-01T07:28:10-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:10-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:11 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:11 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13695. Jan 01 07:28:13 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:14 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:14 volumio go-librespot[16727]: go-librespot daemon starting... Jan 01 07:28:14 volumio go-librespot[16727]: time="2024-01-01T07:28:14-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:14 volumio go-librespot[16727]: time="2024-01-01T07:28:14-05:00" level=debug msg="app state loaded" Jan 01 07:28:14 volumio go-librespot[16727]: time="2024-01-01T07:28:14-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:14 volumio go-librespot[16727]: time="2024-01-01T07:28:14-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:14-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:14 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:14 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:16 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:16 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:16 volumio volumio[1043]: info: Listing playlists Jan 01 07:28:16 volumio volumio[1043]: info: Listing playlists Jan 01 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13696. Jan 01 07:28:17 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:17 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:17 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:17 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:17 volumio go-librespot[16749]: go-librespot daemon starting... Jan 01 07:28:17 volumio go-librespot[16749]: time="2024-01-01T07:28:17-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:17 volumio go-librespot[16749]: time="2024-01-01T07:28:17-05:00" level=debug msg="app state loaded" Jan 01 07:28:17 volumio go-librespot[16749]: time="2024-01-01T07:28:17-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:17 volumio go-librespot[16749]: time="2024-01-01T07:28:17-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:17-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:20 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:20 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13697. Jan 01 07:28:20 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:20 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:20 volumio go-librespot[16760]: go-librespot daemon starting... Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::ClearQueue Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::clearPlayQueue Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::addQueueItems Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::addQueueItems Jan 01 07:28:20 volumio volumio[1043]: info: Preload queue cleared Jan 01 07:28:20 volumio volumio[1043]: info: Adding Item to queue: artists://Amy%20Winehouse/Frank Jan 01 07:28:20 volumio volumio[1043]: info: Using cached record of: artists://Amy%20Winehouse/Frank Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::updateTrackBlock Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrackBlock Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::play index 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand stop took 2 milliseconds Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand clear took 0 milliseconds Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:20 volumio go-librespot[16760]: time="2024-01-01T07:28:20-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:20 volumio go-librespot[16760]: time="2024-01-01T07:28:20-05:00" level=debug msg="app state loaded" Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:20 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:20 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 3 milliseconds Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:20 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:20 volumio go-librespot[16760]: time="2024-01-01T07:28:20-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:20 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 6ms Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 6ms Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand play took 5 milliseconds Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:20 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:20 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:20 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:20 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:20 volumio volumio[1043]: info: Jan 01 07:28:20 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:20 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand clearerror took 4 milliseconds Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand clearerror took 3 milliseconds Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand status took 1 milliseconds Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:20 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:20 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: No code Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:20 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: No code Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 25ms Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 24ms Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 01 07:28:20 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:20 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:20 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: No code Jan 01 07:28:20 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:20 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:20 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:20 volumio volumio[1043]: info: ------------------------------ 28ms Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:20 volumio go-librespot[16760]: time="2024-01-01T07:28:20-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:20-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::ClearQueue Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::clearPlayQueue Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::addQueueItems Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::addQueueItems Jan 01 07:28:22 volumio volumio[1043]: info: Preload queue cleared Jan 01 07:28:22 volumio volumio[1043]: info: Adding Item to queue: artists://Amy%20Winehouse/Frank Jan 01 07:28:22 volumio volumio[1043]: info: Using cached record of: artists://Amy%20Winehouse/Frank Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::updateTrackBlock Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrackBlock Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::play index 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand stop took 1 milliseconds Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:22 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand clear took 1 milliseconds Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:22 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:22 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:22 volumio volumio[1043]: error: updateQueue error: null Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:22 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 2 milliseconds Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:22 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:22 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:22 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:22 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:22 volumio volumio[1043]: info: Jan 01 07:28:22 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:22 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand status took 4 milliseconds Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand status took 4 milliseconds Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand status took 5 milliseconds Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand clearerror took 4 milliseconds Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand clearerror took 3 milliseconds Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand clearerror took 2 milliseconds Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:22 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:22 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:22 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:22 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: No code Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:22 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: No code Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:22 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: No code Jan 01 07:28:22 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:22 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:22 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 25ms Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 24ms Jan 01 07:28:22 volumio volumio[1043]: info: ------------------------------ 24ms Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:22 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:23 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:23 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13698. Jan 01 07:28:23 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:23 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:23 volumio go-librespot[16775]: go-librespot daemon starting... Jan 01 07:28:23 volumio go-librespot[16775]: time="2024-01-01T07:28:23-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:23 volumio go-librespot[16775]: time="2024-01-01T07:28:23-05:00" level=debug msg="app state loaded" Jan 01 07:28:23 volumio go-librespot[16775]: time="2024-01-01T07:28:23-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:23 volumio go-librespot[16775]: time="2024-01-01T07:28:23-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:23-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:26 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:26 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13699. Jan 01 07:28:26 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:27 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:27 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:27 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:27 volumio go-librespot[16835]: go-librespot daemon starting... Jan 01 07:28:27 volumio go-librespot[16835]: time="2024-01-01T07:28:27-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:27 volumio go-librespot[16835]: time="2024-01-01T07:28:27-05:00" level=debug msg="app state loaded" Jan 01 07:28:27 volumio go-librespot[16835]: time="2024-01-01T07:28:27-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:27 volumio go-librespot[16835]: time="2024-01-01T07:28:27-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:27-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::ClearQueue Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::clearPlayQueue Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::addQueueItems Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::addQueueItems Jan 01 07:28:29 volumio volumio[1043]: info: Preload queue cleared Jan 01 07:28:29 volumio volumio[1043]: info: Adding Item to queue: artists://Amy%20Winehouse/Frank Jan 01 07:28:29 volumio volumio[1043]: info: Using cached record of: artists://Amy%20Winehouse/Frank Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::updateTrackBlock Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrackBlock Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::play index 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand stop took 2 milliseconds Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand clear took 0 milliseconds Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:29 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:29 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 1 milliseconds Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:29 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:29 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand play took 3 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:29 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:29 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:29 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:29 volumio volumio[1043]: info: Jan 01 07:28:29 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:29 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand status took 8 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 7ms Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand status took 7 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 6ms Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand status took 7 milliseconds Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand clearerror took 4 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand clearerror took 4 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand clearerror took 3 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:29 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:29 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:29 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:29 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: No code Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:29 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: No code Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:29 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: No code Jan 01 07:28:29 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:29 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:29 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 28ms Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 27ms Jan 01 07:28:29 volumio volumio[1043]: info: ------------------------------ 27ms Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:29 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:29 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13700. Jan 01 07:28:30 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:30 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:30 volumio go-librespot[16845]: go-librespot daemon starting... Jan 01 07:28:30 volumio go-librespot[16845]: time="2024-01-01T07:28:30-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:30 volumio go-librespot[16845]: time="2024-01-01T07:28:30-05:00" level=debug msg="app state loaded" Jan 01 07:28:30 volumio go-librespot[16845]: time="2024-01-01T07:28:30-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:30 volumio volumio[1043]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::ClearQueue Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:30 volumio volumio[1043]: info: CorePlayQueue::clearPlayQueue Jan 01 07:28:30 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:30 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::addQueueItems Jan 01 07:28:30 volumio volumio[1043]: info: CorePlayQueue::addQueueItems Jan 01 07:28:30 volumio volumio[1043]: info: Preload queue cleared Jan 01 07:28:30 volumio volumio[1043]: info: Adding Item to queue: music-library/NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:30 volumio volumio[1043]: info: Exploding uri music-library/NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 in service mpd Jan 01 07:28:30 volumio volumio[1043]: error: scanFolder - failure to stat '/mnt/NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3' Jan 01 07:28:30 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:30 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::updateTrackBlock Jan 01 07:28:30 volumio volumio[1043]: info: CorePlayQueue::getTrackBlock Jan 01 07:28:30 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::play index 0 Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:30 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:30 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:30 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:30 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:30 volumio go-librespot[16845]: time="2024-01-01T07:28:30-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:30-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:32 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:32 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::ClearQueue Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::clearPlayQueue Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::addQueueItems Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::addQueueItems Jan 01 07:28:32 volumio volumio[1043]: info: Preload queue cleared Jan 01 07:28:32 volumio volumio[1043]: info: Adding Item to queue: artists://Amy%20Winehouse/Frank Jan 01 07:28:32 volumio volumio[1043]: info: Using cached record of: artists://Amy%20Winehouse/Frank Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushQueue Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::saveQueue Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::updateTrackBlock Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrackBlock Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::play index 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand stop took 1 milliseconds Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand clear took 0 milliseconds Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:32 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:32 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 1 milliseconds Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:32 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:32 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:32 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:32 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:32 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:32 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:32 volumio volumio[1043]: info: Jan 01 07:28:32 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:32 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand status took 6 milliseconds Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 5 milliseconds Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 4 milliseconds Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:32 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: No code Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: No code Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 25ms Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 26ms Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand clearerror took 26 milliseconds Jan 01 07:28:32 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 01 07:28:32 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:32 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:32 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: No code Jan 01 07:28:32 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:32 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:32 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:32 volumio volumio[1043]: info: ------------------------------ 38ms Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:32 volumio volumio[1043]: info: Listing playlists Jan 01 07:28:32 volumio volumio[1043]: info: Listing playlists Jan 01 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13701. Jan 01 07:28:33 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:33 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:33 volumio go-librespot[16859]: go-librespot daemon starting... Jan 01 07:28:33 volumio go-librespot[16859]: time="2024-01-01T07:28:33-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:33 volumio go-librespot[16859]: time="2024-01-01T07:28:33-05:00" level=debug msg="app state loaded" Jan 01 07:28:33 volumio go-librespot[16859]: time="2024-01-01T07:28:33-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:33 volumio go-librespot[16859]: time="2024-01-01T07:28:33-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:33-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:35 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:35 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- Client requests Volumio play Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand stop took 2 milliseconds Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand clear took 0 milliseconds Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:35 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:35 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 1 milliseconds Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:35 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:35 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand play took 2 milliseconds Jan 01 07:28:35 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 1ms Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:35 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:35 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:35 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand status took 5 milliseconds Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:35 volumio volumio[1043]: info: Jan 01 07:28:35 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:35 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand clearerror took 7 milliseconds Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand clearerror took 7 milliseconds Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand status took 5 milliseconds Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:35 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:35 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: No code Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:35 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: No code Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 23ms Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 23ms Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 01 07:28:35 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:35 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:35 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: No code Jan 01 07:28:35 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:35 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:35 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:35 volumio volumio[1043]: info: ------------------------------ 29ms Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:35 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13702. Jan 01 07:28:36 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:36 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:36 volumio go-librespot[16885]: go-librespot daemon starting... Jan 01 07:28:36 volumio go-librespot[16885]: time="2024-01-01T07:28:36-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:36 volumio go-librespot[16885]: time="2024-01-01T07:28:36-05:00" level=debug msg="app state loaded" Jan 01 07:28:36 volumio go-librespot[16885]: time="2024-01-01T07:28:36-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:36 volumio go-librespot[16885]: time="2024-01-01T07:28:36-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:36-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:36 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:36 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:38 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:38 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13703. Jan 01 07:28:39 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:40 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:40 volumio go-librespot[16897]: go-librespot daemon starting... Jan 01 07:28:40 volumio go-librespot[16897]: time="2024-01-01T07:28:40-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:40 volumio go-librespot[16897]: time="2024-01-01T07:28:40-05:00" level=debug msg="app state loaded" Jan 01 07:28:40 volumio go-librespot[16897]: time="2024-01-01T07:28:40-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:40 volumio go-librespot[16897]: time="2024-01-01T07:28:40-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:40-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:41 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:41 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13704. Jan 01 07:28:43 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:43 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:43 volumio go-librespot[16905]: go-librespot daemon starting... Jan 01 07:28:43 volumio go-librespot[16905]: time="2024-01-01T07:28:43-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:43 volumio go-librespot[16905]: time="2024-01-01T07:28:43-05:00" level=debug msg="app state loaded" Jan 01 07:28:43 volumio go-librespot[16905]: time="2024-01-01T07:28:43-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:43 volumio go-librespot[16905]: time="2024-01-01T07:28:43-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:43-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:44 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:44 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13705. Jan 01 07:28:46 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:46 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:46 volumio go-librespot[16927]: go-librespot daemon starting... Jan 01 07:28:46 volumio go-librespot[16927]: time="2024-01-01T07:28:46-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:46 volumio go-librespot[16927]: time="2024-01-01T07:28:46-05:00" level=debug msg="app state loaded" Jan 01 07:28:46 volumio go-librespot[16927]: time="2024-01-01T07:28:46-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:46 volumio go-librespot[16927]: time="2024-01-01T07:28:46-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:46-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:46 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:46 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:47 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:47 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13706. Jan 01 07:28:49 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:50 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:50 volumio go-librespot[16935]: go-librespot daemon starting... Jan 01 07:28:50 volumio go-librespot[16935]: time="2024-01-01T07:28:50-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:50 volumio go-librespot[16935]: time="2024-01-01T07:28:50-05:00" level=debug msg="app state loaded" Jan 01 07:28:50 volumio go-librespot[16935]: time="2024-01-01T07:28:50-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:50 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:50 volumio go-librespot[16935]: time="2024-01-01T07:28:50-05:00" level=debug msg="new websocket client" Jan 01 07:28:50 volumio volumio[1043]: info: Connection to go-librespot Websocket established Jan 01 07:28:50 volumio go-librespot[16935]: time="2024-01-01T07:28:50-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:50-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:50 volumio volumio[1043]: info: Connection to go-librespot Websocket closed Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- Client requests Volumio play Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand stop took 2 milliseconds Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand clear took 1 milliseconds Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:51 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:51 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 1 milliseconds Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:51 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:51 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:51 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:51 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:51 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 4ms Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:51 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:51 volumio volumio[1043]: info: Jan 01 07:28:51 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:51 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand clearerror took 7 milliseconds Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand clearerror took 6 milliseconds Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 6ms Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand status took 5 milliseconds Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:51 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:51 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: No code Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:51 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: No code Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 24ms Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 24ms Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 01 07:28:51 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:51 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:51 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: No code Jan 01 07:28:51 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:51 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:51 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:51 volumio volumio[1043]: info: ------------------------------ 27ms Jan 01 07:28:51 volumio volumio[1043]: info: Listing playlists Jan 01 07:28:51 volumio volumio[1043]: info: Listing playlists Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:51 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- Client requests Volumio play Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPlay Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::play index undefined Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::startPlaybackTimer Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::clearAddPlayTracks NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3 Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand stop Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand stop took 2 milliseconds Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clear Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:52 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand clear took 1 milliseconds Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:52 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:52 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:52 volumio volumio[1043]: error: updateQueue error: null Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:52 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand add "NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3" took 2 milliseconds Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 1ms Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand play Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:52 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces system playlist update Jan 01 07:28:52 volumio volumio[1043]: info: Ignoring MPD Status Update Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 3ms Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand play took 3 milliseconds Jan 01 07:28:52 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 2ms Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:52 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:52 volumio volumio[1043]: info: Jan 01 07:28:52 volumio volumio[1043]: ---------------------------- MPD announces state update: player Jan 01 07:28:52 volumio volumio[1043]: info: ControllerMpd::getState Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand status Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand status took 3 milliseconds Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand status took 2 milliseconds Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand status took 5 milliseconds Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand clearerror took 3 milliseconds Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::parseState Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:52 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:52 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: No code Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:52 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: No code Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 18ms Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 18ms Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand clearerror took 17 milliseconds Jan 01 07:28:52 volumio volumio[1043]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 01 07:28:52 volumio volumio[1043]: verbose: ControllerMpd::parseTrackInfo Jan 01 07:28:52 volumio volumio[1043]: info: ControllerMpd::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::servicePushState Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[Intro] Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"NAS/backup/Amy Winehouse - Discography (Deluxe) CDRips 2011/Amy Winehouse - Discography (Deluxe) CDRips 2011 [Bubanee]/Amy Winehouse - Frank/01 - [Intro] Stronger Than Me.mp3","trackType":"mp3"} Jan 01 07:28:52 volumio volumio[1043]: verbose: CURRENT POSITION 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::syncState stateService stop Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::syncState currentStatus stop Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: No code Jan 01 07:28:52 volumio volumio[1043]: info: CoreStateMachine::pushState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioPushState Jan 01 07:28:52 volumio volumio[1043]: info: ------------------------------ 27ms Jan 01 07:28:52 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:52 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:52 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:53 volumio volumio[1043]: info: Getting Spotify volume Jan 01 07:28:53 volumio volumio[1043]: (node:1043) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:53 volumio volumio[1043]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 01 07:28:53 volumio volumio[1043]: (node:1043) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 326) Jan 01 07:28:53 volumio volumio[1043]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 01 07:28:53 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:53 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:53 volumio volumio[1043]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Jan 01 07:28:53 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:53 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13707. Jan 01 07:28:53 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:53 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:53 volumio go-librespot[16947]: go-librespot daemon starting... Jan 01 07:28:53 volumio go-librespot[16947]: time="2024-01-01T07:28:53-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:53 volumio go-librespot[16947]: time="2024-01-01T07:28:53-05:00" level=debug msg="app state loaded" Jan 01 07:28:53 volumio go-librespot[16947]: time="2024-01-01T07:28:53-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:53 volumio go-librespot[16947]: time="2024-01-01T07:28:53-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:53-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:56 volumio volumio[1043]: info: CoreCommandRouter::volumioGetVisibleSources Jan 01 07:28:56 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 01 07:28:56 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:56 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13708. Jan 01 07:28:56 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:28:56 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:28:56 volumio go-librespot[16969]: go-librespot daemon starting... Jan 01 07:28:56 volumio go-librespot[16969]: time="2024-01-01T07:28:56-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:28:56 volumio go-librespot[16969]: time="2024-01-01T07:28:56-05:00" level=debug msg="app state loaded" Jan 01 07:28:56 volumio go-librespot[16969]: time="2024-01-01T07:28:56-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:28:56 volumio go-librespot[16969]: time="2024-01-01T07:28:56-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:28:56-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:28:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:28:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:28:56 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:28:56 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:28:57 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 01 07:28:57 volumio volumio[1043]: info: CURURI: artists:// Jan 01 07:28:59 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:28:59 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:28:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:28:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13709. Jan 01 07:28:59 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:29:00 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:29:00 volumio go-librespot[16980]: go-librespot daemon starting... Jan 01 07:29:00 volumio go-librespot[16980]: time="2024-01-01T07:29:00-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:29:00 volumio go-librespot[16980]: time="2024-01-01T07:29:00-05:00" level=debug msg="app state loaded" Jan 01 07:29:00 volumio go-librespot[16980]: time="2024-01-01T07:29:00-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:29:00 volumio go-librespot[16980]: time="2024-01-01T07:29:00-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:29:00-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:29:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:29:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:29:02 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:29:02 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:29:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:29:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13710. Jan 01 07:29:03 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:29:03 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:29:03 volumio go-librespot[16988]: go-librespot daemon starting... Jan 01 07:29:03 volumio go-librespot[16988]: time="2024-01-01T07:29:03-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:29:03 volumio go-librespot[16988]: time="2024-01-01T07:29:03-05:00" level=debug msg="app state loaded" Jan 01 07:29:03 volumio go-librespot[16988]: time="2024-01-01T07:29:03-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:29:03 volumio go-librespot[16988]: time="2024-01-01T07:29:03-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:29:03-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:29:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:29:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:29:05 volumio volumio[1043]: info: Initializing connection to go-librespot Websocket Jan 01 07:29:05 volumio volumio[1043]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 01 07:29:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 01 07:29:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13711. Jan 01 07:29:06 volumio systemd[1]: Stopped go-librespot Daemon. Jan 01 07:29:06 volumio systemd[1]: Started go-librespot Daemon. Jan 01 07:29:06 volumio go-librespot[17010]: go-librespot daemon starting... Jan 01 07:29:06 volumio go-librespot[17010]: time="2024-01-01T07:29:06-05:00" level=info msg="running go-librespot 0.4.0" Jan 01 07:29:06 volumio go-librespot[17010]: time="2024-01-01T07:29:06-05:00" level=debug msg="app state loaded" Jan 01 07:29:06 volumio go-librespot[17010]: time="2024-01-01T07:29:06-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 01 07:29:06 volumio go-librespot[17010]: time="2024-01-01T07:29:06-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-01-01T07:29:06-05:00 is before 2025-12-08T00:00:00Z" Jan 01 07:29:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 01 07:29:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 01 07:29:06 volumio volumio[1043]: info: CoreCommandRouter::volumioGetState Jan 01 07:29:06 volumio volumio[1043]: info: CorePlayQueue::getTrack 0 Jan 01 07:29:06 volumio volumio[1043]: info: Listing playlists Jan 01 07:29:06 volumio volumio[1043]: info: Listing playlists Jan 01 07:29:07 volumio volumio[1043]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 01 07:29:07 volumio volumio[1043]: info: CURURI: artists://Daft Punk Jan 01 07:29:07 volumio volumio[1043]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Daft%20Punk/Random%20Access%20Memories/c12de54a-f3c9-45d9-bf67-9937891d1d23.png' Jan 01 07:29:07 volumio volumio[1043]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 01 07:29:07 volumio volumio[1043]: Error: certificate is not yet valid Jan 01 07:29:07 volumio volumio[1043]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 01 07:29:07 volumio volumio[1043]: at TLSSocket.emit (events.js:315:20) Jan 01 07:29:07 volumio volumio[1043]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 01 07:29:07 volumio volumio[1043]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 01 07:29:07 volumio volumio[1043]: code: 'CERT_NOT_YET_VALID' Jan 01 07:29:07 volumio volumio[1043]: } Jan 01 07:29:07 volumio volumio[1043]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 01 07:29:08 volumio sudo[17027]: pam_unix(sudo:account): account volumio has password changed in future Jan 01 07:29:08 volumio sudo[17027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-01-01 07:28 Jan 01 07:29:08 volumio sudo[17027]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"