-- Logs begin at Sat 2025-12-20 12:12:09 CET, end at Sat 2025-12-20 16:35:03 CET. -- Dec 20 16:34:02 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Dec 20 16:34:02 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Dec 20 16:34:03 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 20 16:34:04 volumio volumio[1284]: info: Executing endpoint metavolumio Dec 20 16:34:04 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 16:34:10 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 20 16:34:10 volumio volumio[1284]: info: Preload queue cleared Dec 20 16:34:11 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 16:34:11 volumio volumio[1284]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 16:34:11 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 16:34:11 volumio volumio[1284]: info: Received Get System Version Dec 20 16:34:11 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 16:34:11 volumio volumio[1284]: info: Received Get System Info Dec 20 16:34:11 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 16:34:11 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 16:34:11 volumio volumio[1284]: info: Discovery: Getting this device information Dec 20 16:34:11 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Dec 20 16:34:11 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Dec 20 16:34:11 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 16:34:14 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Dec 20 16:34:14 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Dec 20 16:34:16 volumio volumio[1284]: info: Executing endpoint metavolumio Dec 20 16:34:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 16:34:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 16:34:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 16:34:25 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 16:34:25 volumio volumio[1284]: error: Cannot populate Spotify configuration: TypeError: Cannot read property 'bars' of undefined Dec 20 16:34:40 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Dec 20 16:34:40 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Dec 20 16:34:42 volumio volumio[1284]: info: Executing endpoint metavolumio Dec 20 16:34:42 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 16:34:51 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Dec 20 16:34:51 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Dec 20 16:34:52 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 16:34:52 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 16:34:53 volumio volumio[1284]: info: Executing endpoint metavolumio Dec 20 16:34:53 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 16:34:58 volumio volumio[1284]: info: Enabling plugin spop Dec 20 16:34:58 volumio volumio[1284]: info: Loading plugin "spop"... Dec 20 16:34:58 volumio volumio[1284]: info: PLUGIN START: spop Dec 20 16:34:58 volumio volumio[1284]: info: Checking for old spotify connect plugin installed Dec 20 16:34:58 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 16:34:58 volumio volumio[1284]: [SpotifyConnect] Creating VLS config file Dec 20 16:34:58 volumio volumio[1284]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Dec 20 16:34:58 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:34:58 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:34:58 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 16:34:58 volumio volumio[1284]: [SpotifyConnect] Starting metadata listener Dec 20 16:34:58 volumio volumio[1284]: (node:1284) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Dec 20 16:34:58 volumio volumio[1284]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2578:27) Dec 20 16:34:58 volumio volumio[1284]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2441:14) Dec 20 16:34:58 volumio volumio[1284]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Dec 20 16:34:58 volumio volumio[1284]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Dec 20 16:34:58 volumio volumio[1284]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 20 16:34:58 volumio volumio[1284]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 20 16:34:58 volumio volumio[1284]: (node:1284) 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) Dec 20 16:34:58 volumio volumio[1284]: (node:1284) [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. Dec 20 16:34:58 volumio sudo[6326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 20 16:34:58 volumio volumio[1284]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 20 16:34:58 volumio sudo[6326]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:34:58 volumio systemd[1]: Started Volspotconnect2 Daemon. Dec 20 16:34:58 volumio sudo[6326]: pam_unix(sudo:session): session closed for user root Dec 20 16:34:58 volumio volumio[6332]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Dec 20 16:34:58 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Dec 20 16:34:58 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Dec 20 16:34:58 volumio volumio[1284]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 20 16:34:58 volumio volumio[1284]: [Metrics] SpotifyConnect: 0s 88.65ms Dec 20 16:34:58 volumio volumio[1284]: info: Done. Dec 20 16:35:00 volumio volumio[1284]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 20 16:35:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 16:35:00 volumio volumio[1284]: Cannot find translation for source TIDAL Dec 20 16:35:00 volumio volumio[1284]: [SpotifyConnect] Closing metadata listener Dec 20 16:35:00 volumio volumio[1284]: info: Disabling plugin spop Dec 20 16:35:00 volumio volumio[1284]: info: Done. Dec 20 16:35:00 volumio sudo[6340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Dec 20 16:35:00 volumio sudo[6340]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 16:35:00 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Dec 20 16:35:00 volumio sudo[6340]: pam_unix(sudo:session): session closed for user root Dec 20 16:35:00 volumio volumio[1284]: [SpotifyConnect] Vollibrespot Daemon service stoped! Dec 20 16:35:02 volumio volumio[1284]: info: Checking Spotify Web API Dec 20 16:35:02 volumio volumio[1284]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 16:35:02 volumio volumio[1284]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Dec 20 16:35:02 volumio volumio[1284]: at new NodeError (internal/errors.js:322:7) Dec 20 16:35:02 volumio volumio[1284]: at healthCheck (dgram.js:909:11) Dec 20 16:35:02 volumio volumio[1284]: at Socket.send (dgram.js:643:3) Dec 20 16:35:02 volumio volumio[1284]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Dec 20 16:35:02 volumio volumio[1284]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2354:23) Dec 20 16:35:02 volumio volumio[1284]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2446:18) Dec 20 16:35:02 volumio volumio[1284]: at listOnTimeout (internal/timers.js:557:17) Dec 20 16:35:02 volumio volumio[1284]: at processTimers (internal/timers.js:500:7) { Dec 20 16:35:02 volumio volumio[1284]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Dec 20 16:35:02 volumio volumio[1284]: } Dec 20 16:35:02 volumio volumio[1284]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 16:35:03 volumio sudo[6359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 16:34 Dec 20 16:35:03 volumio sudo[6359]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"