Jul 06 10:42:00 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 06 10:42:01 volumio volumio[3165]: info: Loading plugin "ytmusic"... Jul 06 10:42:07 volumio volumio[3165]: info: Loading plugin "now_playing"... Jul 06 10:42:10 volumio volumio[3165]: info: Loading plugin "outputs"... Jul 06 10:42:10 volumio volumio[3165]: info: Loading plugin "albumart"... Jul 06 10:42:10 volumio volumio[3165]: info: Plugin example_plugin is not enabled Jul 06 10:42:10 volumio volumio[3165]: info: Loading plugin "inputs"... Jul 06 10:42:10 volumio volumio[3165]: info: Loading plugin "updater_comm"... Jul 06 10:42:10 volumio volumio[3165]: info: Plugin mpdemulation is not enabled Jul 06 10:42:10 volumio volumio[3165]: info: Loading plugin "rest_api"... Jul 06 10:42:10 volumio volumio[3165]: info: Loading plugin "websocket"... Jul 06 10:42:10 volumio volumio[3165]: info: Starting Socket.io Server version 1.7.4 Jul 06 10:42:10 volumio volumio[3165]: info: Loading plugin "touch_display"... Jul 06 10:42:10 volumio volumio[3165]: info: Applying required configuration parameters for plugin touch_display Jul 06 10:42:10 volumio volumio[3239]: Forking 3 albumart workers Jul 06 10:42:11 volumio volumio[3165]: info: Loading i18n strings for locale en Jul 06 10:42:11 volumio volumio[3165]: Updating browse sources language Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::initPlayerControls Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: Express server listening on port 3000 Jul 06 10:42:11 volumio volumio[3165]: [Metrics] WebUI: 20s 234.34ms Jul 06 10:42:11 volumio volumio[3165]: info: CoreStateMachine::resetVolumioState Jul 06 10:42:11 volumio volumio[3165]: info: CoreStateMachine::getcurrentVolume Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::volumioRetrievevolume Jul 06 10:42:11 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:11 volumio volumio[3165]: info: Cannot read play queue from file Jul 06 10:42:11 volumio volumio[3165]: info: Volumio Network Manager: Network status updated: 1 Jul 06 10:42:11 volumio volumio[3165]: info: Creating Thumbnail for file BMG-Wallpaper-002.jpg : /data/backgrounds/thumbnail-BMG-Wallpaper-002.jpg Jul 06 10:42:12 volumio volumio[3165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 06 10:42:12 volumio volumio[3165]: info: CoreStateMachine::pushState Jul 06 10:42:12 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:12 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 10:42:12 volumio volumio[3165]: info: CoreCommandRouter::volumioPushState Jul 06 10:42:12 volumio volumio[3165]: info: CoreStateMachine::updateTrackBlock Jul 06 10:42:12 volumio volumio[3165]: info: CorePlayQueue::getTrackBlock Jul 06 10:42:12 volumio volumio[3165]: info: CoreCommandRouter::volumioRetrievevolume Jul 06 10:42:12 volumio volumio[3165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 06 10:42:12 volumio volumio[3165]: info: CoreStateMachine::pushState Jul 06 10:42:12 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:12 volumio volumio[3165]: info: CoreCommandRouter::volumioPushState Jul 06 10:42:12 volumio volumio[3165]: info: Setting Device type: Raspberry PI Jul 06 10:42:12 volumio volumio[3165]: info: CoreStateMachine::setRepeat null single undefined Jul 06 10:42:12 volumio volumio[3165]: info: CoreStateMachine::pushState Jul 06 10:42:12 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:12 volumio volumio[3165]: info: CoreCommandRouter::volumioPushState Jul 06 10:42:12 volumio volumio[3165]: info: CoreStateMachine::setRandom null Jul 06 10:42:12 volumio volumio[3165]: info: CoreStateMachine::pushState Jul 06 10:42:12 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:12 volumio volumio[3165]: info: CoreCommandRouter::volumioPushState Jul 06 10:42:12 volumio volumio[3249]: Starting albumart workers Jul 06 10:42:13 volumio volumio-remote-updater[678]: [2025-07-06 10:42:13] [connect] Successful connection Jul 06 10:42:13 volumio volumio[3250]: Starting albumart workers Jul 06 10:42:13 volumio volumio[3251]: Starting albumart workers Jul 06 10:42:15 volumio volumio[3165]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jul 06 10:42:15 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jul 06 10:42:15 volumio volumio[3165]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'has') Jul 06 10:42:16 volumio volumio[3165]: info: Listing playlists Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::volumioGetQueue Jul 06 10:42:16 volumio volumio[3165]: info: CoreStateMachine::getQueue Jul 06 10:42:16 volumio volumio[3165]: info: CorePlayQueue::getQueue Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:16 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:16 volumio volumio[3165]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jul 06 10:42:16 volumio volumio[3165]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'has') Jul 06 10:42:16 volumio volumio[3165]: info: Listing playlists Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::volumioGetQueue Jul 06 10:42:16 volumio volumio[3165]: info: CoreStateMachine::getQueue Jul 06 10:42:16 volumio volumio[3165]: info: CorePlayQueue::getQueue Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:16 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:16 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:17 volumio volumio-remote-updater[678]: [2025-07-06 10:42:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1751791333 101 Jul 06 10:42:17 volumio volumio[3165]: 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: 3 Jul 06 10:42:17 volumio volumio[3165]: info: Completed loading Core Plugins Jul 06 10:42:17 volumio volumio[3165]: info: Preparing to generate the ALSA configuration file Jul 06 10:42:17 volumio volumio[3165]: info: Discovery: adding 7e16a855-75a3-46be-9a51-32f50eaa46c7 Jul 06 10:42:17 volumio volumio[3165]: info: Discovery: Found device Volumio Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:17 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:17 volumio volumio[3165]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 06 10:42:17 volumio volumio[3165]: info: Reading ALSA contributions from plugins. Jul 06 10:42:17 volumio volumio[3165]: info: Discovery: this is already registered, 7e16a855-75a3-46be-9a51-32f50eaa46c7 Jul 06 10:42:17 volumio volumio[3165]: info: Discovery: Found device Volumio Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:17 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:17 volumio volumio[3165]: info: Asound.conf file unchanged, so no further update is needed Jul 06 10:42:17 volumio volumio[3165]: info: Output device has changed, restarting MPD Jul 06 10:42:17 volumio volumio[3165]: info: ___________ START PLUGINS ___________ Jul 06 10:42:17 volumio sudo[3336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 10:42:17 volumio sudo[3336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:17 volumio volumio[3165]: info: ControllerMpd::onStart: Initializing MPD Jul 06 10:42:17 volumio volumio[3165]: info: Creating MPD Configuration file Jul 06 10:42:17 volumio sudo[3336]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:17 volumio sudo[3338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 10:42:17 volumio sudo[3338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 10:42:17 volumio volumio[3165]: info: [1751791337353] CoreMusicLibrary::Adding element Last_100 Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:17 volumio sudo[3341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 10:42:17 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:17 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 10:42:17 volumio volumio[3165]: info: [1751791337374] CoreMusicLibrary::Adding element Webradio Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:17 volumio sudo[3344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 10:42:17 volumio sudo[3344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 10:42:17 volumio volumio[3165]: info: Initializing BBC Radios Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:17 volumio volumio[3165]: info: Creating Spotify config file Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:17 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 10:42:17 volumio volumio[3165]: info: [1751791337777] CoreMusicLibrary::Adding element YouTube Music Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:17 volumio volumio[3165]: Cannot find translation for source YouTube Music Jul 06 10:42:17 volumio volumio[3165]: info: [now-playing] ConfigUpdater: config is up to date. Jul 06 10:42:17 volumio volumio[3165]: info: Loading i18n strings for locale en Jul 06 10:42:17 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 10:42:17 volumio volumio[3165]: info: Volumio Calling Home Jul 06 10:42:18 volumio sudo[3365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 06 10:42:18 volumio sudo[3367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 06 10:42:18 volumio sudo[3363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 06 10:42:18 volumio sudo[3363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:18 volumio sudo[3365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:18 volumio sudo[3367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:18 volumio volumio[3165]: info: [now-playing] Fetching API key... Jul 06 10:42:18 volumio volumio[3165]: info: [now-playing] App is listening on port 4004. Jul 06 10:42:18 volumio volumio[3165]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 06 10:42:18 volumio volumio[3165]: info: touch_display: Backlight interface detected. Jul 06 10:42:18 volumio volumio[3165]: info: MPD Permissions set Jul 06 10:42:18 volumio volumio[3165]: info: MPD Permissions set Jul 06 10:42:18 volumio volumio[3165]: info: Spotify config file written Jul 06 10:42:18 volumio volumio[3165]: info: Volumio called home Jul 06 10:42:18 volumio volumio[3165]: info: No need to fix Spotify hosts Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio sudo[3375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio sudo[3375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio systemd[1]: Reloading. Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:18 volumio volumio[3165]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jul 06 10:42:18 volumio sudo[3397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jul 06 10:42:18 volumio sudo[3397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:18 volumio sudo[3395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 06 10:42:18 volumio sudo[3395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:18 volumio sudo[3393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Jul 06 10:42:18 volumio sudo[3393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:18 volumio sudo[3393]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:18 volumio sudo[3397]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:18 volumio sudo[3395]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:18 volumio volumio[3165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 06 10:42:19 volumio volumio[3165]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jul 06 10:42:19 volumio volumio[3165]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 06 10:42:19 volumio volumio[3165]: info: touch_display: File permissions for backlight brightness control set. Jul 06 10:42:19 volumio volumio[3165]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 06 10:42:19 volumio volumio[3165]: SPOTIFY: BQCFEaNF4UIPZDiY9G_MWSdGGQyZ9ew1Pk5PVKi926wJRs46aEwZhlc7mRZE46RFjjnNsnG-ULRNYKMlHzr6LwQMDIRp8EeReW1dKt7Gy4yOpkcWFI8GYtjKwDgsUPa0N_rS5eKrLc8H3l6Eu1vUTA271cIrG_DQ9TFUchu8Qx0ConN3Pkwi6MEa4XVpBrAuvcZmPvYdgOssia7Yk3JPrQeLEumCEfry4P0OJy684eBjeXnk11x_D5BbwgpfmdHEjPjeuoCpSW9L_SnBX4t0QpCA-WXkyMzkg8bmsVK4c8TPxcBDyJ49bZqd-Goa3EktHy6xKfnOL5sLvERi96OcOtgJBvu8yJ8KolYDbg Jul 06 10:42:19 volumio volumio[3165]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 06 10:42:19 volumio volumio[3165]: info: New Spotify access token = BQCFEaNF4UIPZDiY9G_MWSdGGQyZ9ew1Pk5PVKi926wJRs46aEwZhlc7mRZE46RFjjnNsnG-ULRNYKMlHzr6LwQMDIRp8EeReW1dKt7Gy4yOpkcWFI8GYtjKwDgsUPa0N_rS5eKrLc8H3l6Eu1vUTA271cIrG_DQ9TFUchu8Qx0ConN3Pkwi6MEa4XVpBrAuvcZmPvYdgOssia7Yk3JPrQeLEumCEfry4P0OJy684eBjeXnk11x_D5BbwgpfmdHEjPjeuoCpSW9L_SnBX4t0QpCA-WXkyMzkg8bmsVK4c8TPxcBDyJ49bZqd-Goa3EktHy6xKfnOL5sLvERi96OcOtgJBvu8yJ8KolYDbg Jul 06 10:42:19 volumio volumio[3165]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 06 10:42:19 volumio volumio[3165]: info: [now-playing] OpenWeatherMap API key obtained. Jul 06 10:42:19 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:19 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:20 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:42:20 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:42:20 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:20 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:20 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:42:20 volumio volumio[3165]: SPOTIFY: User informations: {"country":"NL","display_name":"maroen99","email":"jeroen.snijders@mjwb.nl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/maroen99"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/maroen99","id":"maroen99","images":[],"product":"premium","type":"user","uri":"spotify:user:maroen99"} Jul 06 10:42:20 volumio volumio[3165]: info: Spotify Successfully logged in Jul 06 10:42:20 volumio volumio[3165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 10:42:20 volumio volumio[3165]: info: [1751791340328] CoreMusicLibrary::Adding element Spotify Jul 06 10:42:20 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:20 volumio volumio[3165]: Cannot find translation for source YouTube Music Jul 06 10:42:20 volumio volumio[3165]: Cannot find translation for source Spotify Jul 06 10:42:20 volumio go-librespot[1538]: time="2025-07-06T10:42:20+02:00" level=trace msg="sent dealer ping" Jul 06 10:42:20 volumio go-librespot[1538]: time="2025-07-06T10:42:20+02:00" level=trace msg="received dealer pong" Jul 06 10:42:21 volumio sudo[3422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 10:42:21 volumio sudo[3422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:21 volumio sudo[3422]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:21 volumio sudo[3424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 10:42:21 volumio sudo[3424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:21 volumio sudo[3424]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:21 volumio volumio[3165]: verbose: New Socket.io Connection to 192.168.1.54 from 192.168.1.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 06 10:42:22 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. Jul 06 10:42:22 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. Jul 06 10:42:22 volumio 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. Jul 06 10:42:22 volumio 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. Jul 06 10:42:22 volumio sudo[3367]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:22 volumio volumio[3165]: info: touch_display: systemctl daemon-reload succeeded. Jul 06 10:42:22 volumio sudo[3432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 10:42:22 volumio sudo[3432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:22 volumio sudo[3430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 10:42:22 volumio sudo[3430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:22 volumio sudo[3432]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:22 volumio sudo[3434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 06 10:42:22 volumio sudo[3434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:22 volumio sudo[3430]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:22 volumio systemd[1]: Reloading. Jul 06 10:42:22 volumio volumio[3165]: verbose: New Socket.io Connection to 192.168.1.54 from 192.168.1.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:22 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 10:42:22 volumio volumio[3165]: info: Received Get System Info Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:42:22 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:22 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:22 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:22 volumio volumio[3165]: info: Listing playlists Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 06 10:42:22 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 10:42:23 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:23 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:23 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. Jul 06 10:42:23 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. Jul 06 10:42:24 volumio 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. Jul 06 10:42:24 volumio 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. Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 10:42:24 volumio volumio[3165]: info: Received Get System Info Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:42:24 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:24 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:42:24 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 06 10:42:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 06 10:42:24 volumio systemd[1]: mpd.service: Consumed 5.571s CPU time. Jul 06 10:42:24 volumio sudo[3365]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 06 10:42:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 06 10:42:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 06 10:42:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 06 10:42:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 06 10:42:24 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jul 06 10:42:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 06 10:42:24 volumio sudo[3434]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: Volumio Kiosk started. Jul 06 10:42:24 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jul 06 10:42:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 10:42:24 volumio systemd[1]: go-librespot-daemon.service: Consumed 1.059s CPU time. Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:24 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:24 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:24 volumio sudo[3363]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 06 10:42:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 10:42:24 volumio go-librespot[3460]: go-librespot daemon starting... Jul 06 10:42:24 volumio sudo[3375]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:24 volumio sudo[3457]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 06 10:42:24 volumio sudo[3457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: X display number found: 0 Jul 06 10:42:24 volumio sudo[3457]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: X display number found: 0 Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: X display number found: 0 Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 06 10:42:24 volumio volumio[3165]: info: touch_display: X display number found: 0 Jul 06 10:42:24 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:24 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:25 volumio volumio[3165]: info: touch_display: Setting screensaver timeout to 0 seconds. Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=info msg="running go-librespot 0.2.0" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="app state loaded" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=info msg="zeroconf server listening on port 34989" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="obtained new client token: AABlaVSS73pt0SWeyt8soeV8rDX6BVicveO7AvWIeDPY3DQio2HBww+bvh+eD/9t9CZDSNtp5H8QAVZOHisA86+PH/96doYpCFB+MpsRnOqCSOBolR3zaUHyiAFBUMAfovC0uiWVGrusDzQ7mYd6TopEeJXnAOVzc83YMQDjnPySPUBomjuyWvjBTXkAXMaxC4P+WItJAQ2UqrfmSUdOhdqqtaK1LGsRqxYgdlj81V3a0XWP2Z4FXDM=" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="completed keyexchange" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=debug msg="completed challenge" Jul 06 10:42:25 volumio go-librespot[3464]: time="2025-07-06T10:42:25+02:00" level=info msg="authenticated AP as maroen99" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=info msg="authenticated Login5 as maroen99" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="initializing zeroconf session, username: maroen99" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="dealer connection opened" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=trace msg="starting accesspoint recv loop" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=trace msg="starting dealer recv loop" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=trace msg="received accesspoint ping" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="received connection id: NDNhOTk3NWMtNTE1Yi00OTY5LTg5ZmYtOGEzNTdiZDY4ZWE3K2RlYWxlcit0Y3A6Ly8wYWIxNTA4Zi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQTBFNDAzQkI5QTVGNDhFMzFFMDdBRThFNjMxOThGMDhFMkZERENGQjMyRjg2OUMxMzMzQTAzODdCODI3OTlDQQ==" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=trace msg="received accesspoint pong ack" Jul 06 10:42:26 volumio go-librespot[3464]: time="2025-07-06T10:42:26+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 06 10:42:26 volumio volumio[3165]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 10:42:26 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 06 10:42:26 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 06 10:42:27 volumio sudo[3505]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 06 10:42:27 volumio sudo[3505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:27 volumio sudo[3516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 06 10:42:27 volumio sudo[3516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:27 volumio sudo[3510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 06 10:42:27 volumio sudo[3510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:27 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 06 10:42:27 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 06 10:42:27 volumio sudo[3516]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:27 volumio sudo[3510]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:27 volumio sudo[3521]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 06 10:42:27 volumio sudo[3521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:27 volumio sudo[3521]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:27 volumio sudo[3505]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:27 volumio sudo[3526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 10:42:27 volumio sudo[3526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:27 volumio sudo[3526]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:27 volumio sudo[3530]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 10:42:27 volumio sudo[3530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:27 volumio sudo[3530]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:27 volumio volumio[3165]: info: go-librespot daemon successfully initialized Jul 06 10:42:30 volumio mpd[3468]: 2025-07-06T10:42:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 06 10:42:30 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jul 06 10:42:30 volumio sudo[3338]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:30 volumio sudo[3344]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:30 volumio volumio[3165]: info: Completed starting Core Plugins Jul 06 10:42:30 volumio volumio[3165]: info: ------------------------------------------- Jul 06 10:42:30 volumio volumio[3165]: info: ----- MyVolumio plugins startup ---- Jul 06 10:42:30 volumio volumio[3165]: info: ------------------------------------------- Jul 06 10:42:30 volumio volumio[3165]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 06 10:42:30 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 06 10:42:30 volumio volumio[3165]: info: Initializing connection to go-librespot Websocket Jul 06 10:42:31 volumio volumio[3165]: error: MPD error: The expression evaluated to a falsy value: Jul 06 10:42:31 volumio volumio[3165]: assert.ok(self.idling) Jul 06 10:42:31 volumio volumio[3165]: error: The expression evaluated to a falsy value: Jul 06 10:42:31 volumio volumio[3165]: assert.ok(self.idling) Jul 06 10:42:31 volumio volumio[3165]: info: MPD running with PID3468 Jul 06 10:42:31 volumio volumio[3165]: ,establishing connection Jul 06 10:42:31 volumio volumio[3165]: error: MPD error: The expression evaluated to a falsy value: Jul 06 10:42:31 volumio volumio[3165]: assert.ok(self.idling) Jul 06 10:42:31 volumio volumio[3165]: error: The expression evaluated to a falsy value: Jul 06 10:42:31 volumio volumio[3165]: assert.ok(self.idling) Jul 06 10:42:31 volumio go-librespot[3464]: time="2025-07-06T10:42:31+02:00" level=debug msg="new websocket client" Jul 06 10:42:31 volumio volumio[3165]: error: updateQueue error: null Jul 06 10:42:31 volumio volumio[3165]: info: Connection to go-librespot Websocket established Jul 06 10:42:31 volumio dbus-daemon[659]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.184' (uid=0 pid=3541 comm="timedatectl show --property=NTPSynchronized --valu") Jul 06 10:42:31 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 06 10:42:31 volumio dbus-daemon[659]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 06 10:42:31 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 06 10:42:31 volumio setdatetime-helper.sh[3538]: Time is not synchronized. Attempting to sync... Jul 06 10:42:32 volumio sudo[3549]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 06 Jul 2025 08:42:32 GMT#015' Jul 06 10:42:32 volumio sudo[3549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 06 10:42:32 volumio setdatetime-helper.sh[3550]: Sun Jul 6 10:42:32 CEST 2025 Jul 06 10:42:32 volumio systemd-journald[350]: Time jumped backwards, rotating. Jul 06 10:42:32 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 06 10:42:32 volumio sudo[3549]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:32 volumio setdatetime-helper.sh[3538]: Time synchronized successfully. Jul 06 10:42:32 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 06 10:42:32 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 10:42:34 volumio volumio[3165]: info: Getting Spotify volume Jul 06 10:42:34 volumio volumio[3165]: info: Spotify volume: 100 Jul 06 10:42:34 volumio volumio[3165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 06 10:42:34 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:34 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:34 volumio volumio[3165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jul 06 10:42:34 volumio volumio[3165]: SPOTIFY: SPOTIFY VOLUME 100 Jul 06 10:42:34 volumio volumio[3165]: SPOTIFY: VOLUMIO VOLUME 74 Jul 06 10:42:34 volumio volumio[3165]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 06 10:42:34 volumio volumio[3165]: info: Setting Spotify Volume from Volumio: 74 Jul 06 10:42:35 volumio volumio[3165]: SPOTIFY: SETTING SPOTIFY VOLUME 74 Jul 06 10:42:35 volumio volumio[3165]: info: Sending Spotify command with payload to local API: /player/volume Jul 06 10:42:35 volumio volumio[3165]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Jul 06 10:42:35 volumio volumio[3165]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 06 10:42:39 volumio volumio[3165]: info: Adding plugin bluetooth to MyMusic Plugins Jul 06 10:42:39 volumio volumio[3165]: info: Adding plugin multiroom to MyMusic Plugins Jul 06 10:42:39 volumio volumio[3165]: info: Adding plugin metavolumio to MyMusic Plugins Jul 06 10:42:39 volumio volumio[3165]: info: Adding plugin cd_controller to MyMusic Plugins Jul 06 10:42:39 volumio volumio[3165]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 06 10:42:39 volumio volumio[3165]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 06 10:42:39 volumio volumio[3165]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 06 10:42:39 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 06 10:42:40 volumio volumio[3165]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 06 10:42:40 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 06 10:42:40 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:40 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:40 volumio volumio[3165]: info: Starting MyVolumio Remote Streaming Endpoints Jul 06 10:42:40 volumio volumio[3165]: info: MyVolumio login type: Token Jul 06 10:42:40 volumio volumio[3165]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 06 10:42:40 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 06 10:42:42 volumio volumio[3165]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 06 10:42:42 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 06 10:42:42 volumio volumio[3165]: info: Streaming services startup Jul 06 10:42:42 volumio volumio[3165]: info: Starting Streaming Daemon Jul 06 10:42:42 volumio volumio[3165]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 06 10:42:42 volumio sudo[3569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 06 10:42:42 volumio sudo[3569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:42 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 10:42:42 volumio volumio[3165]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 06 10:42:42 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 06 10:42:42 volumio volumio[3165]: info: Received Get System Version Jul 06 10:42:42 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 10:42:42 volumio volumio[3165]: info: Received Get System Info Jul 06 10:42:42 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:42:42 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:42:42 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:42:42 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:42 volumio volumio[3165]: info: CorePlayQueue::getTrack 0 Jul 06 10:42:42 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:42:42 volumio sudo[3569]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:42 volumio volumio[3165]: error: Cannot start Volumio Streaming Daemon Jul 06 10:42:42 volumio volumio[3165]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 06 10:42:42 volumio volumio[3165]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 06 10:42:43 volumio volumio[3165]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 06 10:42:45 volumio volumio[3165]: info: MyVolumio token set successfully Jul 06 10:42:45 volumio volumio[3165]: info: MYVOLUMIO: Adding device Jul 06 10:42:45 volumio volumio[3165]: info: MYVOLUMIO: Evaluating Server Jul 06 10:42:46 volumio volumio[3165]: info: MyVolumio Plan changed: premium Jul 06 10:42:46 volumio volumio[3165]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jul 06 10:42:46 volumio volumio[3165]: info: Removing browser output: myVolumio user plan is not superstar Jul 06 10:42:46 volumio volumio[3165]: info: Removing audio output: Jul 06 10:42:46 volumio volumio[3165]: info: MYVOLUMIO: Adding device Jul 06 10:42:46 volumio volumio[3165]: info: MYVOLUMIO: Evaluating Server Jul 06 10:42:46 volumio volumio[3165]: info: Remote config written successfully Jul 06 10:42:46 volumio volumio[3165]: info: Starting Tunnel 1 Jul 06 10:42:46 volumio volumio[3165]: info: Starting Tunnel Connection Checker Jul 06 10:42:46 volumio volumio[3165]: info: Completed starting MyVolumio Plugin Jul 06 10:42:46 volumio volumio[3165]: info: MYVolumio Device enabled Jul 06 10:42:46 volumio volumio[3165]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jul 06 10:42:46 volumio volumio[3165]: info: MyVolumio status changed Jul 06 10:42:46 volumio volumio[3165]: info: Streaming services startup Jul 06 10:42:46 volumio volumio[3165]: info: Starting Streaming Daemon Jul 06 10:42:46 volumio sudo[3608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 06 10:42:46 volumio sudo[3608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:46 volumio volumio[3165]: info: Setting Geolocation for MyVolumio to eu8 Jul 06 10:42:46 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:46 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:46 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:46 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jul 06 10:42:46 volumio sudo[3608]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:46 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jul 06 10:42:46 volumio volumio[3165]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jul 06 10:42:46 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jul 06 10:42:48 volumio volumio[3165]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jul 06 10:42:48 volumio sudo[3626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 06 10:42:48 volumio sudo[3626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:48 volumio sudo[3626]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:48 volumio volumio[3165]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jul 06 10:42:48 volumio volumio[3165]: info: MRS: MultiRoom plugin initialized Jul 06 10:42:48 volumio volumio[3165]: info: MRS: STOPPING SNAPCLIENT Jul 06 10:42:48 volumio volumio[3165]: info: MRS: Snap server stop Jul 06 10:42:48 volumio volumio[3165]: info: MRS: STOPPING volumioStreaming Jul 06 10:42:48 volumio sudo[3643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 06 10:42:48 volumio sudo[3643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:48 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jul 06 10:42:48 volumio sudo[3645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 06 10:42:48 volumio sudo[3645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:48 volumio sudo[3648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 06 10:42:48 volumio sudo[3648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:48 volumio sudo[3650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 06 10:42:48 volumio sudo[3650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:48 volumio sudo[3650]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:48 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jul 06 10:42:48 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jul 06 10:42:48 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jul 06 10:42:48 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jul 06 10:42:49 volumio sudo[3643]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jul 06 10:42:49 volumio sudo[3648]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:49 volumio sudo[3645]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jul 06 10:42:49 volumio volumio[3165]: info: Preparing to generate the ALSA configuration file Jul 06 10:42:49 volumio volumio[3165]: info: Updating MyVolumio device info Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 10:42:49 volumio volumio[3165]: info: Setting Geolocation for MyVolumio to eu8 Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:49 volumio volumio[3165]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 06 10:42:49 volumio volumio[3165]: info: Reading ALSA contributions from plugins. Jul 06 10:42:49 volumio volumio[3165]: info: Successfully Added MyVolumio device Jul 06 10:42:49 volumio volumio[3165]: info: MRS: Removed streaming files Jul 06 10:42:49 volumio volumio[3165]: info: MRS: volumioStreaming STOPPED Jul 06 10:42:49 volumio volumio[3165]: info: MRS: SNAPSERVER STOPPED Jul 06 10:42:49 volumio volumio[3165]: info: MRS: SNAPCLIENT STOPPED Jul 06 10:42:49 volumio volumio[3165]: error: Cannot start Volumio Streaming Daemon Jul 06 10:42:49 volumio volumio[3165]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 06 10:42:49 volumio volumio[3165]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 06 10:42:49 volumio volumio[3165]: info: Asound.conf file unchanged, so no further update is needed Jul 06 10:42:49 volumio volumio[3165]: info: Output device has changed, restarting MPD Jul 06 10:42:49 volumio volumio[3165]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 06 10:42:49 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:49 volumio sudo[3659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 10:42:49 volumio sudo[3659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jul 06 10:42:49 volumio volumio[3165]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jul 06 10:42:49 volumio volumio[3165]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jul 06 10:42:49 volumio sudo[3659]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:49 volumio sudo[3661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 10:42:49 volumio sudo[3661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:49 volumio volumio[3165]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jul 06 10:42:49 volumio volumio[3165]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jul 06 10:42:49 volumio volumio[3165]: info: Adding METAVOLUMIO REST API Endpoints Jul 06 10:42:49 volumio volumio[3165]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 06 10:42:49 volumio volumio[3165]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 06 10:42:49 volumio volumio[3165]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 06 10:42:49 volumio volumio[3165]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jul 06 10:42:49 volumio volumio[3165]: error: Hi Res Audio Failed Login: Missing Login Data Jul 06 10:42:49 volumio volumio[3165]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jul 06 10:42:49 volumio volumio[3165]: info: Refreshing QOBUZ token Jul 06 10:42:49 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Jul 06 10:42:49 volumio volumio[3165]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jul 06 10:42:49 volumio volumio[3165]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jul 06 10:42:49 volumio volumio[3165]: info: QobuzConnect: Starting Qobuz Connect socket and service Jul 06 10:42:49 volumio volumio[3165]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jul 06 10:42:49 volumio volumio[3165]: info: Stopping AccessToken refresher cron Jul 06 10:42:49 volumio sudo[3673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 06 10:42:49 volumio sudo[3673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:49 volumio volumio[3165]: info: AccessToken refresher cron started Jul 06 10:42:49 volumio volumio[3165]: info: Adding TIDAL REST API Endpoints Jul 06 10:42:49 volumio sudo[3673]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:49 volumio sudo[3675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 06 10:42:49 volumio sudo[3675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:49 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jul 06 10:42:49 volumio volumio[3165]: info: Successfully Updated MyVolumio device Jul 06 10:42:49 volumio bluetoothd[918]: Adv Monitor app :1.187 disconnected from D-Bus Jul 06 10:42:49 volumio volumio[3165]: info: Successfully Added MyVolumio device Jul 06 10:42:49 volumio volumio[3165]: info: MPD Permissions set Jul 06 10:42:50 volumio volumio[3165]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jul 06 10:42:50 volumio sudo[3679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 06 10:42:50 volumio sudo[3679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio sudo[3679]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:50 volumio qobuz-connect[2202]: 20250706 10:42:50.263 [2202.2202] INFO SampleApp: Stopping Local configuration server Jul 06 10:42:50 volumio volumio[3165]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::volumioStop Jul 06 10:42:50 volumio volumio[3165]: info: CoreStateMachine::stop Jul 06 10:42:50 volumio volumio[3165]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 10:42:50 volumio volumio[3165]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jul 06 10:42:50 volumio volumio[3165]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 10:42:50 volumio volumio[3165]: info: Access Token successfully retrieved Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 10:42:50 volumio volumio[3165]: info: [1751791370309] CoreMusicLibrary::Adding element QOBUZ Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:42:50 volumio volumio[3165]: Cannot find translation for source YouTube Music Jul 06 10:42:50 volumio volumio[3165]: Cannot find translation for source Spotify Jul 06 10:42:50 volumio volumio[3165]: Cannot find translation for source QOBUZ Jul 06 10:42:50 volumio volumio[3165]: info: Stopping AccessToken refresher cron for QOBUZ Jul 06 10:42:50 volumio volumio[3165]: info: AccessToken refresher cron started for QOBUZ Jul 06 10:42:50 volumio volumio[3165]: info: Adding QOBUZ REST API Endpoints Jul 06 10:42:50 volumio volumio[3165]: info: Updating MyVolumio device info Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:50 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 06 10:42:50 volumio volumio[3165]: info: Successfully Updated MyVolumio device Jul 06 10:42:50 volumio volumio-remote-updater[678]: Test mode enabled Jul 06 10:42:50 volumio volumio-remote-updater[678]: Alpha mode disabled Jul 06 10:42:50 volumio volumio-remote-updater[678]: Alpha legacy test mode disabled Jul 06 10:42:51 volumio volumio[3165]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 06 10:42:51 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 06 10:42:51 volumio sudo[3693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jul 06 10:42:51 volumio sudo[3693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:42:51 volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Jul 06 10:42:51 volumio autossh[1962]: received signal to exit (15) Jul 06 10:42:51 volumio systemd[1]: sshtunnel.service: Deactivated successfully. Jul 06 10:42:51 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jul 06 10:42:51 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jul 06 10:42:51 volumio sudo[3693]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:51 volumio volumio[3165]: info: Remote SSH Started Jul 06 10:42:51 volumio autossh[3697]: port set to 0, monitoring disabled Jul 06 10:42:51 volumio autossh[3697]: starting ssh (count 1) Jul 06 10:42:51 volumio autossh[3697]: ssh child pid is 3700 Jul 06 10:42:51 volumio volumio[3165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 06 10:42:51 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jul 06 10:42:51 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jul 06 10:42:51 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:51 volumio volumio[3165]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.CJmlLJ5YN0YdQMyGwcXusAAtkdM2.20ced2d3086682bba88615a7879e2789.state.status' Jul 06 10:42:51 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jul 06 10:42:51 volumio sudo[3675]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:51 volumio volumiossh-tunnel[3700]: Warning: Permanently added '[eu8.myvolumio.org]:2222' (RSA) to the list of known hosts. Jul 06 10:42:51 volumio volumio[3165]: [Metrics] CommandRouter: 59s 493.52ms Jul 06 10:42:51 volumio volumio[3165]: info: CoreCommandRouter::volumiosetStartupVolume Jul 06 10:42:51 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:42:51 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 10:42:51 volumio volumio[3165]: info: CoreCommandRouter::Close All Modals sent Jul 06 10:42:51 volumio volumio[3165]: info: CoreCommandRouter::Close All Modals sent Jul 06 10:42:52 volumio volumio[3165]: info: Executing endpoint qc_getconfig Jul 06 10:42:52 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.279 [3701.3701] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.329 [3701.3701] INFO VolumeManager: [0x1292408]: Setting new playback volume: 75 Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.329 [3701.3701] INFO VolumeManager: [0x1292408]: Setting new mute state: 0 Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.329 [3701.3701] INFO QobuzConnect: [0x1292dd8]: Client initialized! Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.329 [3701.3701] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jul 06 10:42:52 volumio volumio[3165]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 06 10:42:52 volumio volumio[3165]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.413 [3701.3701] INFO LocalConfigManager: [0x1291ce8]: Starting Local Configuration server Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.413 [3701.3701] INFO SampleApp: Starting Local configuration server Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.415 [3701.3701] INFO SampleApp: Connected to UNIX socket client 0x127c818 Jul 06 10:42:52 volumio qobuz-connect[3701]: 20250706 10:42:52.430 [3701.3701] INFO SampleApp: Playback volume changed: 75 Jul 06 10:42:52 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:52 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 06 10:42:52 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 06 10:42:52 volumio systemd[1]: mpd.service: Consumed 5.503s CPU time. Jul 06 10:42:52 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 06 10:42:52 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 06 10:42:52 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 06 10:42:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 06 10:42:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 06 10:42:52 volumio sudo[3707]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 06 10:42:52 volumio sudo[3707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 06 10:42:52 volumio sudo[3707]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:52 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 06 10:42:52 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 06 10:42:52 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 06 10:42:55 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 10:42:55 volumio volumio[3165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 06 10:42:56 volumio go-librespot[3464]: time="2025-07-06T10:42:56+02:00" level=trace msg="sent dealer ping" Jul 06 10:42:56 volumio go-librespot[3464]: time="2025-07-06T10:42:56+02:00" level=trace msg="received dealer pong" Jul 06 10:42:56 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:42:57 volumio mpd[3709]: 2025-07-06T10:42:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 06 10:42:57 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jul 06 10:42:57 volumio sudo[3661]: pam_unix(sudo:session): session closed for user root Jul 06 10:42:57 volumio volumio[3165]: error: updateQueue error: null Jul 06 10:42:58 volumio volumio[3165]: info: BOOT COMPLETED Jul 06 10:43:00 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:43:00 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 10:43:00 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 06 10:43:00 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 06 10:43:00 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 06 10:43:00 volumio volumio[3165]: info: CoreCommandRouter::volumioGetBrowseSources Jul 06 10:43:00 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 10:43:01 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 06 10:43:05 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:07 volumio volumio[3165]: info: Executing endpoint metavolumio Jul 06 10:43:07 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 06 10:43:10 volumio sudo[3747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 10:43:10 volumio sudo[3747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:43:10 volumio sudo[3747]: pam_unix(sudo:session): session closed for user root Jul 06 10:43:10 volumio sudo[3749]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 10:43:10 volumio sudo[3749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:43:10 volumio sudo[3749]: pam_unix(sudo:session): session closed for user root Jul 06 10:43:10 volumio volumio[3165]: verbose: New Socket.io Connection to 192.168.1.54 from 192.168.1.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:10 volumio volumio[3165]: info: Received Get System Info Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:43:10 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 10:43:10 volumio volumio[3165]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 06 10:43:10 volumio volumio[3165]: info: Received Get System Info Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:43:10 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:10 volumio volumio[3165]: info: Listing playlists Jul 06 10:43:10 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 06 10:43:20 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 10:43:24 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 06 10:43:24 volumio volumio[3165]: info: MyVolumio login type: Token Jul 06 10:43:25 volumio go-librespot[3464]: time="2025-07-06T10:43:25+02:00" level=trace msg="sent dealer ping" Jul 06 10:43:25 volumio go-librespot[3464]: time="2025-07-06T10:43:25+02:00" level=trace msg="received dealer pong" Jul 06 10:43:29 volumio sudo[3782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 10:43:29 volumio sudo[3782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:43:29 volumio sudo[3782]: pam_unix(sudo:session): session closed for user root Jul 06 10:43:29 volumio sudo[3784]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 10:43:29 volumio sudo[3784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 10:43:29 volumio sudo[3784]: pam_unix(sudo:session): session closed for user root Jul 06 10:43:29 volumio volumio[3165]: verbose: New Socket.io Connection to 192.168.1.54 from 192.168.1.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: Received Get System Info Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:43:29 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 10:43:29 volumio volumio[3165]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 06 10:43:29 volumio volumio[3165]: info: Received Get System Info Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:43:29 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: Listing playlists Jul 06 10:43:29 volumio volumio[3165]: info: Received Get System Info Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 10:43:29 volumio volumio[3165]: info: Discovery: Getting this device information Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:29 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 06 10:43:30 volumio volumio[3165]: info: CoreCommandRouter::volumioGetState Jul 06 10:43:32 volumio volumio[3165]: info: Executing endpoint metavolumio Jul 06 10:43:32 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 10:43:34 volumio volumio[3165]: info: Retrieving Cloud Streaming UI Jul 06 10:43:34 volumio volumio[3165]: info: Getting Tidal Cloud Configuration Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 10:43:34 volumio volumio[3165]: info: Getting Qobuz Cloud Configuration Jul 06 10:43:34 volumio volumio[3165]: info: Asking plugin for UI Config Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 10:43:34 volumio volumio[3165]: info: Getting Spotify Cloud Configuration Jul 06 10:43:34 volumio volumio[3165]: info: Asking plugin for UI Config Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 10:43:34 volumio volumio[3165]: info: Saving Spotify Acccount Jul 06 10:43:34 volumio volumio[3165]: info: Got it Jul 06 10:43:34 volumio volumio[3165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 06 10:43:34 volumio volumio[3165]: info: Got Tidal Cloud Configuration Jul 06 10:43:34 volumio volumio[3165]: info: Got it Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::volumioGetBrowseSources Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::volumioGetBrowseSources Jul 06 10:43:34 volumio volumio[3165]: info: CoreCommandRouter::volumioGetBrowseSources Jul 06 10:43:35 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 06 10:43:35 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 06 10:43:38 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 06 10:43:38 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 06 10:43:39 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 06 10:43:39 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 10:43:43 volumio volumio[3165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 06 10:43:43 volumio volumio[3165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 10:43:43 volumio volumio[3165]: Error: dns service error: unknown Jul 06 10:43:43 volumio volumio[3165]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jul 06 10:43:43 volumio volumio[3165]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jul 06 10:43:43 volumio volumio[3165]: errorCode: -65537 Jul 06 10:43:43 volumio volumio[3165]: } Jul 06 10:43:43 volumio volumio[3165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 10:43:46 volumio sudo[3823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-06 10:42' Jul 06 10:43:46 volumio sudo[3823]: 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:50:33 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d42ab79cc13d24482826e04fda2e8350"