-- Logs begin at Sat 2025-03-08 17:51:58 UTC, end at Mon 2025-03-17 00:13:54 UTC. -- Mar 17 00:12:18 volumio volumio[863]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 00:12:18 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 17 00:12:18 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 00:12:18 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 00:12:18 volumio volumio[863]: info: Getting Alsa Cards List without I2S DAC Mar 17 00:12:18 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 17 00:12:18 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 17 00:12:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 00:12:41 volumio volumio[863]: info: Retrieving Cloud Streaming UI Mar 17 00:12:41 volumio volumio[863]: info: Getting Tidal Cloud Configuration Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 00:12:41 volumio volumio[863]: info: Getting Qobuz Cloud Configuration Mar 17 00:12:41 volumio volumio[863]: info: Asking plugin for UI Config Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 00:12:41 volumio volumio[863]: info: Getting Spotify Cloud Configuration Mar 17 00:12:41 volumio volumio[863]: info: Asking plugin for UI Config Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 00:12:41 volumio volumio[863]: info: Saving Spotify Acccount Mar 17 00:12:41 volumio volumio[863]: info: Got Tidal Cloud Configuration Mar 17 00:12:41 volumio volumio[863]: info: Got it Mar 17 00:12:41 volumio volumio[863]: info: Got it Mar 17 00:12:41 volumio volumio[863]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 00:12:41 volumio volumio[863]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 00:12:41 volumio volumio[863]: info: CoreCommandRouter::volumioGetBrowseSources Mar 17 00:12:42 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:12:42 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 17 00:12:46 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:12:50 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:12:54 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:12:57 volumio volumio[863]: info: Enabling MyMusic plugin metavolumio Mar 17 00:12:57 volumio volumio[863]: info: [MyVolumio PluginManager] Enabling and starting plugin miscellanea metavolumio Mar 17 00:12:57 volumio volumio[863]: info: [MyVolumio PluginManager] Plugin miscellanea metavolumio not in user plan, not enabling Mar 17 00:12:57 volumio volumio[863]: info: Error: Plugin miscellanea metavolumio not in user plan, not enabling Mar 17 00:12:57 volumio volumio[863]: error: Could not Enable MyMusic Plugin: Error Mar 17 00:12:58 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:13:02 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:13:02 volumio volumio[863]: info: Enabling MyMusic plugin cd_controller Mar 17 00:13:02 volumio volumio[863]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service cd_controller Mar 17 00:13:02 volumio volumio[863]: info: [MyVolumio PluginManager] Plugin music_service cd_controller not in user plan, not enabling Mar 17 00:13:02 volumio volumio[863]: info: Error: Plugin music_service cd_controller not in user plan, not enabling Mar 17 00:13:02 volumio volumio[863]: error: Could not Enable MyMusic Plugin: Error Mar 17 00:13:06 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:13:10 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:13:14 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:13:18 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:13:20 volumio volumio[863]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Mar 17 00:13:20 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Mar 17 00:13:21 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Mar 17 00:13:21 volumio volumio[863]: info: Rebuild Album cache Mar 17 00:13:22 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 00:13:32 volumio volumio[863]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 00:13:32 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 17 00:13:32 volumio volumio[863]: info: Received Get System Version Mar 17 00:13:32 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 00:13:36 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 17 00:13:36 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 17 00:13:49 volumio volumio[863]: info: Enabling plugin spop Mar 17 00:13:49 volumio volumio[863]: info: Loading plugin "spop"... Mar 17 00:13:49 volumio volumio[863]: info: PLUGIN START: spop Mar 17 00:13:49 volumio volumio[863]: [SpotifyConnect] Creating VLS config file Mar 17 00:13:49 volumio volumio[863]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Mar 17 00:13:49 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:49 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:49 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:49 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:49 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:49 volumio volumio[863]: [SpotifyConnect] Starting metadata listener Mar 17 00:13:49 volumio volumio[863]: (node:863) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Mar 17 00:13:49 volumio volumio[863]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2422:27) Mar 17 00:13:49 volumio volumio[863]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2294:14) Mar 17 00:13:49 volumio volumio[863]: at ControllerSpotify.onStart (/data/plugins/music_service/spop/index.js:124:10) Mar 17 00:13:49 volumio volumio[863]: at PluginManager.startPlugin (/volumio/app/pluginmanager.js:431:30) Mar 17 00:13:49 volumio volumio[863]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 17 00:13:49 volumio volumio[863]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 17 00:13:49 volumio volumio[863]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 17 00:13:49 volumio volumio[863]: (node:863) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Mar 17 00:13:49 volumio volumio[863]: (node:863) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Mar 17 00:13:49 volumio sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Mar 17 00:13:49 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 00:13:49 volumio volumio[863]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Mar 17 00:13:49 volumio systemd[1]: Started Volspotconnect2 Daemon. Mar 17 00:13:49 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Mar 17 00:13:49 volumio volumio[2446]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Mar 17 00:13:49 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Mar 17 00:13:49 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Mar 17 00:13:49 volumio volumio[863]: [SpotifyConnect] Vollibrespot Daemon service started! Mar 17 00:13:49 volumio volumio[863]: [Metrics] SpotifyConnect: 0s 104.98ms Mar 17 00:13:49 volumio volumio[863]: info: Done. Mar 17 00:13:51 volumio volumio[863]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Mar 17 00:13:51 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 00:13:51 volumio volumio[863]: Cannot find translation for sourceMedia Servers Mar 17 00:13:51 volumio volumio[863]: Cannot find translation for sourceBandcamp Discover Mar 17 00:13:51 volumio volumio[863]: Cannot find translation for sourceYouTube2 Mar 17 00:13:51 volumio volumio[863]: Cannot find translation for source80s80s Radio Mar 17 00:13:51 volumio volumio[863]: [SpotifyConnect] Closing metadata listener Mar 17 00:13:51 volumio volumio[863]: info: Disabling plugin spop Mar 17 00:13:51 volumio volumio[863]: info: Done. Mar 17 00:13:51 volumio sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Mar 17 00:13:51 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 00:13:51 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Mar 17 00:13:51 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Mar 17 00:13:51 volumio volumio[863]: [SpotifyConnect] Vollibrespot Daemon service stoped! Mar 17 00:13:53 volumio volumio[863]: info: Enabling plugin spop Mar 17 00:13:53 volumio volumio[863]: info: Loading plugin "spop"... Mar 17 00:13:53 volumio volumio[863]: info: PLUGIN START: spop Mar 17 00:13:53 volumio volumio[863]: [SpotifyConnect] Creating VLS config file Mar 17 00:13:53 volumio volumio[863]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Mar 17 00:13:53 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:53 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:53 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:53 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:53 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 00:13:53 volumio volumio[863]: [SpotifyConnect] Starting metadata listener Mar 17 00:13:53 volumio volumio[863]: (node:863) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Mar 17 00:13:53 volumio volumio[863]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2422:27) Mar 17 00:13:53 volumio volumio[863]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2294:14) Mar 17 00:13:53 volumio volumio[863]: at ControllerSpotify.onStart (/data/plugins/music_service/spop/index.js:124:10) Mar 17 00:13:53 volumio volumio[863]: at PluginManager.startPlugin (/volumio/app/pluginmanager.js:431:30) Mar 17 00:13:53 volumio volumio[863]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 17 00:13:53 volumio volumio[863]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 17 00:13:53 volumio volumio[863]: (node:863) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Mar 17 00:13:53 volumio sudo[2453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Mar 17 00:13:53 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 00:13:53 volumio volumio[863]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Mar 17 00:13:53 volumio systemd[1]: Started Volspotconnect2 Daemon. Mar 17 00:13:53 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Mar 17 00:13:53 volumio volumio[2455]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Mar 17 00:13:53 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Mar 17 00:13:53 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Mar 17 00:13:53 volumio volumio[863]: [SpotifyConnect] Vollibrespot Daemon service started! Mar 17 00:13:53 volumio volumio[863]: [Metrics] SpotifyConnect: 0s 99.54ms Mar 17 00:13:53 volumio volumio[863]: info: Done. Mar 17 00:13:53 volumio volumio[863]: info: Checking Spotify Web API Mar 17 00:13:53 volumio volumio[863]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 00:13:53 volumio volumio[863]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Mar 17 00:13:53 volumio volumio[863]: at healthCheck (dgram.js:897:11) Mar 17 00:13:53 volumio volumio[863]: at Socket.send (dgram.js:622:3) Mar 17 00:13:53 volumio volumio[863]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Mar 17 00:13:53 volumio volumio[863]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2212:23) Mar 17 00:13:53 volumio volumio[863]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2299:18) Mar 17 00:13:53 volumio volumio[863]: at listOnTimeout (internal/timers.js:554:17) Mar 17 00:13:53 volumio volumio[863]: at processTimers (internal/timers.js:497:7) { Mar 17 00:13:53 volumio volumio[863]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Mar 17 00:13:53 volumio volumio[863]: } Mar 17 00:13:53 volumio volumio[863]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 00:13:54 volumio sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 00:12 Mar 17 00:13:54 volumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"