Dec 10 23:35:09 volumio volumio[1966]: info: Dec 10 23:35:09 volumio volumio[1966]: info: up to date, audited 166 packages in 9s Dec 10 23:35:09 volumio volumio[1966]: info: Dec 10 23:35:09 volumio volumio[1966]: info: 23 packages are looking for funding Dec 10 23:35:09 volumio volumio[1966]: info: run `npm fund` for details Dec 10 23:35:09 volumio volumio[1966]: info: Dec 10 23:35:09 volumio volumio[1966]: info: 3 vulnerabilities (1 low, 2 high) Dec 10 23:35:09 volumio volumio[1966]: info: Dec 10 23:35:09 volumio volumio[1966]: info: To address all issues, run: Dec 10 23:35:09 volumio volumio[1966]: info: npm audit fix Dec 10 23:35:09 volumio volumio[1966]: info: Dec 10 23:35:09 volumio volumio[1966]: info: Run `npm audit` for details. Dec 10 23:35:10 volumio volumio[1966]: info: Creating user directories (if not exist) Dec 10 23:35:10 volumio volumio[1966]: info: Now Playing plugin installed Dec 10 23:35:10 volumio sudo[2853]: pam_unix(sudo:session): session closed for user root Dec 10 23:35:10 volumio volumio[1966]: Plugin install end detected on script Dec 10 23:35:10 volumio volumio[1966]: info: Install script completed Dec 10 23:35:10 volumio volumio[1966]: info: Adding reference to registry Dec 10 23:35:10 volumio volumio[1966]: info: Done installing plugin. Dec 10 23:35:10 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 23:35:10 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 10 23:35:10 volumio volumio[1966]: info: Folder /tmp/plugins removed Dec 10 23:35:10 volumio volumio[1966]: info: Folder /tmp/downloaded_plugin.zip removed Dec 10 23:35:10 volumio volumio[1966]: info: Folder /data/temp removed Dec 10 23:35:11 volumio go-librespot[2289]: time="2025-12-10T23:35:11+02:00" level=debug msg="fetched chunk 16/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:35:12 volumio volumio[1966]: info: Enabling plugin now_playing Dec 10 23:35:12 volumio volumio[1966]: info: Loading plugin "now_playing"... Dec 10 23:35:16 volumio volumio[1966]: info: PLUGIN START: now_playing Dec 10 23:35:16 volumio volumio[1966]: info: [now-playing] ConfigUpdater: config version unavailable. Either this is the first time the plugin is installed, or the previous version is < 0.3.0). Config will not be updated. Dec 10 23:35:16 volumio volumio[1966]: info: [now-playing] ConfigUpdater: updated config version to 1.0.2 Dec 10 23:35:16 volumio volumio[1966]: info: [now-playing] App is listening on port 4004. Dec 10 23:35:16 volumio volumio[1966]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 10 23:35:16 volumio volumio[1966]: info: Done. Dec 10 23:35:19 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 23:35:19 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 10 23:35:22 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:35:22 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 23:35:22 volumio volumio[1966]: info: Discovery: Getting this device information Dec 10 23:35:22 volumio volumio[1966]: info: CoreCommandRouter::volumioGetState Dec 10 23:35:22 volumio volumio[1966]: info: CorePlayQueue::getTrack 0 Dec 10 23:35:22 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 23:35:23 volumio go-librespot[2289]: time="2025-12-10T23:35:23+02:00" level=debug msg="fetched chunk 17/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:35:27 volumio volumio[1966]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.189 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 26_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/143.0.7499.92 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 Dec 10 23:35:27 volumio volumio[1966]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 10 23:35:27 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 10 23:35:27 volumio go-librespot[2289]: time="2025-12-10T23:35:27+02:00" level=trace msg="sent dealer ping" Dec 10 23:35:27 volumio volumio[1966]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Dec 10 23:35:27 volumio volumio[1966]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Dec 10 23:35:27 volumio volumio[1966]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:37) Dec 10 23:35:27 volumio volumio[1966]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:51:13 Dec 10 23:35:27 volumio volumio[1966]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Dec 10 23:35:27 volumio volumio[1966]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Dec 10 23:35:27 volumio volumio[1966]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Dec 10 23:35:27 volumio volumio[1966]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Dec 10 23:35:27 volumio volumio[1966]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Dec 10 23:35:27 volumio volumio[1966]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Dec 10 23:35:27 volumio volumio[1966]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Dec 10 23:35:27 volumio volumio[1966]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Dec 10 23:35:27 volumio volumio[1966]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Dec 10 23:35:27 volumio volumio[1966]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Dec 10 23:35:27 volumio volumio[1966]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Dec 10 23:35:27 volumio volumio[1966]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Dec 10 23:35:27 volumio volumio[1966]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Dec 10 23:35:27 volumio volumio[1966]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.189 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 26_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/143.0.7499.92 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 8 Dec 10 23:35:27 volumio go-librespot[2289]: time="2025-12-10T23:35:27+02:00" level=trace msg="received dealer pong" Dec 10 23:35:27 volumio volumio[1966]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 10 23:35:27 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 10 23:35:27 volumio volumio[1966]: info: Listing playlists Dec 10 23:35:27 volumio volumio[1966]: info: CoreCommandRouter::volumioGetQueue Dec 10 23:35:27 volumio volumio[1966]: info: CoreStateMachine::getQueue Dec 10 23:35:27 volumio volumio[1966]: info: CorePlayQueue::getQueue Dec 10 23:35:27 volumio volumio[1966]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 23:35:27 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 23:35:27 volumio volumio[1966]: info: CoreCommandRouter::volumioGetState Dec 10 23:35:27 volumio volumio[1966]: info: CorePlayQueue::getTrack 0 Dec 10 23:35:31 volumio volumio[1966]: info: CoreCommandRouter::volumioToggle Dec 10 23:35:31 volumio volumio[1966]: info: CorePlayQueue::getTrack 0 Dec 10 23:35:31 volumio volumio[1966]: info: CoreStateMachine::play index undefined Dec 10 23:35:31 volumio volumio[1966]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 23:35:31 volumio volumio[1966]: info: CorePlayQueue::getTrack 0 Dec 10 23:35:32 volumio volumio[1966]: info: CoreCommandRouter::volumioNext Dec 10 23:35:32 volumio volumio[1966]: info: CoreStateMachine::next Dec 10 23:35:32 volumio volumio[1966]: info: CoreStateMachine::stop Dec 10 23:35:32 volumio volumio[1966]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 23:35:32 volumio volumio[1966]: info: CoreStateMachine::play index undefined Dec 10 23:35:32 volumio volumio[1966]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 23:35:32 volumio volumio[1966]: info: CorePlayQueue::getTrack 1 Dec 10 23:35:36 volumio go-librespot[2289]: time="2025-12-10T23:35:36+02:00" level=debug msg="fetched chunk 18/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:35:42 volumio volumio[1966]: info: CALLMETHOD: user_interface now_playing configSaveDaemon [object Object] Dec 10 23:35:42 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: now_playing , configSaveDaemon Dec 10 23:35:48 volumio go-librespot[2289]: time="2025-12-10T23:35:48+02:00" level=debug msg="fetched chunk 19/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:35:57 volumio go-librespot[2289]: time="2025-12-10T23:35:57+02:00" level=trace msg="sent dealer ping" Dec 10 23:35:57 volumio go-librespot[2289]: time="2025-12-10T23:35:57+02:00" level=trace msg="received dealer pong" Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:35:59 volumio volumio[1966]: info: Retrieving Cloud Streaming UI Dec 10 23:35:59 volumio volumio[1966]: info: Getting Tidal Cloud Configuration Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:35:59 volumio volumio[1966]: info: Getting Qobuz Cloud Configuration Dec 10 23:35:59 volumio volumio[1966]: info: Asking plugin for UI Config Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:35:59 volumio volumio[1966]: info: Getting Spotify Cloud Configuration Dec 10 23:35:59 volumio volumio[1966]: info: Asking plugin for UI Config Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:35:59 volumio volumio[1966]: info: Saving Spotify Acccount Dec 10 23:35:59 volumio volumio[1966]: info: Got Tidal Cloud Configuration Dec 10 23:35:59 volumio volumio[1966]: info: Got it Dec 10 23:35:59 volumio volumio[1966]: info: Got it Dec 10 23:35:59 volumio volumio[1966]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:35:59 volumio volumio[1966]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::volumioGetBrowseSources Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::volumioGetBrowseSources Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::volumioGetBrowseSources Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 23:35:59 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 10 23:36:01 volumio go-librespot[2289]: time="2025-12-10T23:36:01+02:00" level=debug msg="fetched chunk 20/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:36:03 volumio ntpd[869]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 10 23:36:03 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 10 23:36:09 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 23:36:09 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 10 23:36:12 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:36:12 volumio volumio[1966]: info: touch_display: About to detect HDMI ports... Dec 10 23:36:12 volumio volumio[1966]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Dec 10 23:36:13 volumio go-librespot[2289]: time="2025-12-10T23:36:13+02:00" level=debug msg="fetched chunk 21/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:36:16 volumio volumio[1966]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Dec 10 23:36:16 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Dec 10 23:36:16 volumio volumio[1966]: info: CoreCommandRouter::volumioGetState Dec 10 23:36:16 volumio volumio[1966]: info: CorePlayQueue::getTrack 0 Dec 10 23:36:16 volumio volumio[1966]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 10 23:36:25 volumio go-librespot[2289]: time="2025-12-10T23:36:25+02:00" level=debug msg="fetched chunk 22/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:36:26 volumio go-librespot[2289]: time="2025-12-10T23:36:26+02:00" level=trace msg="received accesspoint ping" Dec 10 23:36:26 volumio go-librespot[2289]: time="2025-12-10T23:36:26+02:00" level=trace msg="received accesspoint pong ack" Dec 10 23:36:27 volumio go-librespot[2289]: time="2025-12-10T23:36:27+02:00" level=trace msg="sent dealer ping" Dec 10 23:36:27 volumio go-librespot[2289]: time="2025-12-10T23:36:27+02:00" level=trace msg="received dealer pong" Dec 10 23:36:32 volumio volumio[1966]: info: CALLMETHOD: user_interface touch_display saveVirtualKeyboardConf [object Object] Dec 10 23:36:32 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveVirtualKeyboardConf Dec 10 23:36:35 volumio volumio[1966]: info: CALLMETHOD: user_interface touch_display saveVirtualKeyboardConf [object Object] Dec 10 23:36:35 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveVirtualKeyboardConf Dec 10 23:36:35 volumio volumio[1966]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 23:36:35 volumio volumio[1966]: error: touch_display: Virtual keyboard setting cannot be applied: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 10 23:36:35 volumio volumio[1966]: info: touch_display: About to detect HDMI ports... Dec 10 23:36:35 volumio volumio[1966]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Dec 10 23:36:37 volumio go-librespot[2289]: time="2025-12-10T23:36:37+02:00" level=debug msg="fetched chunk 23/25, size: 524288" uri="spotify:track:5L4wJD8eyVhSrm4pbPuF2u" Dec 10 23:36:41 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 23:36:41 volumio volumio[1966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 10 23:36:45 volumio sudo[3220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 10 23:36:45 volumio sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 10 23:36:45 volumio sudo[3216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 10 23:36:45 volumio sudo[3222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 10 23:36:45 volumio sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:36:45 volumio sudo[3220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:36:45 volumio sudo[3216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:36:45 volumio sudo[3222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 23:36:45 volumio sudo[3216]: pam_unix(sudo:session): session closed for user root Dec 10 23:36:45 volumio volumio[1966]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 10 23:36:45 volumio volumio[1966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:36:45 volumio volumio[1966]: TypeError: Cannot read properties of undefined (reading 'split') Dec 10 23:36:45 volumio volumio[1966]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 10 23:36:45 volumio volumio[1966]: at ChildProcess.exithandler (node:child_process:413:7) Dec 10 23:36:45 volumio volumio[1966]: at ChildProcess.emit (node:events:514:28) Dec 10 23:36:45 volumio volumio[1966]: at maybeClose (node:internal/child_process:1105:16) Dec 10 23:36:45 volumio volumio[1966]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 10 23:36:45 volumio volumio[1966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 23:36:45 volumio systemd[1]: Reloading. Dec 10 23:36:46 volumio sudo[3258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 23:35' Dec 10 23:36:46 volumio sudo[3258]: 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"