Dec 06 02:20:00 volumio volumio[1137]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Dec 06 02:20:03 volumio upmpdcli[3367]: writing RSA key Dec 06 02:20:05 volumio volumio[1137]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Dec 06 02:20:08 volumio volumio[1137]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Dec 06 02:20:15 volumio volumio[1137]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Dec 06 02:20:15 volumio volumio[1137]: info: Folder /tmp/plugins removed Dec 06 02:20:16 volumio volumio[1137]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Dec 06 02:20:16 volumio volumio[1137]: info: Folder /tmp/plugins removed Dec 06 02:20:19 volumio volumio[1137]: info: Check plugin dependencies Dec 06 02:20:19 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 02:20:19 volumio volumio[1137]: info: Checking if plugin already exists Dec 06 02:20:19 volumio volumio[1137]: info: Rename folder Dec 06 02:20:19 volumio volumio[1137]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 02:20:19 volumio volumio[1137]: info: Move to category Dec 06 02:20:29 volumio volumio[1137]: info: Checking if install.sh is present Dec 06 02:20:29 volumio volumio[1137]: info: Executing install.sh Dec 06 02:20:30 volumio sudo[3433]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh Dec 06 02:20:30 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 02:20:30 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root Dec 06 02:20:30 volumio volumio[1137]: info: Skipping Deno installation - not supported on armhf Dec 06 02:20:30 volumio volumio[1137]: info: YouTube Music plugin installed Dec 06 02:20:30 volumio volumio[1137]: Plugin install end detected on script Dec 06 02:20:30 volumio volumio[1137]: info: Install script completed Dec 06 02:20:30 volumio volumio[1137]: info: Adding reference to registry Dec 06 02:20:30 volumio volumio[1137]: info: Done installing plugin. Dec 06 02:20:30 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 02:20:30 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 02:20:30 volumio volumio[1137]: info: Folder /tmp/plugins removed Dec 06 02:20:30 volumio volumio[1137]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 02:20:30 volumio volumio[1137]: info: Folder /data/temp removed Dec 06 02:20:34 volumio volumio[1137]: info: Enabling plugin ytmusic Dec 06 02:20:34 volumio volumio[1137]: info: Loading plugin "ytmusic"... Dec 06 02:20:35 volumio volumio[1137]: info: PLUGIN START: ytmusic Dec 06 02:20:35 volumio volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 02:20:35 volumio volumio[1137]: info: [1764967835734] CoreMusicLibrary::Adding element YouTube Music Dec 06 02:20:35 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 02:20:35 volumio volumio[1137]: Cannot find translation for source YouTube Music Dec 06 02:20:35 volumio volumio[1137]: info: Done. Dec 06 02:20:35 volumio volumio[1137]: info: Check plugin dependencies Dec 06 02:20:36 volumio volumio[1137]: error: Error finding native modules: Command failed: find /data/temp/downloaded_plugin/node_modules -name obj.target -prune -false -o -type f -name "*.node" 2>/dev/null {"output":[null,"",""],"pid":3461,"signal":null,"stack":"Error: Command failed: find /data/temp/downloaded_plugin/node_modules -name obj.target -prune -false -o -type f -name \"*.node\" 2>/dev/null\n at checkExecSyncError (node:child_process:887:11)\n at execSync (node:child_process:959:15)\n at PluginManager.checkPluginDependencies (/volumio/app/pluginmanager.js:1149:26)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)","status":1,"stderr":"","stdout":""} Dec 06 02:20:36 volumio volumio[1137]: info: An error occurred installing the plugin. Rolling back config Dec 06 02:20:36 volumio volumio[1137]: info: Plugin folders cleanup Dec 06 02:20:36 volumio volumio[1137]: info: Scanning into folder /volumio/app/plugins/ Dec 06 02:20:36 volumio volumio[1137]: info: Scanning category audio_interface Dec 06 02:20:36 volumio volumio[1137]: info: Scanning category miscellanea Dec 06 02:20:36 volumio volumio[1137]: info: Scanning category music_service Dec 06 02:20:36 volumio volumio[1137]: info: Scanning category plugins.json Dec 06 02:20:36 volumio volumio[1137]: info: Scanning category system_controller Dec 06 02:20:36 volumio volumio[1137]: info: Scanning category user_interface Dec 06 02:20:36 volumio volumio[1137]: info: Scanning into folder /data/plugins/ Dec 06 02:20:36 volumio volumio[1137]: info: Scanning category music_service Dec 06 02:20:36 volumio volumio[1137]: info: Plugin folders cleanup completed Dec 06 02:20:36 volumio volumio[1137]: info: Error: Error Dec 06 02:20:36 volumio volumio[1137]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 02:20:36 volumio volumio[1137]: info: Folder /data/temp removed Dec 06 02:20:36 volumio volumio[1137]: info: Folder /tmp/plugins removed Dec 06 02:20:36 volumio volumio[1137]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 02:20:36 volumio volumio[1137]: info: Enabling plugin ytmusic Dec 06 02:20:36 volumio volumio[1137]: info: Loading plugin "ytmusic"... Dec 06 02:20:36 volumio volumio[1137]: info: PLUGIN START: ytmusic Dec 06 02:20:36 volumio volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 02:20:36 volumio volumio[1137]: info: [1764967836662] CoreMusicLibrary::Adding element YouTube Music Dec 06 02:20:36 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 02:20:36 volumio volumio[1137]: Cannot find translation for source YouTube Music Dec 06 02:20:36 volumio volumio[1137]: info: Done. Dec 06 02:20:42 volumio kernel: hwmon hwmon1: Voltage normalised Dec 06 02:20:44 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 06 02:20:49 volumio volumio[1137]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Dec 06 02:20:53 volumio volumio[1137]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Dec 06 02:20:53 volumio volumio[1137]: info: Folder /tmp/plugins removed Dec 06 02:20:55 volumio volumio[1137]: info: Check plugin dependencies Dec 06 02:20:55 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 02:20:55 volumio volumio[1137]: info: Checking if plugin already exists Dec 06 02:20:55 volumio volumio[1137]: info: Rename folder Dec 06 02:20:55 volumio volumio[1137]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 02:20:55 volumio volumio[1137]: info: Move to category Dec 06 02:20:57 volumio volumio[1137]: info: Checking if install.sh is present Dec 06 02:20:57 volumio volumio[1137]: info: Executing install.sh Dec 06 02:20:58 volumio sudo[3518]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Dec 06 02:20:58 volumio sudo[3518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 02:20:58 volumio volumio[1137]: info: Installing Go-librespot Dec 06 02:20:58 volumio volumio[1137]: info: Checking old vollibrespot installs Dec 06 02:20:58 volumio systemd[1]: Reloading. Dec 06 02:21:00 volumio volumio[1137]: info: Downloading daemon Dec 06 02:21:10 volumio volumio[1137]: info: Creating directories Dec 06 02:21:10 volumio volumio[1137]: info: Creating data directory Dec 06 02:21:10 volumio volumio[1137]: info: Creating Start Script Dec 06 02:21:11 volumio systemd[1]: Reloading. Dec 06 02:21:12 volumio sudo[3518]: pam_unix(sudo:session): session closed for user root Dec 06 02:21:12 volumio volumio[1137]: Plugin install end detected on script Dec 06 02:21:12 volumio volumio[1137]: info: Install script completed Dec 06 02:21:12 volumio volumio[1137]: info: Adding reference to registry Dec 06 02:21:12 volumio volumio[1137]: info: Done installing plugin. Dec 06 02:21:12 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 02:21:12 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 02:21:12 volumio volumio[1137]: info: Folder /tmp/plugins removed Dec 06 02:21:12 volumio volumio[1137]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 02:21:12 volumio volumio[1137]: info: Folder /data/temp removed Dec 06 02:21:15 volumio volumio[1137]: info: Enabling plugin spop Dec 06 02:21:15 volumio volumio[1137]: info: Loading plugin "spop"... Dec 06 02:21:17 volumio volumio[1137]: info: PLUGIN START: spop Dec 06 02:21:17 volumio volumio[1137]: info: Creating Spotify config file Dec 06 02:21:17 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 02:21:17 volumio volumio[1137]: info: Done. Dec 06 02:21:17 volumio volumio[1137]: info: Spotify config file written Dec 06 02:21:17 volumio volumio[1137]: info: No need to fix Spotify hosts Dec 06 02:21:17 volumio sudo[3612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 06 02:21:17 volumio sudo[3612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 02:21:17 volumio 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 06 02:21:17 volumio 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 06 02:21:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:17 volumio go-librespot[3614]: go-librespot daemon starting... Dec 06 02:21:17 volumio sudo[3612]: pam_unix(sudo:session): session closed for user root Dec 06 02:21:17 volumio go-librespot[3615]: time="2025-12-06T02:21:17+05:30" level=info msg="running go-librespot 0.4.0" Dec 06 02:21:17 volumio go-librespot[3615]: time="2025-12-06T02:21:17+05:30" level=debug msg="no app state found" Dec 06 02:21:17 volumio go-librespot[3615]: time="2025-12-06T02:21:17+05:30" level=debug msg="stored credentials not found" Dec 06 02:21:17 volumio go-librespot[3615]: time="2025-12-06T02:21:17+05:30" level=info msg="generated new device id: a511897d6587f9fd35fd1dfd9ee11d55a6594452" Dec 06 02:21:17 volumio go-librespot[3615]: time="2025-12-06T02:21:17+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 02:21:18 volumio go-librespot[3615]: time="2025-12-06T02:21:18+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T02:21:17+05:30 is before 2025-12-08T00:00:00Z" Dec 06 02:21:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 02:21:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 02:21:20 volumio volumio[1137]: info: go-librespot daemon successfully initialized Dec 06 02:21:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 06 02:21:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:21 volumio go-librespot[3623]: go-librespot daemon starting... Dec 06 02:21:21 volumio go-librespot[3624]: time="2025-12-06T02:21:21+05:30" level=info msg="running go-librespot 0.4.0" Dec 06 02:21:21 volumio go-librespot[3624]: time="2025-12-06T02:21:21+05:30" level=debug msg="app state loaded" Dec 06 02:21:21 volumio go-librespot[3624]: time="2025-12-06T02:21:21+05:30" level=debug msg="stored credentials not found" Dec 06 02:21:21 volumio go-librespot[3624]: time="2025-12-06T02:21:21+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 02:21:21 volumio go-librespot[3624]: time="2025-12-06T02:21:21+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T02:21:21+05:30 is before 2025-12-08T00:00:00Z" Dec 06 02:21:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 02:21:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 02:21:23 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 06 02:21:23 volumio volumio[1137]: info: CorePlayQueue::getTrack 4 Dec 06 02:21:23 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 06 02:21:23 volumio volumio[1137]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 02:21:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 06 02:21:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:24 volumio go-librespot[3648]: go-librespot daemon starting... Dec 06 02:21:25 volumio go-librespot[3649]: time="2025-12-06T02:21:25+05:30" level=info msg="running go-librespot 0.4.0" Dec 06 02:21:25 volumio go-librespot[3649]: time="2025-12-06T02:21:25+05:30" level=debug msg="app state loaded" Dec 06 02:21:25 volumio go-librespot[3649]: time="2025-12-06T02:21:25+05:30" level=debug msg="stored credentials not found" Dec 06 02:21:25 volumio go-librespot[3649]: time="2025-12-06T02:21:25+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 02:21:25 volumio go-librespot[3649]: time="2025-12-06T02:21:25+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T02:21:25+05:30 is before 2025-12-08T00:00:00Z" Dec 06 02:21:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 02:21:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 02:21:25 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 02:21:25 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 02:21:26 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 06 02:21:26 volumio volumio[1137]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 02:21:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 06 02:21:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:28 volumio go-librespot[3656]: go-librespot daemon starting... Dec 06 02:21:28 volumio go-librespot[3657]: time="2025-12-06T02:21:28+05:30" level=info msg="running go-librespot 0.4.0" Dec 06 02:21:28 volumio go-librespot[3657]: time="2025-12-06T02:21:28+05:30" level=debug msg="app state loaded" Dec 06 02:21:28 volumio go-librespot[3657]: time="2025-12-06T02:21:28+05:30" level=debug msg="stored credentials not found" Dec 06 02:21:28 volumio go-librespot[3657]: time="2025-12-06T02:21:28+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 02:21:28 volumio go-librespot[3657]: time="2025-12-06T02:21:28+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T02:21:28+05:30 is before 2025-12-08T00:00:00Z" Dec 06 02:21:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 02:21:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 02:21:29 volumio volumio[1137]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 02:21:29 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 06 02:21:29 volumio volumio[1137]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 02:21:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 06 02:21:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:31 volumio go-librespot[3664]: go-librespot daemon starting... Dec 06 02:21:32 volumio go-librespot[3665]: time="2025-12-06T02:21:32+05:30" level=info msg="running go-librespot 0.4.0" Dec 06 02:21:32 volumio go-librespot[3665]: time="2025-12-06T02:21:32+05:30" level=debug msg="app state loaded" Dec 06 02:21:32 volumio go-librespot[3665]: time="2025-12-06T02:21:32+05:30" level=debug msg="stored credentials not found" Dec 06 02:21:32 volumio go-librespot[3665]: time="2025-12-06T02:21:32+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 02:21:32 volumio go-librespot[3665]: time="2025-12-06T02:21:32+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T02:21:32+05:30 is before 2025-12-08T00:00:00Z" Dec 06 02:21:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 02:21:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 02:21:32 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 06 02:21:32 volumio volumio[1137]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 02:21:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 06 02:21:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:35 volumio volumio[1137]: info: Received OAUTH Data Dec 06 02:21:35 volumio volumio[1137]: info: Executing Spotify Oauth Login Dec 06 02:21:35 volumio volumio[1137]: info: Saving Spotify Refresh Token Dec 06 02:21:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:35 volumio go-librespot[3691]: go-librespot daemon starting... Dec 06 02:21:35 volumio go-librespot[3693]: time="2025-12-06T02:21:35+05:30" level=info msg="running go-librespot 0.4.0" Dec 06 02:21:35 volumio go-librespot[3693]: time="2025-12-06T02:21:35+05:30" level=debug msg="app state loaded" Dec 06 02:21:35 volumio go-librespot[3693]: time="2025-12-06T02:21:35+05:30" level=debug msg="stored credentials not found" Dec 06 02:21:35 volumio go-librespot[3693]: time="2025-12-06T02:21:35+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 02:21:35 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 06 02:21:35 volumio go-librespot[3693]: time="2025-12-06T02:21:35+05:30" level=debug msg="new websocket client" Dec 06 02:21:35 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 06 02:21:35 volumio go-librespot[3693]: time="2025-12-06T02:21:35+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T02:21:35+05:30 is before 2025-12-08T00:00:00Z" Dec 06 02:21:35 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 06 02:21:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 02:21:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 02:21:36 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 06 02:21:36 volumio volumio[1137]: SPOTIFY: BQCd-QO9ZsqtbecQWKW28xXEtxaZA7krZ2n01ZXkW8OQFhX3fvafIK_wUZgX2ZAN2_wDnieJ6RgEC0oJav-tne-o08GcgcDTeZWcVL7U3kqVuhDLTV2t_tScz4R8A6FTTfnjfrR-FB8Zh6r4PW7mn7fQUK_N_6-xFVLMeu7iHCUvDTk92JpttACWY-I9DnBcFXeq3pwtk-VAkdNTm7SXHroF4Q5mJ9dZpzFzXVVgVOGr_qcK7PlRE4bntGE4zP66DLiNEwm_sNuF4neUD8j7q1-Q46wSvN3ASZUA35HhPaIR8mtupu-7BGIW Dec 06 02:21:36 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 06 02:21:36 volumio volumio[1137]: info: New Spotify access token = BQCd-QO9ZsqtbecQWKW28xXEtxaZA7krZ2n01ZXkW8OQFhX3fvafIK_wUZgX2ZAN2_wDnieJ6RgEC0oJav-tne-o08GcgcDTeZWcVL7U3kqVuhDLTV2t_tScz4R8A6FTTfnjfrR-FB8Zh6r4PW7mn7fQUK_N_6-xFVLMeu7iHCUvDTk92JpttACWY-I9DnBcFXeq3pwtk-VAkdNTm7SXHroF4Q5mJ9dZpzFzXVVgVOGr_qcK7PlRE4bntGE4zP66DLiNEwm_sNuF4neUD8j7q1-Q46wSvN3ASZUA35HhPaIR8mtupu-7BGIW Dec 06 02:21:36 volumio volumio[1137]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 06 02:21:36 volumio volumio[1137]: error: Failed to retrieve user informations: Error: certificate is not yet valid Dec 06 02:21:36 volumio volumio[1137]: error: Spotify credentials failed to read user data: Dec 06 02:21:36 volumio volumio[1137]: error: Failed to perform Spotify API connection after OAUTH Login: Dec 06 02:21:38 volumio volumio[1137]: info: Getting Spotify volume Dec 06 02:21:38 volumio volumio[1137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 02:21:38 volumio volumio[1137]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 02:21:38 volumio volumio[1137]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 06 02:21:38 volumio volumio[1137]: errno: -111, Dec 06 02:21:38 volumio volumio[1137]: code: 'ECONNREFUSED', Dec 06 02:21:38 volumio volumio[1137]: syscall: 'connect', Dec 06 02:21:38 volumio volumio[1137]: address: '127.0.0.1', Dec 06 02:21:38 volumio volumio[1137]: port: 9879, Dec 06 02:21:38 volumio volumio[1137]: response: undefined Dec 06 02:21:38 volumio volumio[1137]: } Dec 06 02:21:38 volumio volumio[1137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 02:21:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 06 02:21:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 02:21:39 volumio go-librespot[3717]: go-librespot daemon starting... Dec 06 02:21:39 volumio go-librespot[3718]: time="2025-12-06T02:21:39+05:30" level=info msg="running go-librespot 0.4.0" Dec 06 02:21:39 volumio go-librespot[3718]: time="2025-12-06T02:21:39+05:30" level=debug msg="app state loaded" Dec 06 02:21:39 volumio go-librespot[3718]: time="2025-12-06T02:21:39+05:30" level=debug msg="stored credentials not found" Dec 06 02:21:39 volumio go-librespot[3718]: time="2025-12-06T02:21:39+05:30" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 02:21:39 volumio go-librespot[3718]: time="2025-12-06T02:21:39+05:30" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-06T02:21:39+05:30 is before 2025-12-08T00:00:00Z" Dec 06 02:21:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 02:21:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 02:21:40 volumio sudo[3727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 02:20' Dec 06 02:21:40 volumio sudo[3727]: 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"