Jan 26 02:01:00 volumio volumio[1899]: Starting albumart workers Jan 26 02:01:00 volumio volumio[1901]: Starting albumart workers Jan 26 02:01:00 volumio volumio[1477]: info: Applying required configuration parameters for plugin Bluetoothremote Jan 26 02:01:00 volumio volumio[1477]: info: Loading plugin "display_configuration"... Jan 26 02:01:00 volumio volumio[1900]: Starting albumart workers Jan 26 02:01:00 volumio volumio-remote-updater[936]: [2026-01-26 02:01:00] [connect] Successful connection Jan 26 02:01:00 volumio volumio[1477]: info: Plugin peppyspectrum is not enabled Jan 26 02:01:00 volumio volumio[1477]: info: Plugin randomizer is not enabled Jan 26 02:01:00 volumio volumio[1477]: info: Loading i18n strings for locale it Jan 26 02:01:01 volumio volumio[1477]: Updating browse sources language Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::initPlayerControls Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: Express server listening on port 3000 Jan 26 02:01:01 volumio volumio[1477]: [Metrics] WebUI: 8s 738.91ms Jan 26 02:01:01 volumio volumio[1477]: info: Setting Device type: x86 Jan 26 02:01:01 volumio volumio[1477]: info: CoreStateMachine::resetVolumioState Jan 26 02:01:01 volumio volumio[1477]: info: CoreStateMachine::getcurrentVolume Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 02:01:01 volumio volumio[1477]: info: CoreStateMachine::pushState Jan 26 02:01:01 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioPushState Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 26 02:01:01 volumio volumio[1477]: info: Completed loading Core Plugins Jan 26 02:01:01 volumio volumio[1477]: info: Preparing to generate the ALSA configuration file Jan 26 02:01:01 volumio volumio[1477]: info: Volumio Network Manager: Network status updated: 1 Jan 26 02:01:01 volumio volumio[1477]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 26 02:01:01 volumio volumio[1477]: verbose: New Socket.io Connection to volumio.local from 192.168.1.90 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 OPR/125.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 26 02:01:01 volumio volumio[1477]: info: Reloading queue from file Jan 26 02:01:01 volumio volumio[1477]: info: Asound.conf file unchanged, so no further update is needed Jan 26 02:01:01 volumio volumio[1477]: info: Output device has changed, restarting MPD Jan 26 02:01:01 volumio volumio[1477]: info: Output device has changed, restarting Shairport Sync Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:01 volumio sudo[2017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 02:01:01 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:01 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:01 volumio sudo[2019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 02:01:01 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:01 volumio volumio[1477]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 02:01:01 volumio volumio[1477]: info: ___________ START PLUGINS ___________ Jan 26 02:01:01 volumio volumio[1477]: info: ControllerMpd::onStart: Initializing MPD Jan 26 02:01:01 volumio volumio[1477]: info: Creating MPD Configuration file Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 02:01:01 volumio volumio[1477]: info: [1769389261591] CoreMusicLibrary::Adding element Server multimediali Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:01 volumio sudo[2027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 02:01:01 volumio sudo[2027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:01 volumio sudo[2029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 02:01:01 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:01 volumio volumio[1477]: info: UPNP Browser: Client initialized successfully Jan 26 02:01:01 volumio sudo[2027]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 02:01:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 02:01:01 volumio volumio[1477]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 02:01:01 volumio volumio[1477]: info: [1769389261643] CoreMusicLibrary::Adding element Last_100 Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:01 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 26 02:01:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 26 02:01:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 26 02:01:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 02:01:01 volumio volumio[1477]: info: [1769389261647] CoreMusicLibrary::Adding element Webradio Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 02:01:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 02:01:01 volumio volumio[1477]: info: Initializing BBC Radios Jan 26 02:01:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 02:01:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:01 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:01 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 02:01:01 volumio volumio[1477]: info: [1769389261711] CoreMusicLibrary::Adding element Jellyfin Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:01 volumio volumio[1477]: Cannot find translation for source Jellyfin Jan 26 02:01:01 volumio volumio[1477]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.1.119","id":"daffe1e1-8397-4ecc-8f9d-2f7e8d5361b1","name":"Volumio","serviceName":"Volumio","state":{"albumart":"http://192.168.1.50:8096/Items/9c92121732c289563ae36e5008459a59/Images/Primary?maxWidth=500&maxHeight=500&quality=90","artist":"Daft Punk","mute":false,"status":"stop","track":"Give Life Back to Music","volume":100},"type":"device"} Jan 26 02:01:01 volumio volumio[1477]: info: Creating Spotify config file Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:01 volumio sudo[2045]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 02:01:01 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 02:01:01 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:01 volumio volumio[1477]: info: [now-playing] ConfigUpdater: config is up to date. Jan 26 02:01:01 volumio volumio[1477]: info: Loading i18n strings for locale it Jan 26 02:01:01 volumio volumio[1477]: info: Display-configuration --- loadI18nStrings: loading from /data/plugins/user_interface/display_configuration/i18n/strings_en.json Jan 26 02:01:01 volumio volumio[1477]: info: Display-configuration --- loadI18nStrings: loaded 52 keys Jan 26 02:01:01 volumio volumio[1477]: info: Display-configuration --- Loaded i18n strings for language: it Jan 26 02:01:01 volumio volumio[1477]: info: Volumio Calling Home Jan 26 02:01:01 volumio sudo[2062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/bash -c 'if [ -f /root/.Xauthority ]; then cp /root/.Xauthority /home/volumio/ && chown volumio:volumio /home/volumio/.Xauthority; fi' Jan 26 02:01:01 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:01 volumio volumio[1477]: info: [now-playing] Fetching API key... Jan 26 02:01:01 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:01 volumio volumio[1477]: info: [now-playing] App is listening on port 4004. Jan 26 02:01:01 volumio volumio[1477]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 26 02:01:01 volumio volumio[1477]: info: CoreStateMachine::setRepeat null single undefined Jan 26 02:01:01 volumio volumio[1477]: info: CoreStateMachine::pushState Jan 26 02:01:01 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioPushState Jan 26 02:01:01 volumio volumio[1477]: info: CoreStateMachine::setRandom null Jan 26 02:01:01 volumio volumio[1477]: info: CoreStateMachine::pushState Jan 26 02:01:01 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:01 volumio volumio[1477]: info: CoreCommandRouter::volumioPushState Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:02 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:02 volumio volumio-remote-updater[936]: [2026-01-26 02:01:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769389260 101 Jan 26 02:01:02 volumio volumio[1477]: 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: 2 Jan 26 02:01:02 volumio volumio[1477]: info: Display-configuration --- fixXauthority: /home/volumio/.Xauthority updated Jan 26 02:01:02 volumio volumio[1477]: info: MPD Permissions set Jan 26 02:01:02 volumio volumio[1477]: info: MPD Permissions set Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::volumioGetQueue Jan 26 02:01:02 volumio volumio[1477]: info: CoreStateMachine::getQueue Jan 26 02:01:02 volumio volumio[1477]: info: CorePlayQueue::getQueue Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jan 26 02:01:02 volumio volumio[1477]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jan 26 02:01:02 volumio volumio[1477]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jan 26 02:01:02 volumio volumio[1477]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jan 26 02:01:02 volumio volumio[1477]: info: Discovery: adding daffe1e1-8397-4ecc-8f9d-2f7e8d5361b1 Jan 26 02:01:02 volumio volumio[1477]: info: Discovery: Found device Volumio Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:02 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:02 volumio volumio[1477]: info: Volumio called home Jan 26 02:01:02 volumio volumio[1477]: info: Spotify config file written Jan 26 02:01:02 volumio sudo[2074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 26 02:01:02 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 02:01:02 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. Jan 26 02:01:02 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. Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 02:01:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 02:01:02 volumio go-librespot[2085]: go-librespot daemon starting... Jan 26 02:01:02 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:02 volumio volumio[1477]: info: No need to fix Spotify hosts Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=info msg="running go-librespot 0.6.2" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="app state loaded" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 02:01:02 volumio volumio[1477]: info: Discovery: this is already registered, daffe1e1-8397-4ecc-8f9d-2f7e8d5361b1 Jan 26 02:01:02 volumio volumio[1477]: info: Discovery: Found device Volumio Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:02 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:02 volumio volumio[1477]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: Failed to determine most appropriate reg domain: Error: spawnSync /bin/sh ETIMEDOUT Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Jan 26 02:01:02 volumio volumio[1477]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 26 02:01:02 volumio mpd[2059]: 2026-01-26T02:01:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 02:01:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 26 02:01:02 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio volumio[1477]: info: Starting Shairport Sync Jan 26 02:01:02 volumio sudo[2122]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 26 02:01:02 volumio sudo[2122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 02:01:02 volumio sudo[2122]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio volumio[1477]: info: Starting Shairport Sync Jan 26 02:01:02 volumio volumio[1477]: info: Starting Shairport Sync Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 26 02:01:02 volumio sudo[2127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=info msg="zeroconf server listening on port 44455" Jan 26 02:01:02 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:02 volumio sudo[2130]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 26 02:01:02 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 02:01:02 volumio sudo[2131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 02:01:02 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:02 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 26 02:01:02 volumio sudo[2133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 02:01:02 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 26 02:01:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 02:01:02 volumio systemd[1]: shairport-sync.service: Consumed 2.027s CPU time. Jan 26 02:01:02 volumio wpa_supplicant[2138]: Successfully initialized wpa_supplicant Jan 26 02:01:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="obtained new client token: AAAmnOEPN9dEhp/wLrdik01tFwLG1aTZ7gbpCsEaP9p71bGzfGTtrvCua6tZE5+yFn2WQHQ69XmSU+vUrD5asyi9j8bBgB00YSc8NzNEZBiss+clYAukytnWVc+7I4xA+QovA82Osw7JABRr6cx4DzZYngcODmtei7rc/3NcCDPfbyLnf6IhT+C1gUsIj2V5qT+/HC0J0aBTL9Tmvh7ywGvqI92oDnd05P7WZcGKdS1ILm1cSMwlB34=" Jan 26 02:01:02 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:02 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:02 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:02 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 26 02:01:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 26 02:01:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 02:01:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 26 02:01:02 volumio volumio[1477]: info: Completed starting Core Plugins Jan 26 02:01:02 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:02 volumio volumio[1477]: info: ------------------------------------------- Jan 26 02:01:02 volumio volumio[1477]: info: ----- MyVolumio plugins startup ---- Jan 26 02:01:02 volumio volumio[1477]: info: ------------------------------------------- Jan 26 02:01:02 volumio volumio[1477]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 10892ms Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="completed keyexchange" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="completed challenge" Jan 26 02:01:02 volumio volumio[1477]: info: New Spotify access tokenBQA7MfMroE... Jan 26 02:01:02 volumio volumio[1477]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=info msg="authenticated AP" username="v0*********************nl" Jan 26 02:01:02 volumio wireless.js[937]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Jan 26 02:01:02 volumio volumio[1477]: info: Shairport-Sync Started Jan 26 02:01:02 volumio volumio[1477]: Error adding Membership: Error: addMembership EINVAL Jan 26 02:01:02 volumio volumio[1477]: info: Shairport-Sync Started Jan 26 02:01:02 volumio volumio[1477]: info: Shairport-Sync Started Jan 26 02:01:02 volumio volumio[1477]: info: Display-configuration --- X server ready after 1 attempt(s) Jan 26 02:01:02 volumio wireless.js[937]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jan 26 02:01:02 volumio volumio[1477]: error: MPD error: The expression evaluated to a falsy value: Jan 26 02:01:02 volumio volumio[1477]: assert.ok(self.idling) Jan 26 02:01:02 volumio volumio[1477]: error: The expression evaluated to a falsy value: Jan 26 02:01:02 volumio volumio[1477]: assert.ok(self.idling) Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=info msg="authenticated Login5" username="v0*********************nl" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="initializing zeroconf session" username="v0*********************nl" Jan 26 02:01:02 volumio volumio[1477]: info: MPD running with PID2059 Jan 26 02:01:02 volumio volumio[1477]: ,establishing connection Jan 26 02:01:02 volumio volumio[1477]: error: updateQueue error: null Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="dealer connection opened" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=trace msg="starting accesspoint recv loop" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=trace msg="starting dealer recv loop" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=trace msg="received accesspoint ping" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="received connection id: NzJlNDVhZWUtMmM0...NURERDRFNjNGQg==" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=trace msg="received accesspoint pong ack" Jan 26 02:01:02 volumio volumio[1477]: warn: Display-configuration --- No ACPI lid devices detected, lid monitoring disabled. Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 26 02:01:02 volumio go-librespot[2093]: time="2026-01-26T02:01:02+01:00" level=debug msg="update volume requested to 65535/65535" Jan 26 02:01:02 volumio volumio[1477]: info: Display-configuration --- DPMS disabled before playback state check Jan 26 02:01:02 volumio volumio[1477]: error: updateQueue error: null Jan 26 02:01:02 volumio volumio[1477]: info: Display-configuration --- Connected screens: HDMI-2 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- xscreensaver cleaned up before starting Jan 26 02:01:03 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:03 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:03 volumio go-librespot[2093]: time="2026-01-26T02:01:03+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 26 02:01:03 volumio go-librespot[2093]: time="2026-01-26T02:01:03+01:00" level=trace msg="emitting websocket event: volume" Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- xscreensaver started (using ~/.xscreensaver settings) Jan 26 02:01:03 volumio volumio[1477]: SPOTIFY: User informations: {"country":"IT","display_name":"Albi","email":"alberto_mele@tim.it","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/v0ecbvxf3c4bnwp4fyqhtb1nl"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/v0ecbvxf3c4bnwp4fyqhtb1nl","id":"v0ecbvxf3c4bnwp4fyqhtb1nl","images":[],"product":"premium","type":"user","uri":"spotify:user:v0ecbvxf3c4bnwp4fyqhtb1nl"} Jan 26 02:01:03 volumio volumio[1477]: info: Spotify Successfully logged in Jan 26 02:01:03 volumio volumio[1477]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 02:01:03 volumio volumio[1477]: info: [1769389263137] CoreMusicLibrary::Adding element Spotify Jan 26 02:01:03 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:03 volumio volumio[1477]: Cannot find translation for source Jellyfin Jan 26 02:01:03 volumio volumio[1477]: Cannot find translation for source Spotify Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- No forced DRM orientation detected for HDMI-2 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- TTY: display=normal(0) + offset=same(0) = 0 deg (fbconv=0) Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Plymouth: display=normal(0) + offset=same(0) = 0 deg Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Connected screens: HDMI-2 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Rotation config saved for Grub: screen=HDMI-2, plymouth=0, fbcon=0 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Panel Rotation applied via xrandr Jan 26 02:01:03 volumio sudo[2200]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/graphics/fbcon/rotate_all Jan 26 02:01:03 volumio sudo[2200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:03 volumio sudo[2200]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Runtime rotation applied: normal | Boot config (plymouth=0, fbconv=0) Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- fbcon console rotation set to 0 Jan 26 02:01:03 volumio sudo[2207]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 02:01:03 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:03 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:03 volumio sudo[2208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Connected screens: HDMI-2 Jan 26 02:01:03 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:03 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:03 volumio volumio[1477]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- No touchscreen detected, skipping correction. Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- applyPointerCorrection: pointer_offset=0 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Pointer correction: none (xrandr handles relative devices) Jan 26 02:01:03 volumio volumio[1477]: warn: Display-configuration --- No backlight device found, brightness control unavailable. Falling back to Soft Brightness Jan 26 02:01:03 volumio sudo[2215]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -9 -f unclutter Jan 26 02:01:03 volumio sudo[2215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:03 volumio volumio[1477]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.90 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 OPR/125.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Connected screens: HDMI-2 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- Brightness set to 100% for screen HDMI-2 Jan 26 02:01:03 volumio volumio[1477]: info: Display-configuration --- No unclutter process to stop Jan 26 02:01:03 volumio volumio[1477]: info: [now-playing] OpenWeatherMap API key obtained. Jan 26 02:01:03 volumio volumio[1477]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 26 02:01:03 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 26 02:01:03 volumio sudo[2225]: volumio : PWD=/ ; USER=root ; ENV=DISPLAY=:0 ; COMMAND=/usr/bin/unclutter-xfixes -idle 3 Jan 26 02:01:03 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:04 volumio volumio[1477]: info: Listing playlists Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::volumioGetQueue Jan 26 02:01:04 volumio volumio[1477]: info: CoreStateMachine::getQueue Jan 26 02:01:04 volumio volumio[1477]: info: CorePlayQueue::getQueue Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:04 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:04 volumio sudo[2229]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 02:01:04 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:04 volumio sudo[2231]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 02:01:04 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:04 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:04 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:04 volumio volumio[1477]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.90 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 OPR/125.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:04 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 02:01:04 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:04 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:04 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:04 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:04 volumio volumio[1477]: info: Listing playlists Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 02:01:04 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 02:01:04 volumio volumio[1477]: info: Display-configuration --- Kiosk browser detected after 1s, re-applying input settings in 3s Jan 26 02:01:05 volumio volumio[1477]: info: [jellyfin-poller] Polled http:\\192.168.1.50:8096: offline Jan 26 02:01:05 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:05 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:05 volumio volumio[1477]: info: go-librespot daemon successfully initialized Jan 26 02:01:05 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 26 02:01:05 volumio sudo[2256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 02:01:05 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:05 volumio sudo[2258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 02:01:05 volumio sudo[2258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:05 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:05 volumio sudo[2258]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:05 volumio sudo[2262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 02:01:05 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:06 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 26 02:01:06 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 02:01:06 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:06 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:06 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:06 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:06 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:06 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:06 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:07 volumio volumio[1477]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 26 02:01:07 volumio volumio[1477]: info: Display-configuration --- Connected screens: HDMI-2 Jan 26 02:01:07 volumio volumio[1477]: info: Display-configuration --- No touchscreen detected, skipping correction. Jan 26 02:01:07 volumio volumio[1477]: info: Display-configuration --- applyPointerCorrection: pointer_offset=0 Jan 26 02:01:07 volumio volumio[1477]: info: Display-configuration --- Pointer correction: none (xrandr handles relative devices) Jan 26 02:01:07 volumio volumio[1477]: info: Display-configuration --- Input settings re-applied after kiosk start Jan 26 02:01:08 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 26 02:01:08 volumio volumio[1477]: info: Initializing connection to go-librespot Websocket Jan 26 02:01:08 volumio go-librespot[2093]: time="2026-01-26T02:01:08+01:00" level=debug msg="new websocket client" Jan 26 02:01:08 volumio volumio[1477]: info: Connection to go-librespot Websocket established Jan 26 02:01:08 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 26 02:01:08 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 02:01:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 02:01:08 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:08 volumio volumio[1477]: info: Upmpdcli Daemon Started Jan 26 02:01:10 volumio upmpdcli[2292]: writing RSA key Jan 26 02:01:11 volumio volumio[1477]: info: Getting Spotify volume Jan 26 02:01:11 volumio volumio[1477]: info: Spotify volume: 100 Jan 26 02:01:11 volumio volumio[1477]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 26 02:01:11 volumio volumio[1477]: info: Adding plugin bluetooth to MyMusic Plugins Jan 26 02:01:11 volumio volumio[1477]: info: Adding plugin multiroom to MyMusic Plugins Jan 26 02:01:11 volumio volumio[1477]: info: Adding plugin metavolumio to MyMusic Plugins Jan 26 02:01:11 volumio volumio[1477]: info: Adding plugin cd_controller to MyMusic Plugins Jan 26 02:01:11 volumio volumio[1477]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 26 02:01:11 volumio volumio[1477]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 26 02:01:11 volumio volumio[1477]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 26 02:01:11 volumio volumio[1477]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 26 02:01:12 volumio volumio[1477]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 26 02:01:12 volumio volumio[1477]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 26 02:01:12 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:12 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:12 volumio volumio[1477]: info: Starting MyVolumio Remote Streaming Endpoints Jan 26 02:01:12 volumio volumio[1477]: info: MyVolumio login type: Token Jan 26 02:01:12 volumio volumio[1477]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 26 02:01:12 volumio volumio[1477]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 26 02:01:12 volumio volumio[1477]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 26 02:01:12 volumio volumio[1477]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 26 02:01:12 volumio volumio[1477]: info: Streaming services startup Jan 26 02:01:12 volumio volumio[1477]: info: Starting Streaming Daemon Jan 26 02:01:12 volumio volumio[1477]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 26 02:01:12 volumio sudo[2301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 02:01:12 volumio sudo[2301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:12 volumio sudo[2301]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:12 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 02:01:12 volumio volumio[1477]: error: Cannot start Volumio Streaming Daemon Jan 26 02:01:12 volumio volumio[1477]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 02:01:12 volumio volumio[1477]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 02:01:12 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:12 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:13 volumio volumio[1477]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 26 02:01:14 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 26 02:01:14 volumio volumio[1477]: info: MyVolumio token set successfully Jan 26 02:01:14 volumio volumio[1477]: info: MYVOLUMIO: Adding device Jan 26 02:01:14 volumio volumio[1477]: info: MYVOLUMIO: Evaluating Server Jan 26 02:01:14 volumio volumio[1477]: info: MyVolumio status changed Jan 26 02:01:14 volumio volumio[1477]: info: Streaming services startup Jan 26 02:01:14 volumio volumio[1477]: info: Starting Streaming Daemon Jan 26 02:01:14 volumio volumio[1477]: info: Removing browser output: myVolumio user plan is not superstar Jan 26 02:01:14 volumio volumio[1477]: info: Removing audio output: Jan 26 02:01:14 volumio volumio[1477]: info: Stoppping Tunnel 1 Jan 26 02:01:14 volumio sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 02:01:14 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:14 volumio sudo[2332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 26 02:01:14 volumio sudo[2332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:14 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:14 volumio volumio[1477]: error: Cannot start Volumio Streaming Daemon Jan 26 02:01:14 volumio volumio[1477]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 02:01:14 volumio volumio[1477]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 02:01:14 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. Jan 26 02:01:14 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. Jan 26 02:01:14 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. Jan 26 02:01:14 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. Jan 26 02:01:14 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. Jan 26 02:01:14 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. Jan 26 02:01:14 volumio sudo[2332]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:14 volumio volumio[1477]: info: Remote SSH Stopped Jan 26 02:01:14 volumio volumio[1477]: info: Setting Geolocation for MyVolumio to eu11 Jan 26 02:01:14 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:14 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:14 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:15 volumio volumio[1477]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 26 02:01:15 volumio volumio[1477]: info: Updating MyVolumio device info Jan 26 02:01:15 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:15 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:15 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:16 volumio volumio[1477]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 26 02:01:17 volumio sudo[2353]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 02:01:17 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:17 volumio sudo[2355]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 02:01:17 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:17 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:17 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:17 volumio volumio[1477]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.90 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 OPR/125.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 26 02:01:17 volumio sudo[2359]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 02:01:17 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:17 volumio sudo[2361]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 02:01:17 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:01:17 volumio sudo[2361]: pam_unix(sudo:session): session closed for user root Jan 26 02:01:17 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:17 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 02:01:17 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:17 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: Listing playlists Jan 26 02:01:17 volumio volumio[1477]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.90 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 OPR/125.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:17 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 02:01:17 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:17 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:17 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:17 volumio volumio[1477]: info: Listing playlists Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 26 02:01:17 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 26 02:01:19 volumio volumio[1477]: info: MYVOLUMIO: Adding device Jan 26 02:01:19 volumio volumio[1477]: info: MYVOLUMIO: Evaluating Server Jan 26 02:01:19 volumio volumio[1477]: info: Setting Geolocation for MyVolumio to eu6 Jan 26 02:01:19 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:19 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:19 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:20 volumio volumio[1477]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 26 02:01:20 volumio volumio[1477]: info: Updating MyVolumio device info Jan 26 02:01:20 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:20 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:20 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:20 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:20 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:21 volumio volumio[1477]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 26 02:01:22 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 02:01:22 volumio volumio[1477]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 26 02:01:22 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 26 02:01:22 volumio volumio[1477]: info: Received Get System Version Jan 26 02:01:22 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 02:01:22 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:22 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:22 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:22 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:22 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:22 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:22 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:22 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 26 02:01:23 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 26 02:01:23 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 26 02:01:26 volumio volumio[1477]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Jan 26 02:01:26 volumio volumio[1477]: info: Bluetoothremote--- Device list cleared and placeholder written. Jan 26 02:01:26 volumio bluetoothd[941]: Adv Monitor app :1.21 disconnected from D-Bus Jan 26 02:01:27 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 02:01:27 volumio volumio[1477]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 26 02:01:27 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:27 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:27 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 26 02:01:27 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 26 02:01:27 volumio volumio[1477]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 02:01:27 volumio volumio[1477]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Jan 26 02:01:27 volumio bluetoothd[941]: Adv Monitor app :1.22 disconnected from D-Bus Jan 26 02:01:28 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 02:01:28 volumio volumio[1477]: info: Received Get System Info Jan 26 02:01:28 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:01:28 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:01:28 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:01:28 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:01:28 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:01:28 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:01:31 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 02:01:31 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:31 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 02:01:31 volumio volumio-remote-updater[936]: Test mode disabled Jan 26 02:01:31 volumio volumio-remote-updater[936]: Alpha mode disabled Jan 26 02:01:31 volumio volumio-remote-updater[936]: Alpha legacy test mode disabled Jan 26 02:01:31 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 26 02:01:32 volumio volumio[1477]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 02:01:32 volumio volumio[1477]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 26 02:01:32 volumio volumio[1477]: info: Completed starting MyVolumio Plugin Jan 26 02:01:32 volumio volumio[1477]: [Metrics] CommandRouter: 39s 696.81ms Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::volumiosetStartupVolume Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::Close All Modals sent Jan 26 02:01:32 volumio volumio[1477]: info: CoreCommandRouter::Close All Modals sent Jan 26 02:01:32 volumio go-librespot[2093]: time="2026-01-26T02:01:32+01:00" level=trace msg="sent dealer ping" Jan 26 02:01:32 volumio volumio[1477]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jan 26 02:01:32 volumio volumio[1477]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for SE Jan 26 02:01:32 volumio volumio[1477]: aplay: main:831: audio open error: No such device Jan 26 02:01:32 volumio go-librespot[2093]: time="2026-01-26T02:01:32+01:00" level=trace msg="received dealer pong" Jan 26 02:01:33 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 02:01:33 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 02:01:33 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 26 02:01:33 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 02:01:38 volumio volumio[1477]: info: [jellyfin-poller] Polled http:\\192.168.1.50:8096: offline Jan 26 02:01:39 volumio volumio[1477]: info: BOOT COMPLETED Jan 26 02:02:02 volumio go-librespot[2093]: time="2026-01-26T02:02:02+01:00" level=trace msg="sent dealer ping" Jan 26 02:02:02 volumio go-librespot[2093]: time="2026-01-26T02:02:02+01:00" level=trace msg="received dealer pong" Jan 26 02:02:08 volumio sudo[2548]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 02:02:08 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:02:08 volumio sudo[2550]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 02:02:08 volumio sudo[2550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 02:02:08 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Jan 26 02:02:08 volumio sudo[2550]: pam_unix(sudo:session): session closed for user root Jan 26 02:02:08 volumio volumio[1477]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.90 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 OPR/125.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:02:08 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 02:02:08 volumio volumio[1477]: info: Received Get System Info Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:02:08 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:02:08 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:02:08 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:08 volumio volumio[1477]: info: Listing playlists Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 02:02:08 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 26 02:02:09 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:02:09 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:10 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 26 02:02:10 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 02:02:10 volumio volumio[1477]: info: Received Get System Info Jan 26 02:02:10 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:02:10 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:02:10 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:02:10 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:02:10 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:10 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:02:11 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 02:02:11 volumio volumio[1477]: info: Received Get System Info Jan 26 02:02:11 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 02:02:11 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 02:02:11 volumio volumio[1477]: info: Discovery: Getting this device information Jan 26 02:02:11 volumio volumio[1477]: info: CoreCommandRouter::volumioGetState Jan 26 02:02:11 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:11 volumio volumio[1477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 02:02:11 volumio volumio[1477]: info: [jellyfin-poller] Polled http:\\192.168.1.50:8096: offline Jan 26 02:02:12 volumio volumio[1477]: info: CoreCommandRouter::volumioPlay Jan 26 02:02:12 volumio volumio[1477]: info: CoreStateMachine::play index undefined Jan 26 02:02:12 volumio volumio[1477]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 02:02:12 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:12 volumio volumio[1477]: info: CoreStateMachine::startPlaybackTimer Jan 26 02:02:12 volumio volumio[1477]: info: CorePlayQueue::getTrack 0 Jan 26 02:02:12 volumio volumio[1477]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Music@f4e3ec51a5944bbe96b0481136324b1e/song@songId=28057b4073c1b4756240129c30d7f1ea Jan 26 02:02:12 volumio volumio[1477]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 02:02:12 volumio volumio[1477]: Error: Server unavailable Jan 26 02:02:12 volumio volumio[1477]: at PlayController.getSongFromTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:166:19) Jan 26 02:02:12 volumio volumio[1477]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:77:49) Jan 26 02:02:12 volumio volumio[1477]: at ControllerJellyfin.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/index.js:405:87) Jan 26 02:02:12 volumio volumio[1477]: at Promise._successFn (/volumio/app/statemachine.js:1028:24) Jan 26 02:02:12 volumio volumio[1477]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 26 02:02:12 volumio volumio[1477]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 26 02:02:12 volumio volumio[1477]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 02:02:12 volumio sudo[2572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 02:01' Jan 26 02:02:12 volumio sudo[2572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"