Dec 26 17:01:00 vol volumio[1272]: info: ControllerMpd::onStart: Initializing MPD Dec 26 17:01:00 vol volumio[1272]: info: Creating MPD Configuration file Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 17:01:00 vol volumio[1272]: info: [1766736060073] CoreMusicLibrary::Adding element DLNAサーバー Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 17:01:00 vol sudo[1485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 17:01:00 vol volumio[1272]: info: UPNP Browser: Client initialized successfully Dec 26 17:01:00 vol sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 17:01:00 vol sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:00 vol sudo[1485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:00 vol systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 17:01:00 vol systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 17:01:00 vol sudo[1485]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:00 vol sudo[1499]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 17:01:00 vol sudo[1499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 17:01:00 vol sudo[1508]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 17:01:00 vol sudo[1499]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:00 vol systemd[1]: mpd.service: Deactivated successfully. Dec 26 17:01:00 vol systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 17:01:00 vol systemd[1]: mpd.socket: Deactivated successfully. Dec 26 17:01:00 vol systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 17:01:00 vol systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 17:01:00 vol systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 17:01:00 vol systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 17:01:00 vol volumio[1272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:00 vol sudo[1513]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 17:01:00 vol sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 17:01:00 vol sudo[1518]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 17:01:00 vol sudo[1513]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:00 vol volumio[1272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 17:01:00 vol volumio[1272]: info: [1766736060644] CoreMusicLibrary::Adding element Last_100 Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 17:01:00 vol volumio[1272]: info: [1766736060676] CoreMusicLibrary::Adding element Webradio Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 17:01:00 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 17:01:00 vol volumio[1272]: info: Initializing BBC Radios Dec 26 17:01:01 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 17:01:01 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:01 vol volumio[1272]: info: Creating Spotify config file Dec 26 17:01:01 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:02 vol volumio[1272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 17:01:02 vol volumio[1272]: info: [1766736062662] CoreMusicLibrary::Adding element YouTube2 Dec 26 17:01:02 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 17:01:02 vol volumio[1272]: Cannot find translation for source YouTube2 Dec 26 17:01:02 vol volumio[1272]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 17:01:02 vol volumio[1272]: info: [1766736062766] CoreMusicLibrary::Adding element YouTube Music Dec 26 17:01:02 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 17:01:02 vol volumio[1272]: Cannot find translation for source YouTube2 Dec 26 17:01:02 vol volumio[1272]: Cannot find translation for source YouTube Music Dec 26 17:01:02 vol volumio[1272]: info: Volumio Calling Home Dec 26 17:01:03 vol sudo[1537]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 17:01:03 vol sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:03 vol sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 17:01:03 vol sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:03 vol sudo[1537]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:03 vol volumio[1272]: info: MPD Permissions set Dec 26 17:01:03 vol volumio[1272]: info: MPD Permissions set Dec 26 17:01:04 vol sudo[1539]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:04 vol volumio[1272]: info: Volumio called home Dec 26 17:01:04 vol volumio[1272]: info: Spotify config file written Dec 26 17:01:04 vol sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 17:01:04 vol sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:04 vol systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:04 vol systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:04 vol volumio-remote-updater[837]: [2025-12-26 17:01:04] [connect] Successful connection Dec 26 17:01:04 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:04 vol go-librespot[1546]: go-librespot daemon starting... Dec 26 17:01:04 vol sudo[1544]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:04 vol volumio[1272]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol mpd[1519]: 2025-12-26T17:01:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 17:01:04 vol systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 17:01:04 vol sudo[1487]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:04 vol sudo[1477]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:04 vol go-librespot[1547]: time="2025-12-26T17:01:04+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:04 vol go-librespot[1547]: time="2025-12-26T17:01:04+09:00" level=debug msg="app state loaded" Dec 26 17:01:04 vol go-librespot[1547]: time="2025-12-26T17:01:04+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:04 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:05 vol volumio[1272]: info: No need to fix Spotify hosts Dec 26 17:01:05 vol volumio[1438]: Starting albumart workers Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=info msg="zeroconf server listening on port 45241" Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=debug msg="obtained new client token: AAAULQx9oOR36Ogzv5wZKSzSr22WeD2GnkiahBdYel5pxoy3K3AJKWJuahE7z2ggmPpWXPWtQuaaZhhBnWUuYXovapTzz9xyZDqgyuVUiIGcWSBSx8ZGAvYenI+H+1VoeQsNbK+QOqKsr0mOTl8sT7Oa6jlkPvOwP8Z5Rhz6tI5p6ltkwe28iegOkCVGDaGevB65kCCnet6GeLTux0u8L+XSAFaciR9sOcAG1Lc/5ksV5RB9p6kVAE0V" Dec 26 17:01:05 vol volumio[1435]: Starting albumart workers Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=debug msg="completed challenge" Dec 26 17:01:05 vol go-librespot[1547]: time="2025-12-26T17:01:05+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:05 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:05 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:06 vol volumio-remote-updater[837]: [2025-12-26 17:01:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766736064 101 Dec 26 17:01:06 vol volumio[1272]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Dec 26 17:01:06 vol volumio[1441]: Starting albumart workers Dec 26 17:01:06 vol volumio[1272]: info: Completed starting Core Plugins Dec 26 17:01:06 vol volumio[1272]: info: ------------------------------------------- Dec 26 17:01:06 vol volumio[1272]: info: ----- MyVolumio plugins startup ---- Dec 26 17:01:06 vol volumio[1272]: info: ------------------------------------------- Dec 26 17:01:06 vol volumio[1272]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 26 17:01:06 vol volumio[1272]: info: An error occurred while refreshing Spotify Token Error: Bad Request Dec 26 17:01:06 vol volumio[1272]: info: Starting Shairport Sync Dec 26 17:01:06 vol volumio[1272]: info: Starting Shairport Sync Dec 26 17:01:06 vol sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 17:01:06 vol sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:07 vol volumio[1272]: info: Starting Shairport Sync Dec 26 17:01:07 vol sudo[1575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 17:01:07 vol sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:07 vol sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 17:01:07 vol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 17:01:07 vol systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 17:01:07 vol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:01:07 vol systemd[1]: shairport-sync.service: Consumed 1.403s CPU time. Dec 26 17:01:07 vol sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:07 vol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:01:07 vol sudo[1570]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:07 vol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 17:01:07 vol systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 17:01:07 vol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:01:07 vol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:01:07 vol sudo[1575]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:07 vol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 17:01:07 vol systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 17:01:07 vol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:01:07 vol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 17:01:07 vol sudo[1573]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:07 vol sudo[1579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 26 17:01:07 vol sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:08 vol volumio[1272]: error: MPD error: The expression evaluated to a falsy value: Dec 26 17:01:08 vol volumio[1272]: assert.ok(self.idling) Dec 26 17:01:08 vol volumio[1272]: error: The expression evaluated to a falsy value: Dec 26 17:01:08 vol volumio[1272]: assert.ok(self.idling) Dec 26 17:01:08 vol volumio[1272]: info: MPD running with PID1519 Dec 26 17:01:08 vol volumio[1272]: ,establishing connection Dec 26 17:01:08 vol volumio[1272]: error: updateQueue error: null Dec 26 17:01:08 vol volumio[1272]: info: Shairport-Sync Started Dec 26 17:01:08 vol volumio[1272]: Error adding Membership: Error: addMembership EINVAL Dec 26 17:01:08 vol volumio[1272]: info: Shairport-Sync Started Dec 26 17:01:08 vol volumio[1272]: info: Shairport-Sync Started Dec 26 17:01:08 vol volumio[1272]: error: updateQueue error: null Dec 26 17:01:08 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:08 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:08 vol systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 26 17:01:08 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 26 17:01:08 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:08 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:08 vol go-librespot[1621]: go-librespot daemon starting... Dec 26 17:01:08 vol go-librespot[1622]: time="2025-12-26T17:01:08+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:08 vol go-librespot[1622]: time="2025-12-26T17:01:08+09:00" level=debug msg="app state loaded" Dec 26 17:01:08 vol go-librespot[1622]: time="2025-12-26T17:01:08+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:09 vol volumio[1272]: info: go-librespot daemon successfully initialized Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=info msg="zeroconf server listening on port 34221" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=debug msg="obtained new client token: AABhB80M0W87mqPL6ICRTlYGDj5OYo0gqGn+InTl8PKtoThSKUu6gCe4zjibnORm5QwqxQvh/Im2ZDjuFfLAlBXQbrApN2O5OEhVh+S3d64noCKiS7XqyyhMcoaBVrrrfPtCCoIoSyRrAvvxG/YJVLgGldicMwoi3WwN/xKvAV5bU89/JAmUn2LMzUqGVIy/0rKfJl7Ai818W1x/NRz62KaWV7v3+Yq1WnpiEWaAc36tSCARA+Yr4WA6" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=debug msg="completed challenge" Dec 26 17:01:09 vol go-librespot[1622]: time="2025-12-26T17:01:09+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:09 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:09 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:09 vol sudo[1645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 17:01:09 vol sudo[1645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:09 vol sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 17:01:09 vol sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:09 vol systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 17:01:09 vol mpd_monitor.sh[1650]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 17:01:09 vol sudo[1645]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:09 vol volumio[1272]: info: Successfully started MPD Monitor Dec 26 17:01:09 vol systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 17:01:09 vol systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 17:01:09 vol systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 17:01:09 vol systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 17:01:09 vol mpd_monitor.sh[1659]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 17:01:09 vol sudo[1649]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:10 vol volumio[1272]: info: Successfully started MPD Monitor Dec 26 17:01:11 vol systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 26 17:01:11 vol systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 26 17:01:12 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:12 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:12 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 26 17:01:12 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:12 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:12 vol go-librespot[1686]: go-librespot daemon starting... Dec 26 17:01:12 vol go-librespot[1687]: time="2025-12-26T17:01:12+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:12 vol go-librespot[1687]: time="2025-12-26T17:01:12+09:00" level=debug msg="app state loaded" Dec 26 17:01:12 vol go-librespot[1687]: time="2025-12-26T17:01:12+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:13 vol systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 26 17:01:13 vol systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 17:01:13 vol systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 17:01:13 vol sudo[1579]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:13 vol volumio[1272]: info: Upmpdcli Daemon Started Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=info msg="zeroconf server listening on port 43095" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=debug msg="obtained new client token: AACIaRHaN7f3nR2e/RnT8MLRjQ6n0wn24Z/37sCToDyRufL+mt3Ir0Rmd3RdTcrVZn6OKEdbOmshIddxhqHHkgbTLUPnOXjlT8q0j+kGPPfK8EmSO6jKyOn1wgpxuKf6Vc2eqbxFjNOuThF5aA8yCT6JnKT6OHPrsjNZg9COBQeLnTWI7u4YozC0/HAPmNzdIwiouoBCc81KVlhsfTnuNozi9fsxKy/oC+NAW/r5kHTSejY/n7lY2mZA" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=debug msg="completed challenge" Dec 26 17:01:13 vol go-librespot[1687]: time="2025-12-26T17:01:13+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:13 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:13 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:15 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:15 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 26 17:01:15 vol volumio[1272]: info: Adding plugin bluetooth to MyMusic Plugins Dec 26 17:01:15 vol volumio[1272]: info: Adding plugin multiroom to MyMusic Plugins Dec 26 17:01:15 vol volumio[1272]: info: Adding plugin metavolumio to MyMusic Plugins Dec 26 17:01:15 vol volumio[1272]: info: Adding plugin cd_controller to MyMusic Plugins Dec 26 17:01:15 vol volumio[1272]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 26 17:01:15 vol volumio[1272]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 26 17:01:15 vol volumio[1272]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 26 17:01:15 vol volumio[1272]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 26 17:01:16 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 26 17:01:16 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:16 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:16 vol go-librespot[1712]: go-librespot daemon starting... Dec 26 17:01:16 vol go-librespot[1713]: time="2025-12-26T17:01:16+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:16 vol go-librespot[1713]: time="2025-12-26T17:01:16+09:00" level=debug msg="app state loaded" Dec 26 17:01:16 vol go-librespot[1713]: time="2025-12-26T17:01:16+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=info msg="zeroconf server listening on port 38505" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=debug msg="obtained new client token: AAB3HakglqTQlrob9xaGcP1UYxktUUkSwox//cxdnqX0D3aIoXu4fIk3dBh+0eIT9ITO5hTjuyO2K3p7ZqA8dNhqtcxPI9Wnbrai4NsJpgrh/jhxosex9UWu+Xa7LkI2kqEBvm5KApL38uu/Qwtbe6BfWlPz902zINuayKvW269rh5HKRYkVXfUKYeYOasFIROgg9cV2F5WXe7mfKnQOweJaP1DxRBtLKT6Z2iZmk3cAiFHhVec5DHES" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=debug msg="completed challenge" Dec 26 17:01:17 vol go-librespot[1713]: time="2025-12-26T17:01:17+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:17 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:17 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:20 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 26 17:01:20 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:20 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:20 vol go-librespot[1734]: go-librespot daemon starting... Dec 26 17:01:20 vol go-librespot[1735]: time="2025-12-26T17:01:20+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:20 vol go-librespot[1735]: time="2025-12-26T17:01:20+09:00" level=debug msg="app state loaded" Dec 26 17:01:20 vol go-librespot[1735]: time="2025-12-26T17:01:20+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=info msg="zeroconf server listening on port 44597" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=debug msg="obtained new client token: AAC88ia5TkBYZwlXzEbL2A2K9KuRbW7xzTJ1jEbnACU9TKWRXlNn0BXLUTyRV/U0K1kwjTXp1rcT2iAsMuiJhNQ2XGHLu72g9u2f+rAjsWFfHZhbY69ViTjp3mSzE7eTYkucJ6HHdW7wxrYUaWQCXzWViyAh2SZvxHv5Gy/ioDXYmzl1g/+zg8Wb6TtDVTo/MML2UVpyUgyKS2aQGyUVQwlh3BRznasB95ViwG1WtATtUzouJ+AbtUrB" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=debug msg="completed challenge" Dec 26 17:01:21 vol go-librespot[1735]: time="2025-12-26T17:01:21+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:21 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:21 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:22 vol volumio[1272]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 26 17:01:22 vol volumio[1272]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 26 17:01:22 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:22 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:22 vol volumio[1272]: info: Starting MyVolumio Remote Streaming Endpoints Dec 26 17:01:22 vol volumio[1272]: info: MyVolumio login type: Token Dec 26 17:01:22 vol volumio[1272]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 26 17:01:22 vol volumio[1272]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 26 17:01:24 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 26 17:01:24 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:24 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:24 vol go-librespot[1742]: go-librespot daemon starting... Dec 26 17:01:24 vol go-librespot[1743]: time="2025-12-26T17:01:24+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:24 vol go-librespot[1743]: time="2025-12-26T17:01:24+09:00" level=debug msg="app state loaded" Dec 26 17:01:24 vol go-librespot[1743]: time="2025-12-26T17:01:24+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=info msg="zeroconf server listening on port 38687" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=debug msg="obtained new client token: AAAJhOtHTwyOoL0QFrPG7YVO/PRn6qJFKMTsWcxWVuYjjNrC/4zw0i51kkLQ4YbubojKukoH3Y5frlE++JktnxXblXVJVqmR9kSXeK2kNI1drafxQSsLvnQ6ZY0FlVeMKvZmwcvllnE2mJXh5U5dcSKcE6Be8fKDitVnfyoMhBYuSRgTMu8jg7A76rDwdDcbhyaLoYhN9UU5dbiFYYAQb+Y81U7EPQQ+/5b8pRr3tzbpwa/vfr8xws7y" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=debug msg="completed challenge" Dec 26 17:01:25 vol go-librespot[1743]: time="2025-12-26T17:01:25+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:25 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:25 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:28 vol volumio[1272]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 26 17:01:28 vol volumio[1272]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 26 17:01:28 vol volumio[1272]: info: Streaming services startup Dec 26 17:01:28 vol volumio[1272]: info: Starting Streaming Daemon Dec 26 17:01:28 vol volumio[1272]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 26 17:01:28 vol sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 17:01:28 vol sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:28 vol sudo[1751]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:28 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:28 vol volumio[1272]: error: Cannot start Volumio Streaming Daemon Dec 26 17:01:28 vol volumio[1272]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 17:01:28 vol volumio[1272]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 17:01:28 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 26 17:01:28 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:28 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:28 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:28 vol go-librespot[1757]: go-librespot daemon starting... Dec 26 17:01:28 vol go-librespot[1758]: time="2025-12-26T17:01:28+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:28 vol go-librespot[1758]: time="2025-12-26T17:01:28+09:00" level=debug msg="app state loaded" Dec 26 17:01:28 vol go-librespot[1758]: time="2025-12-26T17:01:28+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=info msg="zeroconf server listening on port 40447" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=debug msg="obtained new client token: AAC+tfQNpiX1sGgaeYkQpPiZCz0Z/KG8nsYooYLIVBy4CHe7frs2/hyI5JoBPK3gAV8dfYmTb3XkxLo+JWuCod9iez9MIdwjEoQx821+LHMDygfoab8bbQIvkkwwcaMThDFwRxQuaRrYOIeflSd8xrdYpvihbbRm4074T0fJ3EfSpQ68knOe7YSpFs349BKh83Yxotbvp3gJJ/O93JUs+tnGGY9uPZaOjyVA+H8mypW2jL4n6RpyI/lW" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=debug msg="completed challenge" Dec 26 17:01:29 vol go-librespot[1758]: time="2025-12-26T17:01:29+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:29 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:29 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:30 vol volumio[1272]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 26 17:01:31 vol sudo[1784]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 17:01:31 vol sudo[1786]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 17:01:31 vol sudo[1784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:31 vol sudo[1786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:31 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:31 vol sudo[1784]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:31 vol sudo[1786]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:31 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:32 vol volumio[1272]: info: MyVolumio token set successfully Dec 26 17:01:32 vol volumio[1272]: info: MYVOLUMIO: Adding device Dec 26 17:01:32 vol volumio[1272]: info: MYVOLUMIO: Evaluating Server Dec 26 17:01:32 vol volumio[1272]: verbose: New Socket.io Connection to 192.168.11.62 from 192.168.11.60 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 26 17:01:32 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 26 17:01:32 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:32 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:32 vol go-librespot[1795]: go-librespot daemon starting... Dec 26 17:01:32 vol go-librespot[1798]: time="2025-12-26T17:01:32+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:32 vol go-librespot[1798]: time="2025-12-26T17:01:32+09:00" level=debug msg="app state loaded" Dec 26 17:01:32 vol go-librespot[1798]: time="2025-12-26T17:01:32+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=info msg="zeroconf server listening on port 34227" Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=debug msg="obtained new client token: AAAuzvoPcEWuQXWgoLj674J6OjktmFcA7ROU42eTkEqNgf9wiU8D5ErvzDvBl26A1Ul85Tb4Rph+2JBYpEtO7N3e63bBPvyuRJ/fCk89pPK7NBhmIWw7a4tw9T1H7YXmR8HeKfrdPKvySPM1yKfZ1XT3MboZqOfMRPKmlfa/BYAPAmIw8lNKyEopNPuX4W7r7PT61mcuTNEX3RApEPxgGRaTGN9G8xiRt0Be4bWlthAAJt3b3tf5lsLj" Dec 26 17:01:33 vol volumio[1272]: info: MyVolumio status changed Dec 26 17:01:33 vol volumio[1272]: info: Streaming services startup Dec 26 17:01:33 vol volumio[1272]: info: Starting Streaming Daemon Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Dec 26 17:01:33 vol volumio[1272]: info: Removing browser output: myVolumio user plan is not superstar Dec 26 17:01:33 vol volumio[1272]: info: Removing audio output: Dec 26 17:01:33 vol volumio[1272]: info: Stoppping Tunnel 1 Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=debug msg="connected to ap-gae2.spotify.com:443" Dec 26 17:01:33 vol sudo[1819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 17:01:33 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 17:01:33 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:33 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 17:01:33 vol sudo[1821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 26 17:01:33 vol sudo[1821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:33 vol sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=debug msg="completed challenge" Dec 26 17:01:33 vol go-librespot[1798]: time="2025-12-26T17:01:33+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:33 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:33 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:33 vol sudo[1819]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:33 vol volumio[1272]: info: Setting Geolocation for MyVolumio to as1 Dec 26 17:01:33 vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:33 vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:33 vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:33 vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:33 vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:33 vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 17:01:33 vol sudo[1821]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 17:01:34 vol volumio-remote-updater[837]: Test mode disabled Dec 26 17:01:34 vol volumio-remote-updater[837]: Alpha mode disabled Dec 26 17:01:34 vol volumio-remote-updater[837]: Alpha legacy test mode disabled Dec 26 17:01:34 vol volumio[1272]: info: Remote SSH Stopped Dec 26 17:01:34 vol volumio[1272]: error: Cannot start Volumio Streaming Daemon Dec 26 17:01:34 vol volumio[1272]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 17:01:34 vol volumio[1272]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 17:01:34 vol volumio[1272]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 17:01:34 vol sudo[1829]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 17:01:34 vol sudo[1831]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 17:01:34 vol sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:34 vol sudo[1829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 17:01:34 vol sudo[1829]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:34 vol sudo[1831]: pam_unix(sudo:session): session closed for user root Dec 26 17:01:34 vol volumio[1272]: verbose: New Socket.io Connection to 192.168.11.62 from 192.168.11.60 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:34 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 17:01:34 vol volumio[1272]: info: Received Get System Info Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:01:34 vol volumio[1272]: info: Discovery: Getting this device information Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:34 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:01:34 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:34 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:35 vol volumio[1272]: info: Listing playlists Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 17:01:35 vol volumio[1272]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 17:01:35 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:35 vol volumio[1272]: info: Updating MyVolumio device info Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 26 17:01:35 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:35 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:35 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:35 vol volumio[1272]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 17:01:36 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 17:01:36 vol volumio[1272]: info: Received Get System Info Dec 26 17:01:36 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:01:36 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:01:36 vol volumio[1272]: info: Discovery: Getting this device information Dec 26 17:01:36 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:36 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:36 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:01:36 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 26 17:01:37 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 26 17:01:37 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:37 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:37 vol go-librespot[1835]: go-librespot daemon starting... Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="app state loaded" Dec 26 17:01:37 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:37 vol volumio[1272]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 26 17:01:37 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=info msg="zeroconf server listening on port 41045" Dec 26 17:01:37 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:37 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="obtained new client token: AAC7/MASxozrgD0R06dnua+hZmkoRu3DMJ5WqGmH4kOjcJFaGqSFZflh3eFiaNp56YkZAEODZ5St0Xajy33pKdjw51x6Fuink5RECzY4QE5IgLd3C35JYrUnJwlE7fH3BiDnxJQ6SXID8s7OU4laASMsQkfnZMDSIsZ6atKtQbXpFDTR+El7aA5Is39y776kC4aiFTucNhng5ocNO/PBTesOshcx4GWVnmrp5szNkV7nfl8oxGMweGVB" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=debug msg="completed challenge" Dec 26 17:01:37 vol go-librespot[1836]: time="2025-12-26T17:01:37+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:37 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:37 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:38 vol volumio[1272]: info: MYVOLUMIO: Adding device Dec 26 17:01:38 vol volumio[1272]: info: MYVOLUMIO: Evaluating Server Dec 26 17:01:39 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:39 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:39 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 17:01:39 vol volumio[1272]: info: Received Get System Info Dec 26 17:01:39 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:01:39 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:01:39 vol volumio[1272]: info: Discovery: Getting this device information Dec 26 17:01:39 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:39 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:39 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:01:40 vol volumio[1272]: info: Setting Geolocation for MyVolumio to as1 Dec 26 17:01:40 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:40 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:40 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:40 vol volumio[1272]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 17:01:40 vol volumio[1272]: info: CoreCommandRouter::volumioGetQueue Dec 26 17:01:40 vol volumio[1272]: info: CoreStateMachine::getQueue Dec 26 17:01:40 vol volumio[1272]: info: CorePlayQueue::getQueue Dec 26 17:01:41 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 26 17:01:41 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:41 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:41 vol go-librespot[1869]: go-librespot daemon starting... Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=debug msg="app state loaded" Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:41 vol volumio[1272]: info: Updating MyVolumio device info Dec 26 17:01:41 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:41 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:41 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=info msg="zeroconf server listening on port 36519" Dec 26 17:01:41 vol upmpdcli[1883]: writing RSA key Dec 26 17:01:41 vol go-librespot[1875]: time="2025-12-26T17:01:41+09:00" level=debug msg="obtained new client token: AACcXqQRq6H95bAYndVdXjrVoPM6K0IUPJqMuSmtNmeVZk9T5BIU9m12qesQyiKFxcgtkXkv0/fjxuF9zAbiT7hGDqTFgsXfYxEm6RJb2Md5U1vaf7Cb4HdFdKhb3rWj+cjTpxgxDVkssLYejgqQRM+U9lwN3uuTE9xVU1Tr4jbDUo7xPcKgOL2UrptXzxk54nevhUi+t6lHS8P1AKzvVCKW3uZ9sr3is/jXG3p98/GfwxB1i9y3OAZD" Dec 26 17:01:42 vol go-librespot[1875]: time="2025-12-26T17:01:42+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:42 vol volumio[1272]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 17:01:42 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:42 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:42 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 17:01:42 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 17:01:42 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 26 17:01:42 vol volumio[1272]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 17:01:42 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 17:01:42 vol go-librespot[1875]: time="2025-12-26T17:01:42+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:42 vol go-librespot[1875]: time="2025-12-26T17:01:42+09:00" level=debug msg="completed challenge" Dec 26 17:01:42 vol go-librespot[1875]: time="2025-12-26T17:01:42+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:42 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:42 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:42 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:42 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:44 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 26 17:01:45 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:45 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:45 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 26 17:01:45 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:45 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:45 vol go-librespot[1888]: go-librespot daemon starting... Dec 26 17:01:45 vol go-librespot[1889]: time="2025-12-26T17:01:45+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:45 vol go-librespot[1889]: time="2025-12-26T17:01:45+09:00" level=debug msg="app state loaded" Dec 26 17:01:45 vol go-librespot[1889]: time="2025-12-26T17:01:45+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:45 vol go-librespot[1889]: time="2025-12-26T17:01:45+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:45 vol go-librespot[1889]: time="2025-12-26T17:01:45+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:45 vol go-librespot[1889]: time="2025-12-26T17:01:45+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:45 vol go-librespot[1889]: time="2025-12-26T17:01:45+09:00" level=info msg="zeroconf server listening on port 40765" Dec 26 17:01:46 vol go-librespot[1889]: time="2025-12-26T17:01:46+09:00" level=debug msg="obtained new client token: AAC0zUu9FEjfXi2dX80eI+spCx/RfQuNyzuu5Az760J8fCnLo3Ax/8VaYdLLBmHCKMrDOS3Yag8KZRBPW6f8lrF4kiH/3YN63MIGw2l+bN8G/ntwPXthEKLTo9/blW6BlWmGkPg73YjoYG+R3H15ilO7+9XobdOhvNEng6DS7ilwsll8Y5Olgcxq8hoffJm4XHwo6UiFPla7bB7sIYCXgglev2u/aNY1KG8W+BAeRmU5OWyyQuWDxw==" Dec 26 17:01:46 vol go-librespot[1889]: time="2025-12-26T17:01:46+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:46 vol go-librespot[1889]: time="2025-12-26T17:01:46+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:46 vol go-librespot[1889]: time="2025-12-26T17:01:46+09:00" level=debug msg="completed challenge" Dec 26 17:01:46 vol go-librespot[1889]: time="2025-12-26T17:01:46+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:46 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:46 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:46 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 26 17:01:46 vol volumio[1272]: info: CURURI: music-library/USB/_g_____F Dec 26 17:01:46 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1303-魚.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1304-祝い.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1306-発酵.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1308-涼.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1309-屋台.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1310-行楽.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1402-乾き物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1411-食感(2).mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1502-長い食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1504-丸い食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1504-角がある食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1506-ベストマッチ.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1507-夏休み.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1508-光と影の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1509-魂の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1510-甘い食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1604-宴の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1605-華のある食べもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1607-港町の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1610-脇役の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1611-備えの食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1612-おいしい脇役.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1701-伝統の食べ物.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1702-酒.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Preloading song: music-library/USB/_g_____F/音楽遊覧－特別.mp3 Dec 26 17:01:46 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 in service mpd Dec 26 17:01:46 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1210-秋のくだもの.mp3' Dec 26 17:01:46 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 in service mpd Dec 26 17:01:46 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1211-コーヒー.mp3' Dec 26 17:01:46 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 in service mpd Dec 26 17:01:46 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3' Dec 26 17:01:46 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1303-魚.mp3 in service mpd Dec 26 17:01:46 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1303-魚.mp3' Dec 26 17:01:46 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1304-祝い.mp3 in service mpd Dec 26 17:01:46 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1304-祝い.mp3' Dec 26 17:01:46 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 in service mpd Dec 26 17:01:46 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1305-緑のたべもの.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1306-発酵.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1306-発酵.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1308-涼.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1308-涼.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1309-屋台.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1309-屋台.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1310-行楽.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1310-行楽.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1401-赤と黒.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1402-乾き物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1402-乾き物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1403-世界のシーフード.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1404-甘酸っぱい.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1405-お袋の味.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1407-水辺の食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1409-小さな食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1410-秋色の食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:47 vol volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::ClearQueue Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:47 vol volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 26 17:01:47 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:47 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:47 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:47 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:47 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:47 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:47 vol volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::play index 0 Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:47 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:47 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:47 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1303-魚.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1303-魚.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1304-祝い.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1304-祝い.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1306-発酵.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1306-発酵.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1308-涼.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1308-涼.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1309-屋台.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1309-屋台.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1310-行楽.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1310-行楽.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1402-乾き物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1402-乾き物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1411-食感(2).mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1411-食感(2).mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1411-食感(2).mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1502-長い食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1502-長い食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1502-長い食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1504-丸い食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1504-丸い食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1504-丸い食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1504-角がある食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1504-角がある食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1504-角がある食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1506-ベストマッチ.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1506-ベストマッチ.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1506-ベストマッチ.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1507-夏休み.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1507-夏休み.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1507-夏休み.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1508-光と影の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1508-光と影の食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1508-光と影の食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1509-魂の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1509-魂の食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1509-魂の食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1510-甘い食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1510-甘い食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1510-甘い食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3 in service mpd Dec 26 17:01:47 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3' Dec 26 17:01:47 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1604-宴の食べ物.mp3 Dec 26 17:01:47 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1604-宴の食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1604-宴の食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1605-華のある食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1605-華のある食べもの.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1605-華のある食べもの.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1607-港町の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1607-港町の食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1607-港町の食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1610-脇役の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1610-脇役の食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1610-脇役の食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1611-備えの食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1611-備えの食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1611-備えの食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1612-おいしい脇役.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1612-おいしい脇役.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1612-おいしい脇役.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1701-伝統の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1701-伝統の食べ物.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1701-伝統の食べ物.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1702-酒.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1702-酒.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1702-酒.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3' Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/音楽遊覧－特別.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F/音楽遊覧－特別.mp3 in service mpd Dec 26 17:01:48 vol volumio[1272]: error: scanFolder - failure to stat '/mnt/USB/_g_____F/音楽遊覧－特別.mp3' Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::play index undefined Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:48 vol volumio[1272]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 26 17:01:48 vol volumio[1272]: info: Completed starting MyVolumio Plugin Dec 26 17:01:48 vol volumio[1272]: [Metrics] CommandRouter: 60s 992.37ms Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::Close All Modals sent Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::Close All Modals sent Dec 26 17:01:48 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::ClearQueue Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:48 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::play index 0 Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:48 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1303-魚.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1303-魚.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1304-祝い.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1304-祝い.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1306-発酵.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1306-発酵.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1308-涼.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1308-涼.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1309-屋台.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1309-屋台.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1310-行楽.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1310-行楽.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1402-乾き物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1402-乾き物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1411-食感(2).mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1411-食感(2).mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1502-長い食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1502-長い食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1504-丸い食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1504-丸い食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1504-角がある食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1504-角がある食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1506-ベストマッチ.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1506-ベストマッチ.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1507-夏休み.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1507-夏休み.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1508-光と影の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1508-光と影の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1509-魂の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1509-魂の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1510-甘い食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1510-甘い食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1604-宴の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1604-宴の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1605-華のある食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1605-華のある食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1607-港町の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1607-港町の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1610-脇役の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1610-脇役の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1611-備えの食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1611-備えの食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1612-おいしい脇役.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1612-おいしい脇役.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1701-伝統の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1701-伝統の食べ物.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1702-酒.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1702-酒.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/音楽遊覧－特別.mp3 Dec 26 17:01:48 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/音楽遊覧－特別.mp3 Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:48 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::play index undefined Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:48 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:48 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:48 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:48 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:49 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 26 17:01:49 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 17:01:49 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 26 17:01:49 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 26 17:01:49 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:49 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:49 vol go-librespot[1901]: go-librespot daemon starting... Dec 26 17:01:49 vol go-librespot[1902]: time="2025-12-26T17:01:49+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:49 vol go-librespot[1902]: time="2025-12-26T17:01:49+09:00" level=debug msg="app state loaded" Dec 26 17:01:49 vol go-librespot[1902]: time="2025-12-26T17:01:49+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:49 vol go-librespot[1902]: time="2025-12-26T17:01:49+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:49 vol go-librespot[1902]: time="2025-12-26T17:01:49+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:49 vol go-librespot[1902]: time="2025-12-26T17:01:49+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:49 vol go-librespot[1902]: time="2025-12-26T17:01:49+09:00" level=info msg="zeroconf server listening on port 39883" Dec 26 17:01:50 vol go-librespot[1902]: time="2025-12-26T17:01:50+09:00" level=debug msg="obtained new client token: AACsdYFeyfryS5NxNZ8G54BzmVtVEIOBJo97B/DqeZI3UJbI9neBOm8RTkhKH2bk8Lgv5bUCla6WGNyr++kX6iEqTV6iceIeTHsQsnpHNlj+iGFDHGw9w7CYZaCUopfTu1BmVaHrAsa3kayoOg0+XJq5rj3s4DoUX8SOEO459GtoytTAOswRGc5zBVSu6p8Jprwg+IzyGc7UZTZuvPRa7bgj5RLpFfYyjxjTZQEPa9RvxUeEicpaZg==" Dec 26 17:01:50 vol go-librespot[1902]: time="2025-12-26T17:01:50+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:50 vol go-librespot[1902]: time="2025-12-26T17:01:50+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:50 vol go-librespot[1902]: time="2025-12-26T17:01:50+09:00" level=debug msg="completed challenge" Dec 26 17:01:50 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 26 17:01:50 vol volumio[1272]: info: CURURI: music-library Dec 26 17:01:50 vol go-librespot[1902]: time="2025-12-26T17:01:50+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:50 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:50 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:50 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:51 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:51 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:52 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 26 17:01:52 vol volumio[1272]: info: CURURI: music-library/USB Dec 26 17:01:52 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:53 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::ClearQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:53 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:53 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F Dec 26 17:01:53 vol volumio[1272]: info: Exploding uri music-library/USB/_g_____F in service mpd Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::play index 0 Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::play index undefined Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:53 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 26 17:01:53 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:53 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:53 vol go-librespot[1926]: go-librespot daemon starting... Dec 26 17:01:53 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::ClearQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:53 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:53 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F Dec 26 17:01:53 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:53 vol go-librespot[1927]: time="2025-12-26T17:01:53+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:53 vol go-librespot[1927]: time="2025-12-26T17:01:53+09:00" level=debug msg="app state loaded" Dec 26 17:01:53 vol go-librespot[1927]: time="2025-12-26T17:01:53+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:53 vol volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::play index 0 Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::play index undefined Dec 26 17:01:53 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:53 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=info msg="zeroconf server listening on port 36153" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=debug msg="obtained new client token: AABwO8+dJDV8paxSKEcjR8vgT4jq1T7JZLkr1EI9e5tnnLmj7p5gj6pb0pf0YV23FxQiu4HXQ1peCogQ1IrHfkh6kVABpwgxHobvwOms3anOIvCShGNLQpoFt5LD5HYN5QeQhqCaCPDIRLxUxX3Sq3RGYH0TdhXynSX+eakh1cPjb5eSs9/z1S3657vbEVzdG5mKq1zkq8D5z0Q/c4calaq3p+FjthPyJV0WPQdwta5MULMxw09kDg==" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=debug msg="completed challenge" Dec 26 17:01:54 vol go-librespot[1927]: time="2025-12-26T17:01:54+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:54 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:54 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:54 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::ClearQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:54 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:54 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F Dec 26 17:01:54 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::play index 0 Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::play index undefined Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:54 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:54 vol volumio[1272]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 17:01:54 vol volumio[1272]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 26 17:01:54 vol volumio[1272]: info: Received Get System Version Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 17:01:54 vol volumio[1272]: info: Received Get System Info Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 17:01:54 vol volumio[1272]: info: Discovery: Getting this device information Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 17:01:54 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::ClearQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:54 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:54 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F Dec 26 17:01:54 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:54 vol volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::play index 0 Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::play index undefined Dec 26 17:01:54 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:54 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:55 vol volumio[1272]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 26 17:01:55 vol volumio[1272]: info: CURURI: music-library/USB/_g_____F Dec 26 17:01:55 vol volumio[1272]: info: BOOT COMPLETED Dec 26 17:01:55 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:55 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:55 vol volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::ClearQueue Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::clearPlayQueue Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:55 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:55 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1210-秋のくだもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:55 vol volumio[1272]: info: CoreCommandRouter::volumioPlay Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::play index 0 Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::addQueueItems Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::addQueueItems Dec 26 17:01:55 vol volumio[1272]: info: Preload queue cleared Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1211-コーヒー.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1301-ハーブ＆スパイス.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1303-魚.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1303-魚.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1304-祝い.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1304-祝い.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1305-緑のたべもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1306-発酵.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1306-発酵.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1308-涼.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1308-涼.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1309-屋台.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1309-屋台.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1310-行楽.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1310-行楽.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1312-2013一番美味しかったもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1401-赤と黒.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1402-乾き物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1402-乾き物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1403-世界のシーフード.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1404-甘酸っぱい.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1405-お袋の味.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1406-しっとりした食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1407-夏をのり切る食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1407-水辺の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1409-小さな食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1410-秋色の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1410-食感を刺激する食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1411-食感(2).mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1411-食感(2).mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1412-2014一番美味しかった食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1502-長い食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1502-長い食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1503-白っぽい食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1504-丸い食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1504-丸い食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1504-角がある食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1504-角がある食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1505-ジューシーな食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1506-ベストマッチ.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1506-ベストマッチ.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1507-夏休み.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1507-夏休み.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1508-光と影の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1508-光と影の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1509-魂の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1509-魂の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1510-甘い食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1510-甘い食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1511-川のある町の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1512-お祝いの食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1601-ホットと名の付く食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1602-Ｂ級グルメ.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1603-パステルカラーの食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1604-宴の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1604-宴の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1605-華のある食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1605-華のある食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1606-酸っぱい食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1607-港町の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1607-港町の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1608-わざわざ食べに行きたい食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1609-旅のお供の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1610-脇役の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1610-脇役の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1611-備えの食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1611-備えの食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1612-2016心に残る食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1612-おいしい脇役.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1612-おいしい脇役.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1701-伝統の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1701-伝統の食べ物.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1702-酒.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1702-酒.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/吉村－1703-ぼくの、わたしの好きな食べもの.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Adding Item to queue: music-library/USB/_g_____F/音楽遊覧－特別.mp3 Dec 26 17:01:55 vol volumio[1272]: info: Using cached record of: music-library/USB/_g_____F/音楽遊覧－特別.mp3 Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::stop Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:55 vol volumio[1272]: info: CoreCommandRouter::volumioPushQueue Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::saveQueue Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::play index undefined Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 17:01:55 vol volumio[1272]: info: CoreStateMachine::updateTrackBlock Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::getTrackBlock Dec 26 17:01:55 vol volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 26 17:01:57 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 26 17:01:57 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:57 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:01:57 vol go-librespot[1935]: go-librespot daemon starting... Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=debug msg="app state loaded" Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:01:57 vol volumio[1272]: info: Initializing connection to go-librespot Websocket Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=debug msg="new websocket client" Dec 26 17:01:57 vol volumio[1272]: info: Connection to go-librespot Websocket established Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:01:57 vol go-librespot[1936]: time="2025-12-26T17:01:57+09:00" level=info msg="zeroconf server listening on port 40195" Dec 26 17:01:58 vol go-librespot[1936]: time="2025-12-26T17:01:58+09:00" level=debug msg="obtained new client token: AACr/wxlYYPE9a28OkSCCLvvuTkjvvMHiMG5X/ijdxyJV19rLLIT2dmgMtUcgFVo3tv+7nzWq+yHRbRO++o6l3hyzvGZvkoJ3pBpDjb7+fHvDoZhkjmoEPjJ1vVUSV6Xdi7DL0LNgwHJzAv34G4RhvTno+RpBy8fOMY70fxzeSf/oR81xC46iBL0/0snfpMyIuD0GOonL7opKx9EYEzCl60x8yT5QFZenbOe1SGU0+ofm9yCNG88hA==" Dec 26 17:01:58 vol go-librespot[1936]: time="2025-12-26T17:01:58+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:01:58 vol go-librespot[1936]: time="2025-12-26T17:01:58+09:00" level=debug msg="completed keyexchange" Dec 26 17:01:58 vol go-librespot[1936]: time="2025-12-26T17:01:58+09:00" level=debug msg="completed challenge" Dec 26 17:01:58 vol go-librespot[1936]: time="2025-12-26T17:01:58+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:01:58 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:01:58 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:01:58 vol volumio[1272]: info: Connection to go-librespot Websocket closed Dec 26 17:02:00 vol volumio[1272]: info: Getting Spotify volume Dec 26 17:02:00 vol volumio[1272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 17:02:00 vol volumio[1272]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 17:02:00 vol volumio[1272]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 26 17:02:00 vol volumio[1272]: errno: -111, Dec 26 17:02:00 vol volumio[1272]: code: 'ECONNREFUSED', Dec 26 17:02:00 vol volumio[1272]: syscall: 'connect', Dec 26 17:02:00 vol volumio[1272]: address: '127.0.0.1', Dec 26 17:02:00 vol volumio[1272]: port: 9879, Dec 26 17:02:00 vol volumio[1272]: response: undefined Dec 26 17:02:00 vol volumio[1272]: } Dec 26 17:02:00 vol volumio[1272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 17:02:01 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 26 17:02:01 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:02:01 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 17:02:01 vol go-librespot[1966]: go-librespot daemon starting... Dec 26 17:02:01 vol go-librespot[1970]: time="2025-12-26T17:02:01+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 17:02:01 vol go-librespot[1970]: time="2025-12-26T17:02:01+09:00" level=debug msg="app state loaded" Dec 26 17:02:01 vol go-librespot[1970]: time="2025-12-26T17:02:01+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=info msg="zeroconf server listening on port 45573" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=debug msg="obtained new client token: AAA0TLQuS+3k3ZcDL7D23LiMy5MJVw9JM1q+oLayqGKYWSlAnfTBMlKwXwla4d4CS+sHHpmIxNEXr3VRo3qMSLT5HXRCWXKm6UMuCT/Nh9YwuLtZbWznk/dDE5Te+Hl101TcTKBJQcdSS8oT9biZnAaVglOCXGDtpoiMX8Z3uSHscaeAHaJVFWIijXfb7kqWLBoRRglk5vjQb7Wo1pPwxn2WNG9eqN3TYnTVf1ULZIcflJ1Wci5cCw==" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=debug msg="completed keyexchange" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=debug msg="completed challenge" Dec 26 17:02:02 vol go-librespot[1970]: time="2025-12-26T17:02:02+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 17:02:02 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 17:02:02 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 17:02:04 vol sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 17:01' Dec 26 17:02:04 vol sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"