-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2026-03-25 23:19:54 CET. -- Mar 25 23:18:20 volumio systemd-timedated[967]: Changed local time to Wed Mar 25 23:18:20 2026 Mar 25 23:18:20 volumio sudo[965]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:20 volumio volumio-time-update[608]: volumio-time-update-util: System time updated successfully. Mar 25 23:18:20 volumio systemd[1]: Started Volumio Time Update Utility. Mar 25 23:18:20 volumio wireless.js[614]: trying... Mar 25 23:18:20 volumio sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 23:18:20 volumio sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:20 volumio sudo[969]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:20 volumio wireless.js[614]: Connected to: ----CasaOnida Mar 25 23:18:20 volumio wireless.js[614]: ---- Mar 25 23:18:20 volumio sudo[972]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 23:18:20 volumio sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:20 volumio sudo[972]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:20 volumio wireless.js[614]: ... joined AP, wlan0 IPv4 is 192.168.178.82, ipV6 is undefined Mar 25 23:18:20 volumio wireless.js[614]: It's done! AP Mar 25 23:18:20 volumio ntpd[773]: Listen normally on 3 wlan0 192.168.178.82:123 Mar 25 23:18:20 volumio ntpd[773]: new interface(s) found: waking up resolver Mar 25 23:18:20 volumio systemd[1]: Started Wireless Services. Mar 25 23:18:20 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 25 23:18:20 volumio systemd[1]: Started Volumio Backend Module. Mar 25 23:18:20 volumio systemd[1]: Started Volumio Cpu Tweaker. Mar 25 23:18:20 volumio nmbd[736]: [2026/03/25 23:18:20.605784, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 25 23:18:20 volumio systemd[1]: Started Samba NMB Daemon. Mar 25 23:18:20 volumio nmbd[736]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 25 23:18:20 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 25 23:18:20 volumio volumio-cpu-tweak[978]: Setting RT Priority for mpd Mar 25 23:18:20 volumio volumio-cpu-tweak[978]: pid 35's current scheduling policy: SCHED_OTHER Mar 25 23:18:20 volumio volumio-cpu-tweak[978]: pid 35's current scheduling priority: 0 Mar 25 23:18:20 volumio volumio-cpu-tweak[978]: Setting MPD Affinity Mar 25 23:18:20 volumio volumio-cpu-tweak[978]: pid 3's current affinity mask: f Mar 25 23:18:20 volumio volumio-cpu-tweak[978]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 25 23:18:20 volumio volumio-cpu-tweak[978]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 25 23:18:20 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 25 23:18:20 volumio winbindd[989]: [2026/03/25 23:18:20.990949, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 25 23:18:20 volumio winbindd[989]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 25 23:18:21 volumio winbindd[989]: [2026/03/25 23:18:21.026915, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 25 23:18:21 volumio winbindd[989]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 25 23:18:21 volumio systemd[1]: Started Samba Winbind Daemon. Mar 25 23:18:21 volumio systemd[1]: Starting Samba SMB Daemon... Mar 25 23:18:21 volumio volumio-remote-updater[612]: [2026-03-25 23:18:21] [info] asio async_connect error: system:111 (Connection refused) Mar 25 23:18:21 volumio volumio-remote-updater[612]: [2026-03-25 23:18:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 25 23:18:21 volumio volumio-remote-updater[612]: [2026-03-25 23:18:21] [error] handle_connect error: Underlying Transport Error Mar 25 23:18:21 volumio smbd[999]: [2026/03/25 23:18:21.724955, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 25 23:18:21 volumio smbd[999]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 25 23:18:21 volumio systemd[1]: Started Samba SMB Daemon. Mar 25 23:18:21 volumio systemd[1]: Reached target Multi-User System. Mar 25 23:18:21 volumio systemd[1]: Reached target Graphical Interface. Mar 25 23:18:21 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 25 23:18:21 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 25 23:18:21 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 25 23:18:21 volumio systemd[1]: Startup finished in 15.121s (kernel) + 18.859s (userspace) = 33.981s. Mar 25 23:18:23 volumio volumio[977]: info: ------------------------------------------- Mar 25 23:18:23 volumio volumio[977]: info: ----- Volumio3 ---- Mar 25 23:18:23 volumio volumio[977]: info: ------------------------------------------- Mar 25 23:18:23 volumio volumio[977]: info: ----- System startup ---- Mar 25 23:18:23 volumio volumio[977]: info: ------------------------------------------- Mar 25 23:18:24 volumio volumio[977]: info: MYVOLUMIO Environment detected Mar 25 23:18:25 volumio volumio[977]: info: Plugin folders cleanup Mar 25 23:18:25 volumio volumio[977]: info: Scanning into folder /volumio/app/plugins/ Mar 25 23:18:25 volumio volumio[977]: info: Scanning category audio_interface Mar 25 23:18:25 volumio volumio[977]: info: Scanning category miscellanea Mar 25 23:18:25 volumio volumio[977]: info: Scanning category music_service Mar 25 23:18:25 volumio volumio[977]: info: Scanning category plugins.json Mar 25 23:18:25 volumio volumio[977]: info: Scanning category system_controller Mar 25 23:18:25 volumio volumio[977]: info: Scanning category user_interface Mar 25 23:18:25 volumio volumio[977]: info: Scanning into folder /data/plugins/ Mar 25 23:18:25 volumio volumio[977]: info: Scanning category music_service Mar 25 23:18:25 volumio volumio[977]: info: Scanning category system_controller Mar 25 23:18:25 volumio volumio[977]: info: Scanning category user_interface Mar 25 23:18:25 volumio volumio[977]: info: Plugin folders cleanup completed Mar 25 23:18:25 volumio volumio[977]: info: ------------------------------------------- Mar 25 23:18:25 volumio volumio[977]: info: ----- Core plugins startup ---- Mar 25 23:18:25 volumio volumio[977]: info: ------------------------------------------- Mar 25 23:18:25 volumio volumio[977]: info: Loading plugins from folder /volumio/app/plugins/ Mar 25 23:18:25 volumio volumio[977]: info: Adding plugin upnp to MyMusic Plugins Mar 25 23:18:25 volumio volumio[977]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 25 23:18:25 volumio volumio[977]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 25 23:18:25 volumio volumio[977]: info: Loading plugins from folder /data/plugins/ Mar 25 23:18:25 volumio volumio[977]: info: Loading plugin "system"... Mar 25 23:18:25 volumio volumio[977]: info: Loading plugin "appearance"... Mar 25 23:18:26 volumio volumio[977]: info: Loading plugin "network"... Mar 25 23:18:26 volumio volumio[977]: info: Refreshing Cached IP Addresses Mar 25 23:18:26 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 23:18:26 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:26 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 23:18:26 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:26 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:26 volumio volumio[977]: info: Loading plugin "services"... Mar 25 23:18:26 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:26 volumio volumio[977]: info: Loading plugin "volumio5onboarding"... Mar 25 23:18:26 volumio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 25 23:18:26 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:26 volumio volumio[977]: info: Loading plugin "alsa_controller"... Mar 25 23:18:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:18:27 volumio volumio[977]: info: Loading plugin "wizard"... Mar 25 23:18:27 volumio volumio[977]: info: Loading plugin "networkfs"... Mar 25 23:18:27 volumio volumio[977]: info: Starting Udev Watcher for removable devices Mar 25 23:18:27 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=sv0icUvEWucMtsTF4EsW,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.42/volumio /mnt/NAS/NAS Mar 25 23:18:27 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:27 volumio volumio[977]: info: Ignoring mount for partition: boot Mar 25 23:18:27 volumio volumio[977]: info: Ignoring mount for partition: volumio Mar 25 23:18:27 volumio volumio[977]: info: Ignoring mount for partition: volumio_data Mar 25 23:18:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 23:18:27 volumio volumio[977]: info: Loading plugin "volumio_command_line_client"... Mar 25 23:18:27 volumio volumio[977]: info: Loading plugin "upnp"... Mar 25 23:18:27 volumio volumio[977]: info: [1774477107159] Starting Upmpd Daemon Mar 25 23:18:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 23:18:27 volumio volumio[977]: info: Loading plugin "my_music"... Mar 25 23:18:27 volumio volumio[977]: info: Loading plugin "mpd"... Mar 25 23:18:27 volumio kernel: Key type cifs.spnego registered Mar 25 23:18:27 volumio kernel: Key type cifs.idmap registered Mar 25 23:18:27 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 25 23:18:27 volumio kernel: CIFS: Attempting to mount //192.168.178.42/volumio Mar 25 23:18:27 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:27 volumio volumio[977]: info: Loading plugin "upnp_browser"... Mar 25 23:18:28 volumio volumio[977]: info: Starting UPNP Browser Mar 25 23:18:28 volumio volumio[977]: info: Loading plugin "alarm-clock"... Mar 25 23:18:28 volumio volumio[977]: info: Loading plugin "airplay_emulation"... Mar 25 23:18:28 volumio volumio[977]: info: Starting Shairport Sync Mar 25 23:18:28 volumio volumio[977]: info: Loading plugin "last_100"... Mar 25 23:18:28 volumio volumio[977]: info: Loading plugin "webradio"... Mar 25 23:18:28 volumio volumio[977]: info: Loading plugin "i2s_dacs"... Mar 25 23:18:28 volumio volumio[977]: info: Loading plugin "volumiodiscovery"... Mar 25 23:18:28 volumio volumio[977]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 25 23:18:28 volumio volumio[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 23:18:28 volumio volumio[977]: *** WARNING *** For more information see Mar 25 23:18:28 volumio volumio[977]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 25 23:18:28 volumio volumio[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 23:18:28 volumio volumio[977]: *** WARNING *** For more information see Mar 25 23:18:28 volumio node[977]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 25 23:18:28 volumio node[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 23:18:28 volumio node[977]: *** WARNING *** For more information see Mar 25 23:18:28 volumio node[977]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 25 23:18:28 volumio node[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 23:18:28 volumio node[977]: *** WARNING *** For more information see Mar 25 23:18:28 volumio volumio[977]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 25 23:18:28 volumio volumio[977]: info: Discovery: Started advertising with name: Volumio Mar 25 23:18:28 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 23:18:28 volumio volumio[977]: info: Loading plugin "spop"... Mar 25 23:18:30 volumio volumio[977]: info: Loading plugin "outputs"... Mar 25 23:18:30 volumio volumio[977]: info: Loading plugin "albumart"... Mar 25 23:18:30 volumio volumio[977]: info: Plugin example_plugin is not enabled Mar 25 23:18:30 volumio volumio[977]: info: Loading plugin "inputs"... Mar 25 23:18:30 volumio volumio[977]: info: Loading plugin "updater_comm"... Mar 25 23:18:31 volumio volumio[977]: info: Plugin mpdemulation is not enabled Mar 25 23:18:31 volumio volumio[977]: info: Loading plugin "rest_api"... Mar 25 23:18:31 volumio volumio[977]: info: Loading plugin "websocket"... Mar 25 23:18:31 volumio volumio[977]: info: Starting Socket.io Server version 2.3.0 Mar 25 23:18:31 volumio volumio[977]: info: Loading plugin "backup_restore"... Mar 25 23:18:31 volumio volumio[977]: Forking 3 albumart workers Mar 25 23:18:31 volumio volumio[977]: info: Applying required configuration parameters for plugin backup_restore Mar 25 23:18:31 volumio volumio[977]: info: Plugin peppymeterbasic is not enabled Mar 25 23:18:31 volumio volumio[977]: info: Loading plugin "peppyspectrum"... Mar 25 23:18:31 volumio volumio-remote-updater[612]: [2026-03-25 23:18:31] [connect] Successful connection Mar 25 23:18:32 volumio volumio[977]: Starting albumart workers Mar 25 23:18:32 volumio volumio[977]: Starting albumart workers Mar 25 23:18:32 volumio volumio[977]: Starting albumart workers Mar 25 23:18:32 volumio volumio[977]: info: Loading plugin "touch_display"... Mar 25 23:18:33 volumio volumio[977]: info: Applying required configuration parameters for plugin touch_display Mar 25 23:18:33 volumio volumio[977]: info: Loading i18n strings for locale it Mar 25 23:18:33 volumio volumio[977]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 25 23:18:33 volumio volumio[977]: Updating browse sources language Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:33 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:33 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 23:18:33 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::initPlayerControls Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 23:18:33 volumio volumio[977]: Express server listening on port 3000 Mar 25 23:18:33 volumio volumio[977]: [Metrics] WebUI: 11s 158.89ms Mar 25 23:18:33 volumio volumio[977]: info: CoreStateMachine::resetVolumioState Mar 25 23:18:33 volumio volumio[977]: info: CoreStateMachine::getcurrentVolume Mar 25 23:18:33 volumio volumio[977]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 23:18:33 volumio volumio[977]: info: Volumio Network Manager: Network status updated: 2 Mar 25 23:18:33 volumio volumio[977]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Mar 25 23:18:33 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.178.82 from 192.168.178.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 25 23:18:33 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.178.82 from 192.168.178.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 25 23:18:33 volumio volumio-remote-updater[612]: [2026-03-25 23:18:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1774477111 101 Mar 25 23:18:33 volumio volumio[977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Mar 25 23:18:33 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: CoreStateMachine::setRepeat null single undefined Mar 25 23:18:34 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:18:34 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:18:34 volumio volumio[977]: info: CoreStateMachine::setRandom null Mar 25 23:18:34 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:18:34 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:18:34 volumio volumio[977]: info: Setting Device type: Raspberry PI Mar 25 23:18:34 volumio volumio[977]: info: Completed loading Core Plugins Mar 25 23:18:34 volumio volumio[977]: info: Preparing to generate the ALSA configuration file Mar 25 23:18:34 volumio volumio[977]: info: VolumeController:: Volume=10 Mute =false Mar 25 23:18:34 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:18:34 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:18:34 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:18:34 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 23:18:34 volumio volumio[977]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 25 23:18:34 volumio volumio[977]: info: Reading ALSA contributions from plugins. Mar 25 23:18:34 volumio volumio[977]: info: Discovery: adding 29d20366-6bcf-4ad3-a174-7ce4a4244bef Mar 25 23:18:34 volumio volumio[977]: info: Discovery: Found device Volumio Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:34 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:34 volumio volumio[977]: info: VolumeController:: Volume=10 Mute =false Mar 25 23:18:34 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:18:34 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:18:34 volumio volumio[977]: info: Asound.conf file unchanged, so no further update is needed Mar 25 23:18:34 volumio volumio[977]: info: Output device has changed, restarting MPD Mar 25 23:18:34 volumio volumio[977]: info: Output device has changed, restarting Shairport Sync Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:34 volumio sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 23:18:34 volumio sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:34 volumio sudo[1132]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:34 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 23:18:34 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:34 volumio volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 23:18:34 volumio volumio[977]: info: ___________ START PLUGINS ___________ Mar 25 23:18:34 volumio volumio[977]: info: ControllerMpd::onStart: Initializing MPD Mar 25 23:18:34 volumio volumio[977]: info: Creating MPD Configuration file Mar 25 23:18:34 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 23:18:34 volumio volumio[977]: info: [1774477114485] CoreMusicLibrary::Adding element Server multimediali Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:34 volumio systemd[1]: Listening on mpd.socket. Mar 25 23:18:34 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:34 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 23:18:34 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:34 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:34 volumio systemd[1]: Starting Music Player Daemon... Mar 25 23:18:34 volumio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 23:18:34 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:34 volumio volumio[977]: info: UPNP Browser: Client initialized successfully Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:34 volumio systemd[1]: Started Volumio5 Onboarding Server. Mar 25 23:18:34 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:34 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 25 23:18:34 volumio systemd[1]: mpd.service: Succeeded. Mar 25 23:18:34 volumio systemd[1]: Stopped Music Player Daemon. Mar 25 23:18:34 volumio systemd[1]: Starting Music Player Daemon... Mar 25 23:18:34 volumio volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 23:18:34 volumio volumio[977]: info: [1774477114665] CoreMusicLibrary::Adding element Last_100 Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 23:18:34 volumio volumio[977]: info: [1774477114671] CoreMusicLibrary::Adding element Webradio Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:18:34 volumio volumio[977]: info: Initializing BBC Radios Mar 25 23:18:34 volumio sudo[1155]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 23:18:34 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:34 volumio sudo[1155]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 25 23:18:34 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: Creating Spotify config file Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 25 23:18:34 volumio volumio[977]: info: Loading i18n strings for locale it Mar 25 23:18:34 volumio volumio[977]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 25 23:18:34 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:18:34 volumio volumio[977]: info: Volumio Calling Home Mar 25 23:18:35 volumio volumio[977]: info: Preparing to generate the ALSA configuration file Mar 25 23:18:35 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 25 23:18:35 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:35 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 25 23:18:35 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:35 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 25 23:18:35 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:35 volumio systemd[1]: Reloading. Mar 25 23:18:35 volumio volumio[977]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 25 23:18:35 volumio volumio[977]: info: Reading ALSA contributions from plugins. Mar 25 23:18:35 volumio volumio[977]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Mar 25 23:18:35 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:35.228+01:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Mar 25 23:18:35 volumio volumio[977]: info: MPD Permissions set Mar 25 23:18:35 volumio volumio[977]: info: MPD Permissions set Mar 25 23:18:35 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Mar 25 23:18:35 volumio volumio[977]: info: Spotify config file written Mar 25 23:18:35 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 25 23:18:35 volumio sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 25 23:18:35 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 25 23:18:35 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:35 volumio volumio[977]: info: Volumio called home Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:35 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 25 23:18:35 volumio volumio[977]: info: Received Get System Info Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:18:35 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:35 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:18:35 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:35.676+01:00 level=INFO msg="system info for d3191eaa6d0f588735c1cc2475f50e21" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Mar 25 23:18:35 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 25 23:18:35 volumio volumio[977]: info: Received Get System Info Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:18:35 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:35 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:18:35 volumio volumio[977]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 23:18:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 23:18:35 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:35.945+01:00 level=INFO msg="enabling local network discovery" Mar 25 23:18:35 volumio sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 25 23:18:35 volumio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:35 volumio sudo[1217]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:35 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:35.979+01:00 level=INFO msg="enabling BLE discovery" Mar 25 23:18:35 volumio volumio[977]: info: Starting Shairport Sync Mar 25 23:18:36 volumio volumio[977]: info: Starting Shairport Sync Mar 25 23:18:36 volumio volumio[977]: info: Starting Shairport Sync Mar 25 23:18:36 volumio sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 23:18:36 volumio sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 23:18:36 volumio sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 23:18:36 volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:36 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:36 volumio sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:36.125+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 25 23:18:36 volumio volumio[977]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 25 23:18:36 volumio volumio[977]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 25 23:18:36 volumio volumio[977]: SPOTIFY: BQB9YjDuqLeBAYOIOjnaGsocILL4wBiqZJfMxewb1F68xqYthUDli1EzSQjxmzGNqivvr1CtQkQY0aBf4D8ul3pDPq3wQ_oEyPbQ7tVXqCfzH9zg0uk3TYD5DxLeT-RXsDmsJTvcWDkc5OH66NuYqZfCJ_YRzvIXIKfi0s2aPBhwtBNHMsI-Y2-bA4GlL0epYJb7L0jzhwK62p6pVoIMxWLcFuwbA-omZBjX4xbzKqmRzC0MuZGE0epefsQBwrbiUwuGnCuidBBdhOyJ2qq7ziJtnZer6ihqVPenqQ Mar 25 23:18:36 volumio volumio[977]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 25 23:18:36 volumio volumio[977]: info: New Spotify access token = BQB9YjDuqLeBAYOIOjnaGsocILL4wBiqZJfMxewb1F68xqYthUDli1EzSQjxmzGNqivvr1CtQkQY0aBf4D8ul3pDPq3wQ_oEyPbQ7tVXqCfzH9zg0uk3TYD5DxLeT-RXsDmsJTvcWDkc5OH66NuYqZfCJ_YRzvIXIKfi0s2aPBhwtBNHMsI-Y2-bA4GlL0epYJb7L0jzhwK62p6pVoIMxWLcFuwbA-omZBjX4xbzKqmRzC0MuZGE0epefsQBwrbiUwuGnCuidBBdhOyJ2qq7ziJtnZer6ihqVPenqQ Mar 25 23:18:36 volumio volumio[977]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 25 23:18:36 volumio volumio[977]: info: Received Get System Info Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:18:36 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:36 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:18:36 volumio volumio[977]: info: Asound.conf file unchanged, so no further update is needed Mar 25 23:18:36 volumio volumio[977]: info: Output device has changed, restarting MPD Mar 25 23:18:36 volumio volumio[977]: info: Output device has changed, restarting Shairport Sync Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:36 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 23:18:36 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:36 volumio sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 23:18:36 volumio sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 25 23:18:36 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:36 volumio volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 25 23:18:36 volumio volumio-remote-updater[612]: No test mode Mar 25 23:18:36 volumio volumio-remote-updater[612]: No alpha test mode Mar 25 23:18:36 volumio systemd[1]: Reloading. Mar 25 23:18:36 volumio volumio[977]: info: MPD Permissions set Mar 25 23:18:36 volumio volumio[977]: info: touch_display: systemctl daemon-reload succeeded. Mar 25 23:18:36 volumio sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 23:18:36 volumio sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 23:18:36 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 25 23:18:36 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:36 volumio sudo[1245]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:36 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:36 volumio volumio[977]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 23:18:36 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:36.765+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Mar 25 23:18:36 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:36.767+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Mar 25 23:18:36 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:36.767+01:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:18:36 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 23:18:37 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.178.82 from 192.168.178.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 25 23:18:37 volumio volumio[977]: info: Starting Shairport Sync Mar 25 23:18:37 volumio sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:37 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:37 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 23:18:37 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 25 23:18:37 volumio volumio[977]: SPOTIFY: User informations: {"country":"IT","display_name":"Giuseppe Busu","email":"giuseppe.busu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1166886259"},"followers":{"href":null,"total":100},"href":"https://api.spotify.com/v1/users/1166886259","id":"1166886259","images":[{"height":300,"url":"https://scontent-mrs2-2.xx.fbcdn.net/v/t1.6435-1/39860186_10213871084066207_8310732962953953280_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=101&ccb=1-7&_nc_sid=08baa4&_nc_ohc=ygmcxugKBgQQ7kNvwF9dTg5&_nc_oc=Adp8lHM1cGoA6XboSLeFmjd_pY7sPYC67qJm9M-DVst-GOmXJvkoPIVZrax7rQdlFfiMNJDESkTE8GuFVoKsmv3m&_nc_zt=24&_nc_ht=scontent-mrs2-2.xx&edm=AP4hL3IEAAAA&_nc_gid=7VAdv69X_BHxBoS2rfZ7Gw&_nc_tpa=Q5bMBQG0Ac1i8QiZ2VTEgaSmnmFDT0eR3qKiSZwLBoszl1v2hr2yFT97E0Tj_eu8sR7coofIkoca&oh=00_AfyQa6tMnY9W9Np206uNEQTTT0GuHDRCSADB__ntFm2AWw&oe=69EB28F2","width":300},{"height":64,"url":"https://scontent-mrs2-2.xx.fbcdn.net/v/t1.6435-1/39860186_10213871084066207_8310732962953953280_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=101&ccb=1-7&_nc_sid=28885b&_nc_ohc=ygmcxugKBgQQ7kNvwF9dTg5&_nc_oc=Adp8lHM1cGoA6XboSLeFmjd_pY7sPYC67qJm9M-DVst-GOmXJvkoPIVZrax7rQdlFfiMNJDESkTE8GuFVoKsmv3m&_nc_zt=24&_nc_ht=scontent-mrs2-2.xx&edm=AP4hL3IEAAAA&_nc_gid=7VAdv69X_BHxBoS2rfZ7Gw&_nc_tpa=Q5bMBQEuo5be_LpZ9vXB6M9hRt4-wZzAnIOLAYQUTiVpayMJxNiMoD5a9BpGMCsdaIQRvprkJttA&oh=00_AfwJjpe52cMoFJcfRoqHOI6SZ0jz41dKly5YR2UO5hTGbQ&oe=69EB28F2","width":64}],"product":"premium","type":"user","uri":"spotify:user:1166886259"} Mar 25 23:18:37 volumio volumio[977]: info: Spotify Successfully logged in Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 23:18:37 volumio volumio[977]: info: [1774477117143] CoreMusicLibrary::Adding element Spotify Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:37 volumio volumio[977]: Cannot find translation for source Spotify Mar 25 23:18:37 volumio sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 23:18:37 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:37 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:37 volumio sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 23:18:37 volumio sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:37 volumio sudo[1290]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:37 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 25 23:18:37 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:37 volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 23:18:37 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:37 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:37 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 23:18:37 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:37 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:37 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.178.82 from 192.168.178.68 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:37 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:37 volumio volumio[977]: info: Listing playlists Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 23:18:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 23:18:37 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:37 volumio volumio[977]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 25 23:18:37 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 25 23:18:37 volumio systemd[1]: Started Volumio Kiosk. Mar 25 23:18:37 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 25 23:18:37 volumio systemd[1]: Started go-librespot Daemon. Mar 25 23:18:37 volumio volumio[977]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 25 23:18:37 volumio go-librespot[1304]: Librespot-go daemon starting... Mar 25 23:18:37 volumio volumio[977]: info: touch_display: Volumio Kiosk started. Mar 25 23:18:37 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio volumio5-onboarding[1152]: time=2026-03-25T23:18:38.008+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 23:18:38 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 25 23:18:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 25 23:18:38 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:38 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 25 23:18:38 volumio systemd[1]: mpd.service: Succeeded. Mar 25 23:18:38 volumio systemd[1]: Stopped Music Player Daemon. Mar 25 23:18:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:38 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:38 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio volumio[977]: info: Upmpdcli Daemon Started Mar 25 23:18:38 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio volumio[977]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 25 23:18:38 volumio volumio[977]: info: touch_display: X display number found: 0 Mar 25 23:18:38 volumio systemd[1]: Starting Music Player Daemon... Mar 25 23:18:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 25 23:18:38 volumio sudo[1224]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio sudo[1227]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio volumio[977]: info: Shairport-Sync Started Mar 25 23:18:38 volumio volumio[977]: Error adding Membership: Error: addMembership EINVAL Mar 25 23:18:38 volumio volumio[977]: info: Shairport-Sync Started Mar 25 23:18:38 volumio volumio[977]: info: touch_display: X display number found: 0 Mar 25 23:18:38 volumio volumio[977]: info: touch_display: X display number found: 0 Mar 25 23:18:38 volumio sudo[1334]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 25 23:18:38 volumio sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:38 volumio sudo[1334]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 25 23:18:38 volumio sudo[1334]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:38 volumio startx[1303]: X.Org X Server 1.20.4 Mar 25 23:18:38 volumio startx[1303]: X Protocol Version 11, Revision 0 Mar 25 23:18:38 volumio startx[1303]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 25 23:18:38 volumio startx[1303]: Current Operating System: Linux volumio 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Mar 25 23:18:38 volumio startx[1303]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=7462f9ec-50f6-4f55-9f22-79cca1bee42a imgfile=/volumio_current.sqsh bootpart=UUID=6B37-2179 datapart=UUID=05aa9627-e829-40c7-ad33-94111ba2ebbc uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 25 23:18:38 volumio startx[1303]: Build Date: 04 April 2023 07:50:56AM Mar 25 23:18:38 volumio startx[1303]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 25 23:18:38 volumio startx[1303]: Current version of pixman: 0.36.0 Mar 25 23:18:38 volumio startx[1303]: Before reporting problems, check http://wiki.x.org Mar 25 23:18:38 volumio startx[1303]: to make sure that you have the latest version. Mar 25 23:18:38 volumio startx[1303]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 25 23:18:38 volumio startx[1303]: (++) from command line, (!!) notice, (II) informational, Mar 25 23:18:38 volumio startx[1303]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 25 23:18:38 volumio startx[1303]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Mar 25 23:18:38 2026 Mar 25 23:18:38 volumio sh[523]: timed out Mar 25 23:18:38 volumio dhcpcd[551]: timed out Mar 25 23:18:38 volumio sh[523]: dhcpcd exited Mar 25 23:18:38 volumio dhcpcd[551]: dhcpcd exited Mar 25 23:18:38 volumio sh[523]: ifup: failed to bring up eth0 Mar 25 23:18:38 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 25 23:18:38 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 25 23:18:38 volumio startx[1303]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 25 23:18:38 volumio startx[1303]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 25 23:18:38 volumio volumio[977]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 25 23:18:38 volumio volumio[977]: info: touch_display: X display number found: 0 Mar 25 23:18:38 volumio go-librespot[1304]: time="2026-03-25T23:18:38+01:00" level=info msg="generated new device id: 0a67858ad063ad09617f8a671c9c94ae113a7860" Mar 25 23:18:38 volumio go-librespot[1304]: time="2026-03-25T23:18:38+01:00" level=debug msg="stored credentials found for 1166886259" Mar 25 23:18:38 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=sv0icUvEWucMtsTF4EsW,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.42/volumio /mnt/NAS/NAS Mar 25 23:18:38 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:38 volumio kernel: CIFS: Attempting to mount //192.168.178.42/volumio Mar 25 23:18:39 volumio go-librespot[1304]: time="2026-03-25T23:18:39+01:00" level=debug msg="obtained new client token: AABAUnfk6FA4LmOpr87vsckUQ54+hDdlF0sbwbFO16jEZchR3TP6kJaNQWwROpc45RdVBivhb258iJqljwR2DN6O8D4Sn3aHe89jtDfG1vEG+dAu1HMyXpl8Ldyax95SOZlAU96qDAp/3ao1XdTvpvK5yYm0aVJj17+y7qvO3iIW/UoLQ3HaiTMsjP1oKBKXbgPJCtQlwuXMLBziz9UP82W8nGr1Het3C8Mlh2OWPInseSFjGrkUl0FE+C3kVek=" Mar 25 23:18:39 volumio go-librespot[1304]: time="2026-03-25T23:18:39+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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 25 23:18:39 volumio go-librespot[1304]: time="2026-03-25T23:18:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 34.158.1.133:4070: connect: connection refused" Mar 25 23:18:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 23:18:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 23:18:39 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 23:18:39 volumio volumio[977]: info: Received Get System Info Mar 25 23:18:39 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:18:39 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:18:39 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:18:39 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:39 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:39 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:18:40 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 23:18:40 volumio volumio[977]: info: Received Get System Info Mar 25 23:18:40 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:18:40 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:18:40 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:18:40 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:40 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:40 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:18:40 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:40 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:40 volumio volumio[977]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 25 23:18:40 volumio mpd[1344]: Mar 25 23:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 25 23:18:40 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 25 23:18:40 volumio systemd[1]: Started Music Player Daemon. Mar 25 23:18:40 volumio volumio[977]: Upnp client error: Error: This socket has been ended by the other party Mar 25 23:18:40 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:40 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:40 volumio sudo[1233]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:40 volumio volumio[977]: info: Completed starting Core Plugins Mar 25 23:18:40 volumio volumio[977]: info: ------------------------------------------- Mar 25 23:18:40 volumio volumio[977]: info: ----- MyVolumio plugins startup ---- Mar 25 23:18:40 volumio volumio[977]: info: ------------------------------------------- Mar 25 23:18:40 volumio volumio[977]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 25 23:18:40 volumio volumio[977]: error: MPD error: The expression evaluated to a falsy value: Mar 25 23:18:40 volumio volumio[977]: assert.ok(self.idling) Mar 25 23:18:40 volumio volumio[977]: error: The expression evaluated to a falsy value: Mar 25 23:18:40 volumio volumio[977]: assert.ok(self.idling) Mar 25 23:18:40 volumio volumio[977]: error: MPD error: The expression evaluated to a falsy value: Mar 25 23:18:40 volumio volumio[977]: assert.ok(self.idling) Mar 25 23:18:40 volumio volumio[977]: error: The expression evaluated to a falsy value: Mar 25 23:18:40 volumio volumio[977]: assert.ok(self.idling) Mar 25 23:18:40 volumio volumio[977]: info: MPD running with PID1344 Mar 25 23:18:40 volumio volumio[977]: ,establishing connection Mar 25 23:18:40 volumio volumio[977]: error: MPD error: The expression evaluated to a falsy value: Mar 25 23:18:40 volumio volumio[977]: assert.ok(self.idling) Mar 25 23:18:40 volumio volumio[977]: error: The expression evaluated to a falsy value: Mar 25 23:18:40 volumio volumio[977]: assert.ok(self.idling) Mar 25 23:18:40 volumio volumio[977]: error: updateQueue error: null Mar 25 23:18:41 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 25 23:18:41 volumio volumio[977]: info: go-librespot daemon successfully initialized Mar 25 23:18:42 volumio volumio[1302]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 25 23:18:42 volumio volumio[977]: info: VolumeController::SetAlsaVolume5 Mar 25 23:18:42 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:18:42 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:42 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:18:42 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:18:42 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status stop Mar 25 23:18:42 volumio sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 25 23:18:42 volumio sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 25 23:18:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 25 23:18:42 volumio sudo[1441]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:42 volumio systemd[1]: Stopped go-librespot Daemon. Mar 25 23:18:42 volumio volumio[977]: info: peppyspectrum Daemon Stop Mar 25 23:18:42 volumio systemd[1]: Started go-librespot Daemon. Mar 25 23:18:42 volumio go-librespot[1453]: Librespot-go daemon starting... Mar 25 23:18:42 volumio go-librespot[1453]: time="2026-03-25T23:18:42+01:00" level=info msg="generated new device id: 77da58fc6ec85b8d4fa837b2f0a8f3f8bcaea77c" Mar 25 23:18:42 volumio go-librespot[1453]: time="2026-03-25T23:18:42+01:00" level=debug msg="stored credentials found for 1166886259" Mar 25 23:18:42 volumio go-librespot[1453]: time="2026-03-25T23:18:42+01:00" level=debug msg="obtained new client token: AACfrqahA3VyEsLsbZk7ABWLb8j96yC4hwnJD3pLLGLwI6dB5bTBlRHO/+7OW50KWMmtQXszRL3MUlvTFP21QaN0Xjj164vpxDGA02xUZuH9y63mOp+Z4yjA5ifqbjF7DzqUhXQJOkFo8hwdDH8h4PnlwniAYWVF7Gok/oNR2lLFt5yGTG0AEaOXxNycvnCeZWiAejyBH9S2vZNqPWflyCoDiNJaJVPRcbvIBQyJoMLzCZE0J1bMuD4NiFNya2g=" Mar 25 23:18:43 volumio go-librespot[1453]: time="2026-03-25T23:18:43+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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 25 23:18:43 volumio go-librespot[1453]: time="2026-03-25T23:18:43+01:00" level=debug msg="completed keyexchange" Mar 25 23:18:43 volumio go-librespot[1453]: time="2026-03-25T23:18:43+01:00" level=debug msg="completed challenge" Mar 25 23:18:43 volumio volumio[1302]: .........++++ Mar 25 23:18:43 volumio go-librespot[1453]: time="2026-03-25T23:18:43+01:00" level=debug msg="authenticated as 1166886259" Mar 25 23:18:43 volumio go-librespot[1453]: time="2026-03-25T23:18:43+01:00" level=debug msg="authenticated as 1166886259" Mar 25 23:18:43 volumio go-librespot[1453]: time="2026-03-25T23:18:43+01: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]" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 25 23:18:44 volumio volumio[977]: info: Initializing connection to go-librespot Websocket Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="new websocket client" Mar 25 23:18:44 volumio volumio[977]: info: Connection to go-librespot Websocket established Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="dealer connection opened" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="received connection id: OGQzNTVjNWUtYWI3ZS00ZmI2LWEzZjAtYTg5NDZlOTNlMTM3K2RlYWxlcit0Y3A6Ly8wYWIxNTAzNC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQTAxNUVBREVCODExOUNDNTFCQUZCMkEyQUQ3ODRBMzA4RDYwRTBBQ0Y0NjFBQzJFMTIyMTEwN0Q5Rjg2NzVDRQ==" Mar 25 23:18:44 volumio go-librespot[1453]: time="2026-03-25T23:18:44+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 25 23:18:44 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Mar 25 23:18:45 volumio volumio[977]: info: CoreStateMachine::play index undefined Mar 25 23:18:45 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:18:45 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:45 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:45 volumio volumio[977]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Mar 25 23:18:45 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 23:18:45 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 25 23:18:45 volumio volumio[977]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 25 23:18:46 volumio volumio[1302]: .................++++ Mar 25 23:18:46 volumio volumio[1302]: e is 65537 (0x010001) Mar 25 23:18:46 volumio volumio[1302]: writing RSA key Mar 25 23:18:47 volumio volumio[977]: info: Getting Spotify volume Mar 25 23:18:47 volumio volumio[977]: info: Spotify volume: 100 Mar 25 23:18:47 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 25 23:18:47 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:47 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:47 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 25 23:18:47 volumio volumio[977]: SPOTIFY: SPOTIFY VOLUME 100 Mar 25 23:18:47 volumio volumio[977]: SPOTIFY: VOLUMIO VOLUME 5 Mar 25 23:18:47 volumio volumio[977]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 25 23:18:47 volumio volumio[977]: info: Setting Spotify Volume from Volumio: 5 Mar 25 23:18:48 volumio volumio[977]: SPOTIFY: SETTING SPOTIFY VOLUME 5 Mar 25 23:18:48 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/volume Mar 25 23:18:48 volumio go-librespot[1453]: time="2026-03-25T23:18:48+01:00" level=debug msg="update volume to 3276/65535" Mar 25 23:18:48 volumio go-librespot[1453]: time="2026-03-25T23:18:48+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 25 23:18:48 volumio go-librespot[1453]: time="2026-03-25T23:18:48+01:00" level=trace msg="emitting websocket event: volume" Mar 25 23:18:48 volumio volumio[977]: SPOTIFY: received: {"type":"volume","data":{"value":5,"max":100}} Mar 25 23:18:48 volumio volumio[977]: SPOTIFY: RECEIVED SPOTIFY VOLUME 5 Mar 25 23:18:49 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 23:18:49 volumio volumio[977]: info: CURURI: music-library Mar 25 23:18:49 volumio volumio[977]: info: CoreCommandRouter::volumioGetQueue Mar 25 23:18:49 volumio volumio[977]: info: CoreStateMachine::getQueue Mar 25 23:18:49 volumio volumio[977]: info: CorePlayQueue::getQueue Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 25 23:18:49 volumio volumio[977]: info: Adding plugin bluetooth to MyMusic Plugins Mar 25 23:18:49 volumio volumio[977]: info: Adding plugin multiroom to MyMusic Plugins Mar 25 23:18:49 volumio volumio[977]: info: Adding plugin metavolumio to MyMusic Plugins Mar 25 23:18:49 volumio volumio[977]: info: Adding plugin cd_controller to MyMusic Plugins Mar 25 23:18:49 volumio volumio[977]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 25 23:18:49 volumio volumio[977]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 25 23:18:49 volumio volumio[977]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 25 23:18:49 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 25 23:18:50 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 25 23:18:52 volumio volumio[977]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 25 23:18:52 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 25 23:18:52 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:52 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:52 volumio volumio[977]: info: Starting MyVolumio Remote Streaming Endpoints Mar 25 23:18:52 volumio volumio[977]: info: MyVolumio login type: Token Mar 25 23:18:52 volumio volumio[977]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 25 23:18:52 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 25 23:18:53 volumio volumio[977]: info: Starting Streaming Service Transparent Proxy Mar 25 23:18:53 volumio volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 25 23:18:53 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 25 23:18:53 volumio volumio[977]: info: Streaming services startup Mar 25 23:18:53 volumio volumio[977]: info: Starting Streaming Daemon Mar 25 23:18:53 volumio volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 25 23:18:53 volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 25 23:18:53 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:53 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:53 volumio volumio[977]: info: Preload queue cleared Mar 25 23:18:53 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=sv0icUvEWucMtsTF4EsW,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.42/volumio /mnt/NAS/NAS Mar 25 23:18:53 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:53 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 23:18:53 volumio volumio[977]: info: CURURI: music-library/NAS Mar 25 23:18:54 volumio volumio[977]: error: Cannot start Volumio Streaming Daemon Mar 25 23:18:54 volumio volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 25 23:18:54 volumio volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 25 23:18:54 volumio kernel: CIFS: Attempting to mount //192.168.178.42/volumio Mar 25 23:18:54 volumio volumio[977]: info: Preload queue cleared Mar 25 23:18:54 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 23:18:54 volumio volumio[977]: info: CURURI: music-library Mar 25 23:18:54 volumio volumio[977]: info: Preload queue cleared Mar 25 23:18:54 volumio volumio[977]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 25 23:18:55 volumio volumio[977]: info: Preload queue cleared Mar 25 23:18:55 volumio volumio[977]: info: MyVolumio token set successfully Mar 25 23:18:55 volumio volumio[977]: info: MYVOLUMIO: Adding device Mar 25 23:18:55 volumio volumio[977]: info: MYVOLUMIO: Evaluating Server Mar 25 23:18:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 23:18:55 volumio volumio[977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 25 23:18:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 25 23:18:55 volumio volumio[977]: info: Received Get System Version Mar 25 23:18:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:18:55 volumio volumio[977]: info: Received Get System Info Mar 25 23:18:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:18:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:18:55 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:18:55 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:55 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:18:56 volumio volumio[977]: STREAMING PROXY: Starting server on port 3245 Mar 25 23:18:56 volumio volumio[977]: Node JS runtime: 14 Mar 25 23:18:56 volumio volumio[977]: info: MyVolumio status changed Mar 25 23:18:56 volumio volumio[977]: info: Streaming services startup Mar 25 23:18:56 volumio volumio[977]: info: Starting Streaming Daemon Mar 25 23:18:56 volumio volumio[977]: info: Removing browser output: myVolumio user plan is not superstar Mar 25 23:18:56 volumio volumio[977]: info: Removing audio output: Mar 25 23:18:56 volumio volumio[977]: info: Stoppping Tunnel 1 Mar 25 23:18:56 volumio sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 25 23:18:56 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:56 volumio sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 25 23:18:56 volumio sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:18:56 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:56 volumio volumio[977]: error: Cannot start Volumio Streaming Daemon Mar 25 23:18:56 volumio volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 25 23:18:56 volumio volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 25 23:18:56 volumio sudo[1639]: pam_unix(sudo:session): session closed for user root Mar 25 23:18:56 volumio volumio[977]: info: Remote SSH Stopped Mar 25 23:18:56 volumio volumio[977]: info: Setting Geolocation for MyVolumio to eu4 Mar 25 23:18:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:56 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:57 volumio volumio[977]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 25 23:18:57 volumio volumio[977]: info: Updating MyVolumio device info Mar 25 23:18:57 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:57 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:18:57 volumio volumio[977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 25 23:18:58 volumio volumio[977]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:18:59 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 23:18:59 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 25 23:18:59 volumio volumio[977]: info: In handleBrowseUri, curUri=spotify Mar 25 23:19:00 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:00 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:00 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:00 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:00 volumio volumio[977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 25 23:19:00 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:00 volumio volumio[977]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Mar 25 23:19:00 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 23:19:00 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 25 23:19:01 volumio volumio[977]: info: MYVOLUMIO: Adding device Mar 25 23:19:01 volumio volumio[977]: info: MYVOLUMIO: Evaluating Server Mar 25 23:19:01 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:19:01 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:01 volumio volumio[977]: info: Setting Geolocation for MyVolumio to eu7 Mar 25 23:19:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 25 23:19:02 volumio volumio[977]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DWVQfeA9N7Q0g Mar 25 23:19:02 volumio go-librespot[1453]: time="2026-03-25T23:19:02+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWVQfeA9N7Q0g" Mar 25 23:19:02 volumio go-librespot[1453]: time="2026-03-25T23:19:02+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 25 23:19:02 volumio volumio[977]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DWVQfeA9N7Q0g Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 23:19:02 volumio volumio[977]: info: Listing playlists Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 23:19:02 volumio volumio[977]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 25 23:19:02 volumio volumio[977]: info: Updating MyVolumio device info Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:03 volumio volumio[977]: info: VolumeController::SetAlsaVolume0 Mar 25 23:19:03 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:03 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:19:03 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:03 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status stop Mar 25 23:19:03 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 25 23:19:03 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:03 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:03 volumio volumio[977]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 25 23:19:03 volumio volumio[977]: info: peppyspectrum Daemon Stop Mar 25 23:19:03 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:5W1mx7Oeg2HxmASrth1nTt Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:63sQFPGkKfzcK5qEZVefpu Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:1ByC6cNyFHMIrqTDVf8Urf Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:1k3pzheXrbtHgvvOsQeMkU Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:730yruXTNk2KvXdbCNzXpR Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:2yzOkthIypp8Lwkww7LZjY Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:4kK4uxstY0lTdun71200Al Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:4ZAjcfbt84O79SGc0jWqu7 Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:1GjQApTKIADKfSoZm8bHRK Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:6IhEd41nfdL7qG4MVfZVAp Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:3X6VETSe1orqobaME0KTzQ Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:3vwObRd71oUa145riRt42O Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:1bXER40RxwNWTf9fnQOY64 Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:2DO1VNiVcIVKJVd2PhKCsW Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:11C1h0tuCW9qRa1QGPV9Vj Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:6zngwmDraHGVczoCzj7rHb Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:5NON0GOPoWxkTK6Hi4JDZi Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:2wNoBWBMt1bpoAzPX2lAi0 Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:4j88Y86NDJvbioBEZTxQeO Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:2XVaLBILDwmtR876otQkpg Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:2qkSLhQ0w3R02XzC5zwkr7 Mar 25 23:19:03 volumio volumio[977]: info: Preloading song: spotify:track:1MXiEYzY1u2DEfh2cRITv9 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:3ByNcS81LqRYUvJjpWhZ7T Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:2NmvOrVquVRNNKUPOGx3xe Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:15RaJcxk9ng0GahyzI6q3g Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5COJWRcIcK8Wn2u5G18BA5 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:229IXngdEIWPyJZuk58stZ Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4pvQiqOrowCEHeCpIDKQyP Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4cvOQtYVSPe1Tv4T4R34IC Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4OiSgru8ScEiHkrL9nThQl Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0OZMRO5yuxhVwQ3MPxll8h Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:6yrwLR7cV6DifWyO1diYFe Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1bs5J4ljYslfbSCtvKxh78 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:24y5rDZTffJ8k4woQ14T9i Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1IG5eF5NNVtGaMTLOxByln Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5NAnT7wXTNGKtrdX8Qo41B Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4WvurZy0QYcgX4pyPvxTee Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1xrQXLdFY7X0WaaBwRV4bS Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5NJeib0D2vsxEw1d1PeG3b Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:37TW8WIet2l0xkxQTuj4MT Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0xesf5VFqTMRXTadReD5Dg Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1jIm4u2ahLNHLPkiILhSa2 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4o27n0ryLEmQvC16wuf1gZ Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0CXKFZkDwVwt67bZTBkXMe Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4SB3yQM0g9XlYyyFJhGkKr Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:3tGAwQkqKMkvIL4ZG415XT Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:3Tz9knLyQw76KmDHypbJAr Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5EfDRuPRCMcuwfc5i9kwjn Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4ObYU97J8H5EiozuDEoyHo Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:6KlHqB5IKmK48uxTN0YWoF Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0VwjJbNuxG338w8a4O4ZtG Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:52Em11vMfAUJYYkLF2iEfn Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1pjVshubbxfq3KBSbIdANz Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5YXZTLBBAnDZcOSosqNRbs Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:46yBh1rkQjidNATQ7YeqTa Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0L7oxMR89ji7A6htHnPR8L Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:2CvhzdXKzJ90JQj6iLg5qI Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4CVzKDQSP7dZOzca7vQvsH Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:5W1mx7Oeg2HxmASrth1nTt in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:5W1mx7Oeg2HxmASrth1nTt Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:63sQFPGkKfzcK5qEZVefpu in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:63sQFPGkKfzcK5qEZVefpu Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:1ByC6cNyFHMIrqTDVf8Urf in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1ByC6cNyFHMIrqTDVf8Urf Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:1k3pzheXrbtHgvvOsQeMkU in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1k3pzheXrbtHgvvOsQeMkU Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:730yruXTNk2KvXdbCNzXpR in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:730yruXTNk2KvXdbCNzXpR Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:2yzOkthIypp8Lwkww7LZjY in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:2yzOkthIypp8Lwkww7LZjY Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:4kK4uxstY0lTdun71200Al in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4kK4uxstY0lTdun71200Al Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:63sQFPGkKfzcK5qEZVefpu","service":"spop","name":"OSSESSIONE","artist":"Samurai Jay","album":"OSSESSIONE","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b2734305c86a243ca7e7db9d710c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:4ZAjcfbt84O79SGc0jWqu7 in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4ZAjcfbt84O79SGc0jWqu7 Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5W1mx7Oeg2HxmASrth1nTt","service":"spop","name":"Per sempre sì","artist":"Sal Da Vinci","album":"Per sempre sì","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b2739fee241bfd5d68e758b4e4e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:1GjQApTKIADKfSoZm8bHRK in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1GjQApTKIADKfSoZm8bHRK Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1k3pzheXrbtHgvvOsQeMkU","service":"spop","name":"Che fastidio!","artist":"Ditonellapiaga","album":"Che fastidio!","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b27357fc29e972e43683bc409cd1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:730yruXTNk2KvXdbCNzXpR","service":"spop","name":"Poesie Clandestine","artist":"LDA","album":"Poesie Clandestine","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273c3528a1f7556e36edb0b0cf8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:6IhEd41nfdL7qG4MVfZVAp in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:6IhEd41nfdL7qG4MVfZVAp Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:3X6VETSe1orqobaME0KTzQ in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:3X6VETSe1orqobaME0KTzQ Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yzOkthIypp8Lwkww7LZjY","service":"spop","name":"Stupida sfortuna","artist":"Fulminacci","album":"Stupida sfortuna","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b2736ee6ea583faf9653c920b030","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:3vwObRd71oUa145riRt42O in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:3vwObRd71oUa145riRt42O Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ByC6cNyFHMIrqTDVf8Urf","service":"spop","name":"TU MI PIACI TANTO","artist":"Sayf","album":"TU MI PIACI TANTO","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b273e1eafe16a2cb5d4e306f1918","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:1bXER40RxwNWTf9fnQOY64 in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1bXER40RxwNWTf9fnQOY64 Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:2DO1VNiVcIVKJVd2PhKCsW in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:2DO1VNiVcIVKJVd2PhKCsW Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:11C1h0tuCW9qRa1QGPV9Vj in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:11C1h0tuCW9qRa1QGPV9Vj Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:6zngwmDraHGVczoCzj7rHb in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:6zngwmDraHGVczoCzj7rHb Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:5NON0GOPoWxkTK6Hi4JDZi in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:5NON0GOPoWxkTK6Hi4JDZi Mar 25 23:19:04 volumio volumio[977]: info: Exploding uri spotify:track:2wNoBWBMt1bpoAzPX2lAi0 in service spop Mar 25 23:19:04 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:2wNoBWBMt1bpoAzPX2lAi0 Mar 25 23:19:04 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4j88Y86NDJvbioBEZTxQeO Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:2XVaLBILDwmtR876otQkpg Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:2qkSLhQ0w3R02XzC5zwkr7 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1MXiEYzY1u2DEfh2cRITv9 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:3ByNcS81LqRYUvJjpWhZ7T Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:2NmvOrVquVRNNKUPOGx3xe Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:15RaJcxk9ng0GahyzI6q3g Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5COJWRcIcK8Wn2u5G18BA5 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:229IXngdEIWPyJZuk58stZ Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4pvQiqOrowCEHeCpIDKQyP Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4cvOQtYVSPe1Tv4T4R34IC Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4OiSgru8ScEiHkrL9nThQl Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0OZMRO5yuxhVwQ3MPxll8h Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:6yrwLR7cV6DifWyO1diYFe Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1bs5J4ljYslfbSCtvKxh78 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:24y5rDZTffJ8k4woQ14T9i Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1IG5eF5NNVtGaMTLOxByln Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5NAnT7wXTNGKtrdX8Qo41B Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4WvurZy0QYcgX4pyPvxTee Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1xrQXLdFY7X0WaaBwRV4bS Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5NJeib0D2vsxEw1d1PeG3b Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:37TW8WIet2l0xkxQTuj4MT Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0xesf5VFqTMRXTadReD5Dg Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1jIm4u2ahLNHLPkiILhSa2 Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4o27n0ryLEmQvC16wuf1gZ Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0CXKFZkDwVwt67bZTBkXMe Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4SB3yQM0g9XlYyyFJhGkKr Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:3tGAwQkqKMkvIL4ZG415XT Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:3Tz9knLyQw76KmDHypbJAr Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5EfDRuPRCMcuwfc5i9kwjn Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4ObYU97J8H5EiozuDEoyHo Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:6KlHqB5IKmK48uxTN0YWoF Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0VwjJbNuxG338w8a4O4ZtG Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:52Em11vMfAUJYYkLF2iEfn Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:1pjVshubbxfq3KBSbIdANz Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:5YXZTLBBAnDZcOSosqNRbs Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:46yBh1rkQjidNATQ7YeqTa Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:0L7oxMR89ji7A6htHnPR8L Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:2CvhzdXKzJ90JQj6iLg5qI Mar 25 23:19:04 volumio volumio[977]: info: Preloading song: spotify:track:4CVzKDQSP7dZOzca7vQvsH Mar 25 23:19:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:19:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:4j88Y86NDJvbioBEZTxQeO in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4j88Y86NDJvbioBEZTxQeO Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:2XVaLBILDwmtR876otQkpg in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:2XVaLBILDwmtR876otQkpg Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:2qkSLhQ0w3R02XzC5zwkr7 in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:2qkSLhQ0w3R02XzC5zwkr7 Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:1MXiEYzY1u2DEfh2cRITv9 in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1MXiEYzY1u2DEfh2cRITv9 Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ZAjcfbt84O79SGc0jWqu7","service":"spop","name":"ITALIA STARTER PACK","artist":"J-AX","album":"ITALIA STARTER PACK","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273e0ed7743fb9034a663e195cd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:3ByNcS81LqRYUvJjpWhZ7T in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:3ByNcS81LqRYUvJjpWhZ7T Mar 25 23:19:05 volumio volumio-remote-updater[612]: No test mode Mar 25 23:19:05 volumio volumio-remote-updater[612]: No alpha test mode Mar 25 23:19:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:2NmvOrVquVRNNKUPOGx3xe in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:2NmvOrVquVRNNKUPOGx3xe Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4kK4uxstY0lTdun71200Al","service":"spop","name":"MALE NECESSARIO","artist":"Fedez","album":"MALE NECESSARIO","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b273dbaafdd357e2ef1634f8d692","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:15RaJcxk9ng0GahyzI6q3g in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:15RaJcxk9ng0GahyzI6q3g Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3X6VETSe1orqobaME0KTzQ","service":"spop","name":"I romantici","artist":"Tommaso Paradiso","album":"I romantici","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b273f36d9162096b9c61c3fd388b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=sv0icUvEWucMtsTF4EsW,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.42/volumio /mnt/NAS/NAS Mar 25 23:19:05 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:05 volumio kernel: CIFS: Attempting to mount //192.168.178.42/volumio Mar 25 23:19:05 volumio volumio[977]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 25 23:19:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:5COJWRcIcK8Wn2u5G18BA5 in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:5COJWRcIcK8Wn2u5G18BA5 Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:229IXngdEIWPyJZuk58stZ in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:229IXngdEIWPyJZuk58stZ Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:4pvQiqOrowCEHeCpIDKQyP in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4pvQiqOrowCEHeCpIDKQyP Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6IhEd41nfdL7qG4MVfZVAp","service":"spop","name":"Voilà","artist":"Elettra Lamborghini","album":"Voilà","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b273109a7947a045fdf6a56fafb7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:4cvOQtYVSPe1Tv4T4R34IC in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4cvOQtYVSPe1Tv4T4R34IC Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:4OiSgru8ScEiHkrL9nThQl in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4OiSgru8ScEiHkrL9nThQl Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:0OZMRO5yuxhVwQ3MPxll8h in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:0OZMRO5yuxhVwQ3MPxll8h Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3vwObRd71oUa145riRt42O","service":"spop","name":"uomo che cade","artist":"Tredici Pietro","album":"uomo che cade","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b27346f43545dc3d194f04568e14","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1MXiEYzY1u2DEfh2cRITv9","service":"spop","name":"SEI TU","artist":"Levante","album":"SEI TU","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b2736e2809b2a1d67628b484a423","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:6yrwLR7cV6DifWyO1diYFe in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:6yrwLR7cV6DifWyO1diYFe Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1GjQApTKIADKfSoZm8bHRK","service":"spop","name":"Resta Con Me","artist":"Bambole Di Pezza","album":"Resta Con Me","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b2733d7306ba69a835c6b96faa4d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2DO1VNiVcIVKJVd2PhKCsW","service":"spop","name":"Magica Favola","artist":"Arisa","album":"Magica Favola","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b273eeb347b1f1cd2a5948e2ffb7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5NON0GOPoWxkTK6Hi4JDZi","service":"spop","name":"AI AI","artist":"Dargen D'Amico","album":"AI AI","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b27323812b884a2952b2b62a4772","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ByNcS81LqRYUvJjpWhZ7T","service":"spop","name":"Avvoltoi","artist":"Eddie Brock","album":"Avvoltoi","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b273460fdd43726fbabf378716e8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1bXER40RxwNWTf9fnQOY64","service":"spop","name":"Prima che","artist":"nayt","album":"Prima che","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b2733f8c47026c5baca0287b6e9e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:1bs5J4ljYslfbSCtvKxh78 in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1bs5J4ljYslfbSCtvKxh78 Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zngwmDraHGVczoCzj7rHb","service":"spop","name":"QUI CON ME","artist":"Serena Brancale","album":"QUI CON ME","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b273618cabb91e31f8871c632a2f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2qkSLhQ0w3R02XzC5zwkr7","service":"spop","name":"animali notturni","artist":"Malika Ayane","album":"animali notturni","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273f9f09cf9df9c58c107cc357c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:15RaJcxk9ng0GahyzI6q3g","service":"spop","name":"Ti penso sempre","artist":"chiello","album":"Ti penso sempre","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b273a4c263ef5bef83d531e2f79d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2wNoBWBMt1bpoAzPX2lAi0","service":"spop","name":"Stella stellina","artist":"Ermal Meta","album":"Stella stellina","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b27323ecfdb5b005eecb673e5e24","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:24y5rDZTffJ8k4woQ14T9i in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:24y5rDZTffJ8k4woQ14T9i Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2NmvOrVquVRNNKUPOGx3xe","service":"spop","name":"La felicità e basta","artist":"Maria Antonietta","album":"La felicità e basta","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273a5b9f9328ec27ca0d364c7ff","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2XVaLBILDwmtR876otQkpg","service":"spop","name":"le cose che non sai di me","artist":"Mara Sattei","album":"le cose che non sai di me","type":"song","duration":204,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d2284bc409fecaa9427f9f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:11C1h0tuCW9qRa1QGPV9Vj","service":"spop","name":"Labirinto","artist":"Luchè","album":"Labirinto","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b273797d3bac974be0c1292bed48","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:1IG5eF5NNVtGaMTLOxByln in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1IG5eF5NNVtGaMTLOxByln Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4pvQiqOrowCEHeCpIDKQyP","service":"spop","name":"NATURALE","artist":"Leo Gassmann","album":"NATURALE","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273153f6d883dce41fe1d64dbdb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4j88Y86NDJvbioBEZTxQeO","service":"spop","name":"Prima o poi","artist":"Michele Bravi","album":"Prima o poi","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b27394e5b9faa6c9cff51faf7197","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:229IXngdEIWPyJZuk58stZ","service":"spop","name":"Ora e per sempre","artist":"Raf","album":"Ora e per sempre","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b273b8367347fc34f12921838a30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5COJWRcIcK8Wn2u5G18BA5","service":"spop","name":"Ogni volta che non so volare","artist":"Enrico Nigiotti","album":"Ogni volta che non so volare","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b27389f9c4b95d20a08657eb47bc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4cvOQtYVSPe1Tv4T4R34IC","service":"spop","name":"Il meglio di me","artist":"Francesco Renga","album":"Il meglio di me","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273445cf4190e4aeef5d6a38730","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:5NAnT7wXTNGKtrdX8Qo41B in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:5NAnT7wXTNGKtrdX8Qo41B Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0OZMRO5yuxhVwQ3MPxll8h","service":"spop","name":"Andamento Lento (feat. Tullio De Piscopo)","artist":"LDA","album":"Andamento Lento (feat. Tullio De Piscopo)","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b27387fa0f63faf300987e807f65","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6yrwLR7cV6DifWyO1diYFe","service":"spop","name":"The Lady Is A Tramp","artist":"Ditonellapiaga","album":"The Lady Is A Tramp","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b27398c3292777fdfc9ab0e8ace9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:4WvurZy0QYcgX4pyPvxTee in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4WvurZy0QYcgX4pyPvxTee Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4OiSgru8ScEiHkrL9nThQl","service":"spop","name":"Opera","artist":"Patty Pravo","album":"Opera","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273063007229fee36c2b364df52","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:1xrQXLdFY7X0WaaBwRV4bS in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1xrQXLdFY7X0WaaBwRV4bS Mar 25 23:19:05 volumio volumio[977]: info: Exploding uri spotify:track:5NJeib0D2vsxEw1d1PeG3b in service spop Mar 25 23:19:05 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:5NJeib0D2vsxEw1d1PeG3b Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5NAnT7wXTNGKtrdX8Qo41B","service":"spop","name":"Cinque giorni","artist":"Sal Da Vinci","album":"Cinque giorni","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b273c8325509ca6688895c4a143d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:37TW8WIet2l0xkxQTuj4MT in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:37TW8WIet2l0xkxQTuj4MT Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:0xesf5VFqTMRXTadReD5Dg in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:0xesf5VFqTMRXTadReD5Dg Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1bs5J4ljYslfbSCtvKxh78","service":"spop","name":"I MASCHI (feat. Gaia)","artist":"Levante","album":"I MASCHI (feat. Gaia)","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273e280f3ba8b3020dacbbbbfe4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4WvurZy0QYcgX4pyPvxTee","service":"spop","name":"MERAVIGLIOSA CREATURA","artist":"Fedez","album":"MERAVIGLIOSA CREATURA","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2735dcc3a672bd38c1ea2dd9791","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1IG5eF5NNVtGaMTLOxByln","service":"spop","name":"Aserejé - Sanremo Remix","artist":"Elettra Lamborghini","album":"Aserejé (Sanremo Remix)","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b27328d1845959de0a257f4e0d38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:24y5rDZTffJ8k4woQ14T9i","service":"spop","name":"En e Xanax (feat. Alfa)","artist":"Enrico Nigiotti","album":"En e Xanax (feat. Alfa)","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273698d2f175f505511dc127c27","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:1jIm4u2ahLNHLPkiILhSa2 in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1jIm4u2ahLNHLPkiILhSa2 Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1xrQXLdFY7X0WaaBwRV4bS","service":"spop","name":"Quello che le donne non dicono","artist":"Arisa","album":"Quello che le donne non dicono","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b27323dac45b6781f53ee8e78ff6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5NJeib0D2vsxEw1d1PeG3b","service":"spop","name":"ERA GIÀ TUTTO PREVISTO","artist":"Leo Gassmann","album":"ERA GIÀ TUTTO PREVISTO","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b2730b5dcd6faa6ebf1966c6302a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:4o27n0ryLEmQvC16wuf1gZ in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4o27n0ryLEmQvC16wuf1gZ Mar 25 23:19:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 23:19:06 volumio volumio[977]: info: Received Get System Info Mar 25 23:19:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:19:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:19:06 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:19:06 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:19:06 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:0CXKFZkDwVwt67bZTBkXMe in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:0CXKFZkDwVwt67bZTBkXMe Mar 25 23:19:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:37TW8WIet2l0xkxQTuj4MT","service":"spop","name":"La canzone dell'amore perduto (feat. Joan Thiele)","artist":"nayt","album":"La canzone dell'amore perduto (feat. Joan Thiele)","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b2733847d07986530438060a8e56","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:4SB3yQM0g9XlYyyFJhGkKr in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4SB3yQM0g9XlYyyFJhGkKr Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0xesf5VFqTMRXTadReD5Dg","service":"spop","name":"Occhi di gatto (feat. Cristina D'Avena)","artist":"Bambole Di Pezza","album":"Occhi di gatto (feat. Cristina D'Avena)","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273d6e49b287efce098a6aedfe0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:3tGAwQkqKMkvIL4ZG415XT in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:3tGAwQkqKMkvIL4ZG415XT Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1jIm4u2ahLNHLPkiILhSa2","service":"spop","name":"l'ultimo bacio","artist":"Mara Sattei","album":"l'ultimo bacio","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273ce47c22c0b58fb8acf05c3e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:3Tz9knLyQw76KmDHypbJAr in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:3Tz9knLyQw76KmDHypbJAr Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:5EfDRuPRCMcuwfc5i9kwjn in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:5EfDRuPRCMcuwfc5i9kwjn Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4o27n0ryLEmQvC16wuf1gZ","service":"spop","name":"Portami via","artist":"Eddie Brock","album":"Portami via","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b27335618e10366030bc1208116e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0CXKFZkDwVwt67bZTBkXMe","service":"spop","name":"The Riddle (feat. The Kolors)","artist":"Raf","album":"The Riddle (feat. The Kolors)","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b2738709938cf6fdb0be04a511d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:4ObYU97J8H5EiozuDEoyHo in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4ObYU97J8H5EiozuDEoyHo Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4SB3yQM0g9XlYyyFJhGkKr","service":"spop","name":"L'ultima luna","artist":"Tommaso Paradiso","album":"L'ultima luna","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b273507f7ba59f1670940eea3ecb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3tGAwQkqKMkvIL4ZG415XT","service":"spop","name":"BÉSAME MUCHO (feat. Gregory Porter, DELIA)","artist":"Serena Brancale","album":"BÉSAME MUCHO (feat. Gregory Porter, DELIA)","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b2736cd4a4ea77d5c529a6295984","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:6KlHqB5IKmK48uxTN0YWoF in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:6KlHqB5IKmK48uxTN0YWoF Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:0VwjJbNuxG338w8a4O4ZtG in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:0VwjJbNuxG338w8a4O4ZtG Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Tz9knLyQw76KmDHypbJAr","service":"spop","name":"Falco a metà (feat. Gianluca Grignani)","artist":"Luchè","album":"Il mio lato peggiore","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b273c62c7920980cba87cda1867f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5EfDRuPRCMcuwfc5i9kwjn","service":"spop","name":"Il Mondo (con Brunori Sas)","artist":"Maria Antonietta","album":"Il Mondo (con Brunori Sas)","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b27307b5792542af12af24d3c2b7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:52Em11vMfAUJYYkLF2iEfn in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:52Em11vMfAUJYYkLF2iEfn Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ObYU97J8H5EiozuDEoyHo","service":"spop","name":"Golden Hour (feat. Dardust)","artist":"Ermal Meta","album":"Golden Hour (feat. Dardust)","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b27341c85b877f9d1d449ac956c8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:1pjVshubbxfq3KBSbIdANz in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:1pjVshubbxfq3KBSbIdANz Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6KlHqB5IKmK48uxTN0YWoF","service":"spop","name":"Domani è un altro giorno (feat. Fiorella Mannoia)","artist":"Michele Bravi","album":"Domani è un altro giorno (feat. Fiorella Mannoia)","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b2732fa9d27f473268b82fa03d2e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:5YXZTLBBAnDZcOSosqNRbs in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:5YXZTLBBAnDZcOSosqNRbs Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VwjJbNuxG338w8a4O4ZtG","service":"spop","name":"Il Giorno dell'Armistizio","artist":"Dargen D'Amico","album":"Il Giorno dell'Armistizio","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b273196a9187c7c3e823e561cc00","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:46yBh1rkQjidNATQ7YeqTa in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:46yBh1rkQjidNATQ7YeqTa Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:0L7oxMR89ji7A6htHnPR8L in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:0L7oxMR89ji7A6htHnPR8L Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1pjVshubbxfq3KBSbIdANz","service":"spop","name":"Ragazzo Solo, Ragazza Sola","artist":"Francesco Renga","album":"Ragazzo Solo, Ragazza Sola","type":"song","duration":204,"albumart":"https://i.scdn.co/image/ab67616d0000b2736acd68d573634f23c8c65ac5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:52Em11vMfAUJYYkLF2iEfn","service":"spop","name":"E, la vita la vita (con la Ligera County Fam)","artist":"J-AX","album":"E, la vita la vita (con la Ligera County Fam)","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273c8d301614d5b0cec41dab431","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:2CvhzdXKzJ90JQj6iLg5qI in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:2CvhzdXKzJ90JQj6iLg5qI Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5YXZTLBBAnDZcOSosqNRbs","service":"spop","name":"Ti lascio una canzone","artist":"Patty Pravo","album":"Ti lascio una canzone","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b27311db726a5708a5388eb46c03","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:06 volumio volumio[977]: info: Exploding uri spotify:track:4CVzKDQSP7dZOzca7vQvsH in service spop Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:track:4CVzKDQSP7dZOzca7vQvsH Mar 25 23:19:06 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:46yBh1rkQjidNATQ7YeqTa","service":"spop","name":"Mattone","artist":"Angelica Bove","album":"Mattone","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b27319ab8656cd4d9330a135c402","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:07 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0L7oxMR89ji7A6htHnPR8L","service":"spop","name":"Laguna","artist":"Nicolò Filippucci","album":"Laguna","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b27395ef706aab76be2638f9e9c3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 23:19:07 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:07 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2CvhzdXKzJ90JQj6iLg5qI","service":"spop","name":"Nei miei DM","artist":"Blind","album":"Nei miei DM","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273632c3411bd415187a8a0e82a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:07 volumio volumio[977]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4CVzKDQSP7dZOzca7vQvsH","service":"spop","name":"MANIFESTAZIONE D'AMORE","artist":"Mazzariello","album":"MANIFESTAZIONE D'AMORE","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b2736853bc442887f5d2b9b222ba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 25 23:19:07 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::ClearQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::clearPlayQueue Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::addQueueItems Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::addQueueItems Mar 25 23:19:07 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5W1mx7Oeg2HxmASrth1nTt Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5W1mx7Oeg2HxmASrth1nTt Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:63sQFPGkKfzcK5qEZVefpu Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:63sQFPGkKfzcK5qEZVefpu Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1ByC6cNyFHMIrqTDVf8Urf Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1ByC6cNyFHMIrqTDVf8Urf Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1k3pzheXrbtHgvvOsQeMkU Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1k3pzheXrbtHgvvOsQeMkU Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:730yruXTNk2KvXdbCNzXpR Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:730yruXTNk2KvXdbCNzXpR Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2yzOkthIypp8Lwkww7LZjY Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2yzOkthIypp8Lwkww7LZjY Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4kK4uxstY0lTdun71200Al Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4kK4uxstY0lTdun71200Al Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::play index 6 Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::addQueueItems Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::addQueueItems Mar 25 23:19:07 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4ZAjcfbt84O79SGc0jWqu7 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4ZAjcfbt84O79SGc0jWqu7 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1GjQApTKIADKfSoZm8bHRK Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1GjQApTKIADKfSoZm8bHRK Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:6IhEd41nfdL7qG4MVfZVAp Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:6IhEd41nfdL7qG4MVfZVAp Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:3X6VETSe1orqobaME0KTzQ Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:3X6VETSe1orqobaME0KTzQ Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:3vwObRd71oUa145riRt42O Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:3vwObRd71oUa145riRt42O Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1bXER40RxwNWTf9fnQOY64 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1bXER40RxwNWTf9fnQOY64 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2DO1VNiVcIVKJVd2PhKCsW Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2DO1VNiVcIVKJVd2PhKCsW Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:11C1h0tuCW9qRa1QGPV9Vj Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:11C1h0tuCW9qRa1QGPV9Vj Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:6zngwmDraHGVczoCzj7rHb Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:6zngwmDraHGVczoCzj7rHb Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5NON0GOPoWxkTK6Hi4JDZi Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5NON0GOPoWxkTK6Hi4JDZi Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2wNoBWBMt1bpoAzPX2lAi0 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2wNoBWBMt1bpoAzPX2lAi0 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4j88Y86NDJvbioBEZTxQeO Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4j88Y86NDJvbioBEZTxQeO Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2XVaLBILDwmtR876otQkpg Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2XVaLBILDwmtR876otQkpg Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2qkSLhQ0w3R02XzC5zwkr7 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2qkSLhQ0w3R02XzC5zwkr7 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1MXiEYzY1u2DEfh2cRITv9 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1MXiEYzY1u2DEfh2cRITv9 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:3ByNcS81LqRYUvJjpWhZ7T Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:3ByNcS81LqRYUvJjpWhZ7T Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2NmvOrVquVRNNKUPOGx3xe Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2NmvOrVquVRNNKUPOGx3xe Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:15RaJcxk9ng0GahyzI6q3g Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:15RaJcxk9ng0GahyzI6q3g Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5COJWRcIcK8Wn2u5G18BA5 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5COJWRcIcK8Wn2u5G18BA5 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:229IXngdEIWPyJZuk58stZ Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:229IXngdEIWPyJZuk58stZ Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4pvQiqOrowCEHeCpIDKQyP Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4pvQiqOrowCEHeCpIDKQyP Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4cvOQtYVSPe1Tv4T4R34IC Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4cvOQtYVSPe1Tv4T4R34IC Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4OiSgru8ScEiHkrL9nThQl Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4OiSgru8ScEiHkrL9nThQl Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:0OZMRO5yuxhVwQ3MPxll8h Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:0OZMRO5yuxhVwQ3MPxll8h Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:6yrwLR7cV6DifWyO1diYFe Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:6yrwLR7cV6DifWyO1diYFe Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1bs5J4ljYslfbSCtvKxh78 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1bs5J4ljYslfbSCtvKxh78 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:24y5rDZTffJ8k4woQ14T9i Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:24y5rDZTffJ8k4woQ14T9i Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1IG5eF5NNVtGaMTLOxByln Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1IG5eF5NNVtGaMTLOxByln Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5NAnT7wXTNGKtrdX8Qo41B Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5NAnT7wXTNGKtrdX8Qo41B Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4WvurZy0QYcgX4pyPvxTee Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4WvurZy0QYcgX4pyPvxTee Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1xrQXLdFY7X0WaaBwRV4bS Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1xrQXLdFY7X0WaaBwRV4bS Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5NJeib0D2vsxEw1d1PeG3b Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5NJeib0D2vsxEw1d1PeG3b Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:37TW8WIet2l0xkxQTuj4MT Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:37TW8WIet2l0xkxQTuj4MT Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:0xesf5VFqTMRXTadReD5Dg Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:0xesf5VFqTMRXTadReD5Dg Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1jIm4u2ahLNHLPkiILhSa2 Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1jIm4u2ahLNHLPkiILhSa2 Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4o27n0ryLEmQvC16wuf1gZ Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4o27n0ryLEmQvC16wuf1gZ Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:0CXKFZkDwVwt67bZTBkXMe Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:0CXKFZkDwVwt67bZTBkXMe Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4SB3yQM0g9XlYyyFJhGkKr Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4SB3yQM0g9XlYyyFJhGkKr Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:3tGAwQkqKMkvIL4ZG415XT Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:3tGAwQkqKMkvIL4ZG415XT Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:3Tz9knLyQw76KmDHypbJAr Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:3Tz9knLyQw76KmDHypbJAr Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5EfDRuPRCMcuwfc5i9kwjn Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5EfDRuPRCMcuwfc5i9kwjn Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4ObYU97J8H5EiozuDEoyHo Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4ObYU97J8H5EiozuDEoyHo Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:6KlHqB5IKmK48uxTN0YWoF Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:6KlHqB5IKmK48uxTN0YWoF Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:0VwjJbNuxG338w8a4O4ZtG Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:0VwjJbNuxG338w8a4O4ZtG Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:52Em11vMfAUJYYkLF2iEfn Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:52Em11vMfAUJYYkLF2iEfn Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1pjVshubbxfq3KBSbIdANz Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1pjVshubbxfq3KBSbIdANz Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5YXZTLBBAnDZcOSosqNRbs Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5YXZTLBBAnDZcOSosqNRbs Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:46yBh1rkQjidNATQ7YeqTa Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:46yBh1rkQjidNATQ7YeqTa Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:0L7oxMR89ji7A6htHnPR8L Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:0L7oxMR89ji7A6htHnPR8L Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2CvhzdXKzJ90JQj6iLg5qI Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2CvhzdXKzJ90JQj6iLg5qI Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4CVzKDQSP7dZOzca7vQvsH Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4CVzKDQSP7dZOzca7vQvsH Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::play index undefined Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:07 volumio volumio[977]: info: [1774477147938] ControllerSpotify::clearAddPlayTrack Mar 25 23:19:07 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/play Mar 25 23:19:07 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::ClearQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::clearPlayQueue Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:07 volumio volumio[977]: info: CoreStateMachine::addQueueItems Mar 25 23:19:07 volumio volumio[977]: info: CorePlayQueue::addQueueItems Mar 25 23:19:07 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:5W1mx7Oeg2HxmASrth1nTt Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:5W1mx7Oeg2HxmASrth1nTt Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:63sQFPGkKfzcK5qEZVefpu Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:63sQFPGkKfzcK5qEZVefpu Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1ByC6cNyFHMIrqTDVf8Urf Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1ByC6cNyFHMIrqTDVf8Urf Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:1k3pzheXrbtHgvvOsQeMkU Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:1k3pzheXrbtHgvvOsQeMkU Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:730yruXTNk2KvXdbCNzXpR Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:730yruXTNk2KvXdbCNzXpR Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:2yzOkthIypp8Lwkww7LZjY Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:2yzOkthIypp8Lwkww7LZjY Mar 25 23:19:07 volumio volumio[977]: info: Adding Item to queue: spotify:track:4kK4uxstY0lTdun71200Al Mar 25 23:19:07 volumio volumio[977]: info: Using cached record of: spotify:track:4kK4uxstY0lTdun71200Al Mar 25 23:19:08 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:08 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:19:08 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:19:08 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::play index 6 Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::addQueueItems Mar 25 23:19:08 volumio volumio[977]: info: CorePlayQueue::addQueueItems Mar 25 23:19:08 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4ZAjcfbt84O79SGc0jWqu7 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4ZAjcfbt84O79SGc0jWqu7 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1GjQApTKIADKfSoZm8bHRK Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1GjQApTKIADKfSoZm8bHRK Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:6IhEd41nfdL7qG4MVfZVAp Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:6IhEd41nfdL7qG4MVfZVAp Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:3X6VETSe1orqobaME0KTzQ Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:3X6VETSe1orqobaME0KTzQ Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:3vwObRd71oUa145riRt42O Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:3vwObRd71oUa145riRt42O Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1bXER40RxwNWTf9fnQOY64 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1bXER40RxwNWTf9fnQOY64 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:2DO1VNiVcIVKJVd2PhKCsW Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:2DO1VNiVcIVKJVd2PhKCsW Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:11C1h0tuCW9qRa1QGPV9Vj Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:11C1h0tuCW9qRa1QGPV9Vj Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:6zngwmDraHGVczoCzj7rHb Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:6zngwmDraHGVczoCzj7rHb Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:5NON0GOPoWxkTK6Hi4JDZi Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:5NON0GOPoWxkTK6Hi4JDZi Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:2wNoBWBMt1bpoAzPX2lAi0 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:2wNoBWBMt1bpoAzPX2lAi0 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4j88Y86NDJvbioBEZTxQeO Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4j88Y86NDJvbioBEZTxQeO Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:2XVaLBILDwmtR876otQkpg Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:2XVaLBILDwmtR876otQkpg Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:2qkSLhQ0w3R02XzC5zwkr7 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:2qkSLhQ0w3R02XzC5zwkr7 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1MXiEYzY1u2DEfh2cRITv9 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1MXiEYzY1u2DEfh2cRITv9 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:3ByNcS81LqRYUvJjpWhZ7T Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:3ByNcS81LqRYUvJjpWhZ7T Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:2NmvOrVquVRNNKUPOGx3xe Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:2NmvOrVquVRNNKUPOGx3xe Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:15RaJcxk9ng0GahyzI6q3g Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:15RaJcxk9ng0GahyzI6q3g Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:5COJWRcIcK8Wn2u5G18BA5 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:5COJWRcIcK8Wn2u5G18BA5 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:229IXngdEIWPyJZuk58stZ Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:229IXngdEIWPyJZuk58stZ Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4pvQiqOrowCEHeCpIDKQyP Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4pvQiqOrowCEHeCpIDKQyP Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4cvOQtYVSPe1Tv4T4R34IC Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4cvOQtYVSPe1Tv4T4R34IC Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4OiSgru8ScEiHkrL9nThQl Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4OiSgru8ScEiHkrL9nThQl Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:0OZMRO5yuxhVwQ3MPxll8h Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:0OZMRO5yuxhVwQ3MPxll8h Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:6yrwLR7cV6DifWyO1diYFe Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:6yrwLR7cV6DifWyO1diYFe Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1bs5J4ljYslfbSCtvKxh78 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1bs5J4ljYslfbSCtvKxh78 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:24y5rDZTffJ8k4woQ14T9i Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:24y5rDZTffJ8k4woQ14T9i Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1IG5eF5NNVtGaMTLOxByln Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1IG5eF5NNVtGaMTLOxByln Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:5NAnT7wXTNGKtrdX8Qo41B Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:5NAnT7wXTNGKtrdX8Qo41B Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4WvurZy0QYcgX4pyPvxTee Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4WvurZy0QYcgX4pyPvxTee Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1xrQXLdFY7X0WaaBwRV4bS Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1xrQXLdFY7X0WaaBwRV4bS Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:5NJeib0D2vsxEw1d1PeG3b Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:5NJeib0D2vsxEw1d1PeG3b Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:37TW8WIet2l0xkxQTuj4MT Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:37TW8WIet2l0xkxQTuj4MT Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:0xesf5VFqTMRXTadReD5Dg Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:0xesf5VFqTMRXTadReD5Dg Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1jIm4u2ahLNHLPkiILhSa2 Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1jIm4u2ahLNHLPkiILhSa2 Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4o27n0ryLEmQvC16wuf1gZ Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4o27n0ryLEmQvC16wuf1gZ Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:0CXKFZkDwVwt67bZTBkXMe Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:0CXKFZkDwVwt67bZTBkXMe Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4SB3yQM0g9XlYyyFJhGkKr Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4SB3yQM0g9XlYyyFJhGkKr Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:3tGAwQkqKMkvIL4ZG415XT Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:3tGAwQkqKMkvIL4ZG415XT Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:3Tz9knLyQw76KmDHypbJAr Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:3Tz9knLyQw76KmDHypbJAr Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:5EfDRuPRCMcuwfc5i9kwjn Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:5EfDRuPRCMcuwfc5i9kwjn Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4ObYU97J8H5EiozuDEoyHo Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4ObYU97J8H5EiozuDEoyHo Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:6KlHqB5IKmK48uxTN0YWoF Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:6KlHqB5IKmK48uxTN0YWoF Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:0VwjJbNuxG338w8a4O4ZtG Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:0VwjJbNuxG338w8a4O4ZtG Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:52Em11vMfAUJYYkLF2iEfn Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:52Em11vMfAUJYYkLF2iEfn Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:1pjVshubbxfq3KBSbIdANz Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:1pjVshubbxfq3KBSbIdANz Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:5YXZTLBBAnDZcOSosqNRbs Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:5YXZTLBBAnDZcOSosqNRbs Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:46yBh1rkQjidNATQ7YeqTa Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:46yBh1rkQjidNATQ7YeqTa Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:0L7oxMR89ji7A6htHnPR8L Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:0L7oxMR89ji7A6htHnPR8L Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:2CvhzdXKzJ90JQj6iLg5qI Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:2CvhzdXKzJ90JQj6iLg5qI Mar 25 23:19:08 volumio volumio[977]: info: Adding Item to queue: spotify:track:4CVzKDQSP7dZOzca7vQvsH Mar 25 23:19:08 volumio volumio[977]: info: Using cached record of: spotify:track:4CVzKDQSP7dZOzca7vQvsH Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:08 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:08 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::play index undefined Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:19:08 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:19:08 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:08 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:19:08 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:08 volumio volumio[977]: info: [1774477148072] ControllerSpotify::clearAddPlayTrack Mar 25 23:19:08 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/play Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="loading track spotify:track:4kK4uxstY0lTdun71200Al (paused: false, position: 1ms)" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=trace msg="emitting websocket event: will_play" Mar 25 23:19:08 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4kK4uxstY0lTdun71200Al","play_origin":"go-librespot"}} Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:4kK4uxstY0lTdun71200Al" Mar 25 23:19:08 volumio volumio[977]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3532" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="loading track spotify:track:4kK4uxstY0lTdun71200Al (paused: false, position: 0ms)" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=trace msg="emitting websocket event: will_play" Mar 25 23:19:08 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4kK4uxstY0lTdun71200Al","play_origin":"go-librespot"}} Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:4kK4uxstY0lTdun71200Al" Mar 25 23:19:08 volumio volumio[977]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 25 23:19:08 volumio go-librespot[1453]: time="2026-03-25T23:19:08+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2219" Mar 25 23:19:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 23:19:09 volumio volumio[977]: info: Received Get System Info Mar 25 23:19:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:19:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:19:09 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:19:09 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:19:09 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:19:11 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:11 volumio volumio[977]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Mar 25 23:19:11 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 23:19:11 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources Mar 25 23:19:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:19:13 volumio volumio[977]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 25 23:19:13 volumio volumio[977]: info: Completed starting MyVolumio Plugin Mar 25 23:19:13 volumio volumio[977]: [Metrics] CommandRouter: 50s 269.43ms Mar 25 23:19:13 volumio volumio[977]: info: CoreCommandRouter::volumiosetStartupVolume Mar 25 23:19:13 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 23:19:13 volumio volumio[977]: info: VolumeController:: Setting startup Volume 10 Mar 25 23:19:13 volumio volumio[977]: info: VolumeController::SetAlsaVolume10 Mar 25 23:19:13 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 23:19:13 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Mar 25 23:19:13 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Mar 25 23:19:13 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:13 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:13 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:19:13 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:13 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status stop Mar 25 23:19:13 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:13 volumio volumio[977]: SPOTIFY: SPOTIFY VOLUME 5 Mar 25 23:19:13 volumio volumio[977]: SPOTIFY: VOLUMIO VOLUME 10 Mar 25 23:19:13 volumio volumio[977]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 25 23:19:13 volumio volumio[977]: info: Setting Spotify Volume from Volumio: 10 Mar 25 23:19:14 volumio sudo[1926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 25 23:19:14 volumio sudo[1926]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:14 volumio sudo[1926]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:14 volumio volumio[977]: info: peppyspectrum Daemon Stop Mar 25 23:19:14 volumio ntpd[773]: Soliciting pool server 204.216.214.76 Mar 25 23:19:14 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 25 23:19:14 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 23:19:14 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 25 23:19:15 volumio volumio[977]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Mar 25 23:19:15 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/volume Mar 25 23:19:15 volumio go-librespot[1453]: time="2026-03-25T23:19:15+01:00" level=debug msg="update volume to 6553/65535" Mar 25 23:19:15 volumio ntpd[773]: Soliciting pool server 162.159.200.123 Mar 25 23:19:15 volumio go-librespot[1453]: time="2026-03-25T23:19:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 25 23:19:15 volumio go-librespot[1453]: time="2026-03-25T23:19:15+01:00" level=trace msg="emitting websocket event: volume" Mar 25 23:19:15 volumio volumio[977]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} Mar 25 23:19:15 volumio volumio[977]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 Mar 25 23:19:16 volumio ntpd[773]: Soliciting pool server 185.157.229.254 Mar 25 23:19:16 volumio ntpd[773]: Soliciting pool server 217.61.62.224 Mar 25 23:19:16 volumio sudo[1929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=sv0icUvEWucMtsTF4EsW,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.42/volumio /mnt/NAS/NAS Mar 25 23:19:16 volumio sudo[1929]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:16 volumio kernel: CIFS: Attempting to mount //192.168.178.42/volumio Mar 25 23:19:17 volumio ntpd[773]: Soliciting pool server 212.237.55.238 Mar 25 23:19:17 volumio ntpd[773]: Soliciting pool server 162.159.200.1 Mar 25 23:19:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 23:19:18 volumio volumio[977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 25 23:19:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 25 23:19:18 volumio volumio[977]: info: Received Get System Version Mar 25 23:19:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 23:19:18 volumio volumio[977]: info: Received Get System Info Mar 25 23:19:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 23:19:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 23:19:18 volumio volumio[977]: info: Discovery: Getting this device information Mar 25 23:19:18 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 25 23:19:18 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 23:19:18 volumio ntpd[773]: Soliciting pool server 31.207.113.74 Mar 25 23:19:18 volumio ntpd[773]: Soliciting pool server 185.19.184.35 Mar 25 23:19:19 volumio ntpd[773]: Soliciting pool server 129.152.16.145 Mar 25 23:19:19 volumio ntpd[773]: Soliciting pool server 80.211.238.32 Mar 25 23:19:19 volumio ntpd[773]: Soliciting pool server 195.32.70.195 Mar 25 23:19:20 volumio ntpd[773]: Soliciting pool server 212.45.144.3 Mar 25 23:19:20 volumio ntpd[773]: Soliciting pool server 93.94.88.51 Mar 25 23:19:20 volumio volumio[977]: info: BOOT COMPLETED Mar 25 23:19:21 volumio ntpd[773]: Soliciting pool server 172.232.209.103 Mar 25 23:19:21 volumio ntpd[773]: Soliciting pool server 95.110.254.234 Mar 25 23:19:22 volumio ntpd[773]: Soliciting pool server 37.247.53.178 Mar 25 23:19:22 volumio ntpd[773]: Soliciting pool server 2a00:6d41:200:2::14 Mar 25 23:19:22 volumio sudo[1929]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:22 volumio volumio[977]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Mar 25 23:19:22 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 23:19:22 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Mar 25 23:19:24 volumio ntpd[773]: receive: Unexpected origin timestamp 0xed6eddeb.8c915501 does not match aorg 0000000000.00000000 from server@185.19.184.35 xmt 0xed6eddec.30f5905c Mar 25 23:19:24 volumio ntpd[773]: receive: Unexpected origin timestamp 0xed6eddeb.8c95bc10 does not match aorg 0000000000.00000000 from server@185.157.229.254 xmt 0xed6eddec.32713b27 Mar 25 23:19:24 volumio ntpd[773]: receive: Unexpected origin timestamp 0xed6eddeb.8c949f8d does not match aorg 0000000000.00000000 from server@212.237.55.238 xmt 0xed6eddec.3211dbbb Mar 25 23:19:24 volumio ntpd[773]: receive: Unexpected origin timestamp 0xed6eddeb.8c934cbb does not match aorg 0000000000.00000000 from server@31.207.113.74 xmt 0xed6eddec.320feeb0 Mar 25 23:19:36 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Mar 25 23:19:36 volumio volumio[977]: info: CoreStateMachine::play index undefined Mar 25 23:19:36 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:36 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:36 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:19:36 volumio volumio[977]: info: CorePlayQueue::getTrack 6 Mar 25 23:19:36 volumio volumio[977]: info: [1774477176036] ControllerSpotify::clearAddPlayTrack Mar 25 23:19:36 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/play Mar 25 23:19:36 volumio go-librespot[1453]: time="2026-03-25T23:19:36+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Mar 25 23:19:36 volumio go-librespot[1453]: time="2026-03-25T23:19:36+01:00" level=debug msg="loading track spotify:track:4kK4uxstY0lTdun71200Al (paused: false, position: 0ms)" Mar 25 23:19:36 volumio go-librespot[1453]: time="2026-03-25T23:19:36+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 25 23:19:36 volumio go-librespot[1453]: time="2026-03-25T23:19:36+01:00" level=trace msg="emitting websocket event: will_play" Mar 25 23:19:36 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4kK4uxstY0lTdun71200Al","play_origin":"go-librespot"}} Mar 25 23:19:36 volumio go-librespot[1453]: time="2026-03-25T23:19:36+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:4kK4uxstY0lTdun71200Al" Mar 25 23:19:36 volumio volumio[977]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 25 23:19:39 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:39 volumio volumio[977]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 23:19:39 volumio volumio[977]: info: CoreStateMachine::ClearQueue Mar 25 23:19:39 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:39 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:39 volumio volumio[977]: info: CorePlayQueue::clearPlayQueue Mar 25 23:19:39 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:39 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:39 volumio volumio[977]: info: CoreStateMachine::addQueueItems Mar 25 23:19:39 volumio volumio[977]: info: CorePlayQueue::addQueueItems Mar 25 23:19:39 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:39 volumio volumio[977]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DWVQfeA9N7Q0g Mar 25 23:19:39 volumio volumio[977]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1DWVQfeA9N7Q0g in service spop Mar 25 23:19:39 volumio volumio[977]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1DWVQfeA9N7Q0g Mar 25 23:19:40 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:40 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:19:40 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:19:40 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::play index 0 Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::play index undefined Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:40 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:40 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:19:40 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:40 volumio volumio[977]: info: [1774477180253] ControllerSpotify::clearAddPlayTrack Mar 25 23:19:40 volumio volumio[977]: info: Sending Spotify command with payload to local API: /player/play Mar 25 23:19:40 volumio go-librespot[1453]: time="2026-03-25T23:19:40+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Mar 25 23:19:40 volumio go-librespot[1453]: time="2026-03-25T23:19:40+01:00" level=debug msg="loading track spotify:track:5W1mx7Oeg2HxmASrth1nTt (paused: false, position: 0ms)" Mar 25 23:19:40 volumio go-librespot[1453]: time="2026-03-25T23:19:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 25 23:19:40 volumio go-librespot[1453]: time="2026-03-25T23:19:40+01:00" level=trace msg="emitting websocket event: will_play" Mar 25 23:19:40 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5W1mx7Oeg2HxmASrth1nTt","play_origin":"go-librespot"}} Mar 25 23:19:40 volumio go-librespot[1453]: time="2026-03-25T23:19:40+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:5W1mx7Oeg2HxmASrth1nTt" Mar 25 23:19:40 volumio volumio[977]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 25 23:19:41 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 25 23:19:41 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:43 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 25 23:19:43 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:46 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 25 23:19:46 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:48 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::ClearQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::clearPlayQueue Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::addQueueItems Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::addQueueItems Mar 25 23:19:48 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:48 volumio volumio[977]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::play index 0 Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::play index undefined Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:48 volumio volumio[977]: info: [1774477188063] ControllerWebradio::clearAddPlayTrack Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand stop took 8 milliseconds Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand clear took 5 milliseconds Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: error: updateQueue error: null Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 17ms Mar 25 23:19:48 volumio volumio[977]: error: Upnp client error: Error: This socket has been ended by the other party Mar 25 23:19:48 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::ClearQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::clearPlayQueue Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::addQueueItems Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::addQueueItems Mar 25 23:19:48 volumio volumio[977]: info: Preload queue cleared Mar 25 23:19:48 volumio volumio[977]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::saveQueue Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::play index 0 Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::play index undefined Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:48 volumio volumio[977]: info: [1774477188182] ControllerWebradio::clearAddPlayTrack Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 662 milliseconds Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand play Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 666ms Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 664ms Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 662ms Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand stop took 568 milliseconds Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand play took 15 milliseconds Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 14ms Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand clear took 59 milliseconds Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:48 volumio volumio[977]: error: updateQueue error: null Mar 25 23:19:48 volumio volumio[977]: error: updateQueue error: null Mar 25 23:19:48 volumio volumio[977]: error: updateQueue error: null Mar 25 23:19:48 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 65ms Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 63ms Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 61ms Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:48 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:48 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:48 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 121 milliseconds Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand play Mar 25 23:19:48 volumio volumio[977]: info: Mar 25 23:19:48 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:48 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 125ms Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand status took 122 milliseconds Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 121ms Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand status took 119 milliseconds Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 118ms Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand status took 117 milliseconds Mar 25 23:19:48 volumio volumio[977]: info: ------------------------------ 116ms Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand status took 115 milliseconds Mar 25 23:19:48 volumio volumio[977]: info: sendMpdCommand play took 9 milliseconds Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:48 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:48 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:48 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 23:19:48 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: No code Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:48 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 23:19:48 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: No code Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:48 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 23:19:48 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:48 volumio volumio[977]: info: No code Mar 25 23:19:48 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:48 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:49 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:49 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:49 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:49 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:49 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:49 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 23:19:49 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:49 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Mar 25 23:19:49 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Mar 25 23:19:49 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:49 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:49 volumio volumio[977]: info: No code Mar 25 23:19:49 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:49 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.039+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.042+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.044+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.046+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.048+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.053+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.055+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.057+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.059+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.061+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.063+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio5-onboarding[1152]: time=2026-03-25T23:19:49.065+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 356ms Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 353ms Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 352ms Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 352ms Mar 25 23:19:49 volumio volumio[977]: info: Mar 25 23:19:49 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:49 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:49 volumio volumio[977]: info: Mar 25 23:19:49 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:49 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:49 volumio volumio[977]: info: Mar 25 23:19:49 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:49 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 259ms Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 12ms Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 10ms Mar 25 23:19:49 volumio volumio[977]: info: ------------------------------ 8ms Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status undefined Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:49 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 96 milliseconds Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:50 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 98 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 96 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 93 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:50 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:50 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 116ms Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:50 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:50 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces system playlist update Mar 25 23:19:50 volumio volumio[977]: info: Ignoring MPD Status Update Mar 25 23:19:50 volumio volumio[977]: info: Mar 25 23:19:50 volumio volumio[977]: ---------------------------- MPD announces state update: player Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::getState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 34ms Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 32 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 22ms Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 20 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 19ms Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 12 milliseconds Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:50 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:50 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:19:50 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:50 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:50 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:19:50 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:50 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:50 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:19:50 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 301ms Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 301ms Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 300ms Mar 25 23:19:50 volumio volumio[977]: info: ------------------------------ 184ms Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand status took 184 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand playlistinfo took 176 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand playlistinfo took 174 milliseconds Mar 25 23:19:50 volumio volumio[977]: info: sendMpdCommand playlistinfo took 173 milliseconds Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseState Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:50 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:50 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:50 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:19:50 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:50 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:50 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:19:50 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:50 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:50 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:19:50 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:50 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:51 volumio volumio[977]: info: ------------------------------ 448ms Mar 25 23:19:51 volumio volumio[977]: info: ------------------------------ 435ms Mar 25 23:19:51 volumio volumio[977]: info: ------------------------------ 434ms Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2005]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio systemd[1]: Started peppyspectrum Daemon. Mar 25 23:19:51 volumio sudo[2015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2015]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio sudo[2005]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2021]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2025]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio sudo[2015]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2012]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio sudo[2008]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio sudo[2021]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio sudo[2025]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio sudo[2037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio sudo[2032]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio volumio[977]: info: sendMpdCommand playlistinfo took 806 milliseconds Mar 25 23:19:51 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:19:51 volumio volumio[977]: info: ControllerMpd::pushState Mar 25 23:19:51 volumio volumio[977]: info: CoreCommandRouter::servicePushState Mar 25 23:19:51 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 25 23:19:51 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 25 23:19:51 volumio volumio[977]: verbose: CURRENT POSITION 0 Mar 25 23:19:51 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Mar 25 23:19:51 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:19:51 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Mar 25 23:19:51 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 25 23:19:51 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:51 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:19:51 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:51 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:51 volumio volumio[977]: info: CoreStateMachine::pushState Mar 25 23:19:51 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Mar 25 23:19:51 volumio volumio[977]: info: ------------------------------ 1084ms Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio volumio[977]: info: PeppySpectrum ---peppyspectrum status play Mar 25 23:19:51 volumio sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 25 23:19:51 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 25 23:19:51 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 23:19:51 volumio sudo[2044]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:52 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:52 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Mar 25 23:19:52 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:52 volumio volumio[977]: info: peppyspectrum Daemon Started Mar 25 23:19:52 volumio volumio[977]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 23:19:52 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 23:19:52 volumio volumio[977]: TypeError: Cannot read property 'slice' of null Mar 25 23:19:52 volumio volumio[977]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 25 23:19:52 volumio volumio[977]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 25 23:19:52 volumio volumio[977]: at Object.onceWrapper (events.js:422:26) Mar 25 23:19:52 volumio volumio[977]: at ClientRequest.emit (events.js:315:20) Mar 25 23:19:52 volumio volumio[977]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 25 23:19:52 volumio volumio[977]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 25 23:19:52 volumio volumio[977]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 25 23:19:52 volumio volumio[977]: at TLSSocket.emit (events.js:315:20) Mar 25 23:19:52 volumio volumio[977]: at addChunk (internal/streams/readable.js:309:12) Mar 25 23:19:52 volumio volumio[977]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 25 23:19:52 volumio volumio[977]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 25 23:19:52 volumio volumio[977]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 25 23:19:52 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 23:19:54 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-25 23:18 Mar 25 23:19:54 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"