Aug 27 22:07:47 volumio ntpd[1108]: CLOCK: time stepped by 82473.671648 Aug 27 22:07:47 volumio ntpd[1108]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 27 22:07:47 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 27 22:07:47 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 27 22:07:47 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 27 22:07:48 volumio python3[1296]: volumio: connect error: Connection refused by the server Aug 27 22:07:48 volumio python3[1296]: volumio: reconnect in 2s (attempt 1) Aug 27 22:07:48 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 27 22:07:48 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 27 22:07:49 volumio volumio[1294]: info: Loading plugin "network"... Aug 27 22:07:49 volumio volumio[1294]: info: Refreshing Cached IP Addresses Aug 27 22:07:49 volumio sudo[1558]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 22:07:49 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:07:49 volumio volumio[1294]: info: Loading plugin "services"... Aug 27 22:07:49 volumio volumio[1294]: info: Loading plugin "volumio5onboarding"... Aug 27 22:07:49 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Aug 27 22:07:49 volumio volumio[1294]: info: Loading plugin "alsa_controller"... Aug 27 22:07:49 volumio sudo[1555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 22:07:49 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:07:49 volumio sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 22:07:49 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:07:49 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Aug 27 22:07:50 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 22:07:50 volumio volumio[1294]: info: Loading plugin "wizard"... Aug 27 22:07:50 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 27 22:07:50 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:07:50 volumio volumio[1294]: info: Loading plugin "networkfs"... Aug 27 22:07:50 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:07:50 volumio volumio[1294]: info: Starting Udev Watcher for removable devices Aug 27 22:07:50 volumio upmpdcli[1591]: Could not open config: /tmp/upmpdcli.conf Aug 27 22:07:50 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:07:50 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 22:07:50 volumio volumio[1294]: info: Ignoring mount for partition: boot Aug 27 22:07:50 volumio volumio[1294]: info: Ignoring mount for partition: volumio Aug 27 22:07:50 volumio volumio[1294]: info: Ignoring mount for partition: volumio_data Aug 27 22:07:50 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 22:07:50 volumio volumio[1294]: info: Loading plugin "volumio_command_line_client"... Aug 27 22:07:50 volumio sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Stephen,password=9617quad9617,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.68/Music /mnt/NAS/Icky-4 Aug 27 22:07:50 volumio volumio[1294]: info: Loading plugin "upnp"... Aug 27 22:07:50 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:07:50 volumio volumio[1294]: info: [1787864870223] Starting Upmpd Daemon Aug 27 22:07:50 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 22:07:50 volumio volumio[1294]: info: Loading plugin "my_music"... Aug 27 22:07:50 volumio volumio[1294]: info: Loading plugin "mpd"... Aug 27 22:07:50 volumio kernel: netfs: FS-Cache loaded Aug 27 22:07:50 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 27 22:07:50 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 27 22:07:50 volumio kernel: Key type cifs.spnego registered Aug 27 22:07:50 volumio kernel: Key type cifs.idmap registered Aug 27 22:07:50 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. Aug 27 22:07:50 volumio kernel: CIFS: Attempting to mount //192.168.1.68/Music Aug 27 22:07:51 volumio volumio[1294]: info: Loading plugin "upnp_browser"... Aug 27 22:07:51 volumio volumio-remote-updater[874]: [2026-08-27 22:07:51] [connect] Successful connection Aug 27 22:07:52 volumio volumio[1294]: info: Starting UPNP Browser Aug 27 22:07:52 volumio volumio[1294]: info: Loading plugin "alarm-clock"... Aug 27 22:07:52 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 22:07:52 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 22:07:52 volumio volumio[1294]: info: Loading plugin "airplay_emulation"... Aug 27 22:07:53 volumio volumio[1294]: info: Starting Shairport Sync Aug 27 22:07:53 volumio volumio[1294]: info: Loading plugin "last_100"... Aug 27 22:07:53 volumio volumio[1294]: info: Loading plugin "webradio"... Aug 27 22:07:53 volumio volumio[1294]: info: Loading plugin "i2s_dacs"... Aug 27 22:07:53 volumio volumio[1294]: info: Loading plugin "volumiodiscovery"... Aug 27 22:07:53 volumio volumio[1294]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 22:07:53 volumio volumio[1294]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 22:07:53 volumio node[1294]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 22:07:53 volumio volumio[1294]: *** WARNING *** For more information see Aug 27 22:07:53 volumio volumio[1294]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 22:07:53 volumio volumio[1294]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 22:07:53 volumio volumio[1294]: *** WARNING *** For more information see Aug 27 22:07:53 volumio node[1294]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 22:07:53 volumio node[1294]: *** WARNING *** For more information see Aug 27 22:07:53 volumio node[1294]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 22:07:53 volumio node[1294]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 22:07:53 volumio node[1294]: *** WARNING *** For more information see Aug 27 22:07:53 volumio volumio[1294]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 22:07:53 volumio volumio[1294]: info: Discovery: Started advertising with name: Volumio Aug 27 22:07:53 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Aug 27 22:07:53 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 22:07:53 volumio volumio[1294]: info: Loading plugin "spop"... Aug 27 22:07:55 volumio python3[1296]: volumio: connect error: Connection refused by the server Aug 27 22:07:55 volumio python3[1296]: volumio: reconnect in 4s (attempt 2) Aug 27 22:07:56 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Aug 27 22:07:56 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 27 22:07:56 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 27 22:07:57 volumio volumio[1294]: info: Loading plugin "outputs"... Aug 27 22:07:57 volumio volumio[1294]: info: Loading plugin "albumart"... Aug 27 22:07:57 volumio volumio[1294]: info: Plugin example_plugin is not enabled Aug 27 22:07:57 volumio volumio[1294]: info: Loading plugin "inputs"... Aug 27 22:07:57 volumio volumio[1294]: info: Loading plugin "updater_comm"... Aug 27 22:07:58 volumio volumio[1294]: info: Plugin mpdemulation is not enabled Aug 27 22:07:58 volumio volumio[1294]: info: Loading plugin "rest_api"... Aug 27 22:07:58 volumio volumio[1294]: info: Loading plugin "websocket"... Aug 27 22:07:58 volumio volumio[1294]: info: Starting Socket.io Server version 1.7.4 Aug 27 22:07:58 volumio volumio[1294]: info: Loading plugin "radio_paradise"... Aug 27 22:07:58 volumio volumio[1694]: Forking 3 albumart workers Aug 27 22:07:59 volumio volumio[1294]: info: Applying required configuration parameters for plugin radio_paradise Aug 27 22:07:59 volumio volumio[1294]: info: [1787864879734] [RadioParadise] API delay: 5 Aug 27 22:07:59 volumio volumio[1294]: info: Plugin sableradio is not enabled Aug 27 22:07:59 volumio volumio[1294]: info: Loading plugin "sable"... Aug 27 22:08:00 volumio volumio[1294]: info: Loading i18n strings for locale en Aug 27 22:08:00 volumio volumio[1294]: Updating browse sources language Aug 27 22:08:00 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::initPlayerControls Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:08:01 volumio volumio[1294]: Express server listening on port 3000 Aug 27 22:08:01 volumio volumio[1294]: [Metrics] WebUI: 18s 231.91ms Aug 27 22:08:01 volumio volumio[1294]: info: CoreStateMachine::resetVolumioState Aug 27 22:08:01 volumio volumio[1294]: info: CoreStateMachine::getcurrentVolume Aug 27 22:08:01 volumio volumio[1294]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 22:08:01 volumio sudo[1783]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 22:08:01 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:01 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:01 volumio sudo[1785]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 22:08:01 volumio sudo[1785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:01 volumio sudo[1785]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:01 volumio volumio[1294]: info: Volumio Network Manager: Network status updated: 2 Aug 27 22:08:01 volumio volumio[1294]: info: Cannot mount NAS Icky-4 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 22:08:02 volumio volumio[1712]: Starting albumart workers Aug 27 22:08:02 volumio volumio[1294]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.28.1 Engine version: 3 Transport: polling Total Clients: 1 Aug 27 22:08:02 volumio volumio[1294]: info: VolumeController:: Volume=40 Mute =false Aug 27 22:08:02 volumio volumio[1294]: info: CoreStateMachine::pushState Aug 27 22:08:02 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:02 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:08:02 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Aug 27 22:08:02 volumio volumio[1294]: info: CoreStateMachine::updateTrackBlock Aug 27 22:08:02 volumio volumio[1294]: info: CorePlayQueue::getTrackBlock Aug 27 22:08:02 volumio volumio[1294]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 22:08:02 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:02 volumio volumio[1294]: info: Reloading queue from file Aug 27 22:08:03 volumio volumio[1294]: info: CoreStateMachine::setRepeat null single undefined Aug 27 22:08:03 volumio volumio[1294]: info: CoreStateMachine::pushState Aug 27 22:08:03 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Aug 27 22:08:03 volumio volumio[1294]: info: CoreStateMachine::setRandom null Aug 27 22:08:03 volumio volumio[1294]: info: CoreStateMachine::pushState Aug 27 22:08:03 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Aug 27 22:08:03 volumio volumio[1294]: info: Setting Device type: Raspberry PI Aug 27 22:08:03 volumio volumio[1294]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 27 22:08:03 volumio volumio[1294]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Aug 27 22:08:03 volumio volumio[1294]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 27 22:08:03 volumio volumio[1294]: info: Completed loading Core Plugins Aug 27 22:08:03 volumio volumio[1294]: info: Preparing to generate the ALSA configuration file Aug 27 22:08:03 volumio volumio[1294]: info: VolumeController:: Volume=40 Mute =false Aug 27 22:08:03 volumio volumio[1294]: info: CoreStateMachine::pushState Aug 27 22:08:03 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Aug 27 22:08:03 volumio sudo[1813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 22:08:03 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:03 volumio volumio[1294]: info: Discovery: adding 3fc45e26-6137-4808-a5a2-359692f6bdf1 Aug 27 22:08:03 volumio volumio[1294]: info: Discovery: Found device Volumio Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:03 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:03 volumio volumio[1294]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.28.1 Engine version: 3 Transport: polling Total Clients: 2 Aug 27 22:08:03 volumio volumio[1294]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.28.1 Engine version: 3 Transport: polling Total Clients: 3 Aug 27 22:08:03 volumio volumio[1294]: info: Discovery: this is already registered, 3fc45e26-6137-4808-a5a2-359692f6bdf1 Aug 27 22:08:03 volumio volumio[1294]: info: Discovery: Found device Volumio Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:03 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:03 volumio python3[1296]: volumio: connected http://localhost:3000 Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:03 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:08:03 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:03 volumio volumio[1294]: info: Asound.conf file unchanged, so no further update is needed Aug 27 22:08:03 volumio volumio[1294]: info: Output device has changed, restarting MPD Aug 27 22:08:04 volumio volumio[1294]: info: Output device has changed, restarting Shairport Sync Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:04 volumio sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:08:04 volumio sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:04 volumio sudo[1829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:08:04 volumio sudo[1828]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:04 volumio sudo[1829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:04 volumio volumio[1294]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 22:08:04 volumio volumio[1294]: info: ___________ START PLUGINS ___________ Aug 27 22:08:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 22:08:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 22:08:04 volumio volumio[1294]: info: ControllerMpd::onStart: Initializing MPD Aug 27 22:08:04 volumio volumio[1294]: info: Creating MPD Configuration file Aug 27 22:08:04 volumio sudo[1839]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:08:04 volumio sudo[1839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 22:08:04 volumio sudo[1843]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 22:08:04 volumio sudo[1839]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 22:08:04 volumio volumio[1294]: info: [1787864884525] CoreMusicLibrary::Adding element Media Servers Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:04 volumio sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:08:04 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:04 volumio volumio[1294]: info: UPNP Browser: Client initialized successfully Aug 27 22:08:04 volumio sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 22:08:04 volumio sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:04 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 22:08:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 22:08:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 22:08:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 22:08:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 22:08:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 22:08:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 22:08:04 volumio sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:08:04 volumio sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:04 volumio sudo[1842]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:04 volumio sudo[1854]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:08:04 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 22:08:04 volumio sudo[1859]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 22:08:04 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:04 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 22:08:04 volumio volumio[1294]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:04 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:04 volumio sudo[1840]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:05 volumio volumio[1294]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 22:08:05 volumio volumio[1294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 22:08:05 volumio volumio[1294]: info: [1787864885110] CoreMusicLibrary::Adding element Last_100 Aug 27 22:08:05 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:05 volumio volumio[1294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 22:08:05 volumio volumio[1294]: info: [1787864885112] CoreMusicLibrary::Adding element Webradio Aug 27 22:08:05 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:05 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 22:08:05 volumio volumio[1294]: info: Initializing BBC Radios Aug 27 22:08:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 27 22:08:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:08:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:08:05 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:05 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:05.537+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 22:08:05 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 22:08:05 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:05 volumio volumio[1711]: Starting albumart workers Aug 27 22:08:05 volumio volumio[1294]: info: Creating Spotify config file Aug 27 22:08:06 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:06 volumio volumio[1710]: Starting albumart workers Aug 27 22:08:06 volumio volumio-remote-updater[874]: [2026-08-27 22:08:06] [connect] Successful connection Aug 27 22:08:07 volumio volumio[1294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 22:08:07 volumio volumio[1294]: info: [1787864887235] CoreMusicLibrary::Adding element Radio Paradise Aug 27 22:08:07 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:07 volumio volumio[1294]: Cannot find translation for source Radio Paradise Aug 27 22:08:07 volumio volumio[1294]: info: Volumio Calling Home Aug 27 22:08:07 volumio sudo[1919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start sable.service Aug 27 22:08:07 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:07 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:09 volumio sudo[1943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Stephen,password=9617quad9617,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.68/Music /mnt/NAS/Icky-4 Aug 27 22:08:09 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:09 volumio kernel: CIFS: Attempting to mount //192.168.1.68/Music Aug 27 22:08:09 volumio volumio[1294]: info: Sable: service started Aug 27 22:08:09 volumio volumio[1294]: info: MPD Permissions set Aug 27 22:08:09 volumio volumio[1294]: info: MPD Permissions set Aug 27 22:08:09 volumio volumio[1294]: info: Upmpdcli Daemon Started Aug 27 22:08:09 volumio python3[1296]: pushBrowseSources -> 6 sources Aug 27 22:08:09 volumio python3[1296]: pushState -> status=stop vol=40 title='Redbud Tree' service=spop Aug 27 22:08:09 volumio volumio[1294]: 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: 4 Aug 27 22:08:09 volumio volumio[1294]: info: Volumio called home Aug 27 22:08:09 volumio volumio[1294]: info: Spotify config file written Aug 27 22:08:10 volumio sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 27 22:08:10 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:10 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 22:08:10 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 22:08:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 22:08:10 volumio go-librespot[1970]: go-librespot daemon starting... Aug 27 22:08:10 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:11 volumio volumio[1294]: 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: 4 Aug 27 22:08:11 volumio volumio[1294]: info: Received Get System Info Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:08:11 volumio volumio[1294]: info: Discovery: Getting this device information Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:11 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:08:11 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:11.045+01:00 level=INFO msg="system info for 22f452614ebb1b95a65eb69f5ca2c461" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 27 22:08:11 volumio volumio-remote-updater[874]: [2026-08-27 22:08:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787864886 101 Aug 27 22:08:11 volumio volumio[1294]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:11 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:11.267+01:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:08:11 volumio volumio[1294]: info: No need to fix Spotify hosts Aug 27 22:08:11 volumio python3[1296]: boot gate: clock=True volumio=True Aug 27 22:08:12 volumio volumio[1294]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 27 22:08:12 volumio python3[1296]: pushBrowseSources -> 7 sources Aug 27 22:08:12 volumio python3[1296]: pushBrowseSources -> 8 sources Aug 27 22:08:12 volumio python3[1296]: pushBrowseSources -> 9 sources Aug 27 22:08:12 volumio python3[1296]: pushBrowseSources -> 10 sources Aug 27 22:08:12 volumio volumio[1294]: info: Received Get System Info Aug 27 22:08:12 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:08:12 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:08:12 volumio volumio[1294]: info: Discovery: Getting this device information Aug 27 22:08:12 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:12 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:12 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:08:12 volumio python3[1296]: rotary started (BCM13/5/6). Aug 27 22:08:12 volumio python3[1296]: IR listener disabled (controls.ir). Aug 27 22:08:12 volumio python3[1296]: ipc: listening on /tmp/sable-cmd.sock Aug 27 22:08:12 volumio volumio[1294]: info: New Spotify access tokenBQAuz_VhIk... Aug 27 22:08:12 volumio volumio[1294]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 27 22:08:12 volumio volumio-remote-updater[874]: Test mode disabled Aug 27 22:08:12 volumio volumio-remote-updater[874]: Alpha mode disabled Aug 27 22:08:12 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Aug 27 22:08:12 volumio volumio[1294]: info: Starting Shairport Sync Aug 27 22:08:12 volumio volumio[1294]: info: Starting Shairport Sync Aug 27 22:08:12 volumio volumio[1294]: info: Starting Shairport Sync Aug 27 22:08:12 volumio python3[1296]: buttons: no MCP23017 found on i2c-1 (probed 0x20-0x27) -- controls disabled. Aug 27 22:08:12 volumio python3[1296]: buttons: MCP init failed, controls disabled: [Errno 5] Input/output error Aug 27 22:08:12 volumio python3[1296]: === Sable on hardware: stage=full. Ctrl-C to release SPI/GPIO. === Aug 27 22:08:12 volumio sudo[2025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 22:08:12 volumio sudo[2025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 22:08:12 volumio volumio[1294]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 22:08:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 22:08:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 22:08:12 volumio systemd[1]: shairport-sync.service: Consumed 1.271s CPU time. Aug 27 22:08:12 volumio sudo[2030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 22:08:12 volumio sudo[2030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:12 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 22:08:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 22:08:12 volumio sudo[2025]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 22:08:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 22:08:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 22:08:12 volumio sudo[2028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 22:08:12 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 22:08:12 volumio sudo[2030]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:12 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 22:08:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 22:08:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 22:08:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 22:08:13 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:13.055+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" Aug 27 22:08:13 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:13.056+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" Aug 27 22:08:13 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:13.056+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" Aug 27 22:08:13 volumio volumio[1294]: info: Shairport-Sync Started Aug 27 22:08:13 volumio volumio[1294]: Error adding Membership: Error: addMembership EINVAL Aug 27 22:08:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 22:08:13 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 22:08:13 volumio sudo[2080]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 22:08:13 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:13 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:13 volumio sudo[2082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 22:08:13 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:13 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:13 volumio volumio[1294]: info: Shairport-Sync Started Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 22:08:13 volumio volumio[1294]: info: Shairport-Sync Started Aug 27 22:08:13 volumio volumio[1294]: info: Received Get System Info Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:08:13 volumio volumio[1294]: info: Discovery: Getting this device information Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:13 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 22:08:13 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:13.950+01:00 level=INFO msg="enabling local network discovery" Aug 27 22:08:13 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:13 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:14 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:14.046+01:00 level=INFO msg="enabling BLE discovery" Aug 27 22:08:14 volumio volumio[1294]: verbose: New Socket.io Connection to 192.168.1.114 from 192.168.1.197 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:14 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::volumioGetQueue Aug 27 22:08:14 volumio volumio[1294]: info: CoreStateMachine::getQueue Aug 27 22:08:14 volumio volumio[1294]: info: CorePlayQueue::getQueue Aug 27 22:08:14 volumio volumio[1294]: info: Listing playlists Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 22:08:14 volumio volumio[1294]: info: Received Get System Info Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:08:14 volumio volumio[1294]: info: Discovery: Getting this device information Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:14 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:14 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 22:08:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 22:08:15 volumio volumio[1294]: info: Spotify Successfully logged in Aug 27 22:08:15 volumio volumio[1294]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 22:08:15 volumio volumio[1294]: info: [1787864895048] CoreMusicLibrary::Adding element Spotify Aug 27 22:08:15 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:08:15 volumio volumio[1294]: Cannot find translation for source Radio Paradise Aug 27 22:08:15 volumio volumio[1294]: Cannot find translation for source Spotify Aug 27 22:08:15 volumio volumio[1294]: info: go-librespot daemon successfully initialized Aug 27 22:08:15 volumio volumio5-onboarding[1863]: time=2026-08-27T22:08:15.107+01:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 22:08:15 volumio python3[1296]: pushBrowseSources -> 11 sources Aug 27 22:08:15 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:15 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 27 22:08:15 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 27 22:08:15 volumio mpd[1862]: 2026-08-27T22:08:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 22:08:15 volumio volumio[1294]: info: Cannot mount NAS Icky-4 at system boot, trial number 2 ,retrying in 5 seconds Aug 27 22:08:15 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 22:08:15 volumio volumio[1294]: info: Received Get System Info Aug 27 22:08:15 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:08:15 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:08:15 volumio volumio[1294]: info: Discovery: Getting this device information Aug 27 22:08:15 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:15 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:15 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:08:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 22:08:16 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 22:08:16 volumio sudo[1829]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=debug msg="app state loaded" Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=debug msg="stored credentials not found" Aug 27 22:08:16 volumio python3[1296]: live spectrum source: cava on /tmp/cava.fifo -> /tmp/sable-display.fifo Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 22:08:16 volumio volumio[1294]: info: Completed starting Core Plugins Aug 27 22:08:16 volumio volumio[1294]: info: ------------------------------------------- Aug 27 22:08:16 volumio volumio[1294]: info: ----- MyVolumio plugins startup ---- Aug 27 22:08:16 volumio volumio[1294]: info: ------------------------------------------- Aug 27 22:08:16 volumio volumio[1294]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 22:08:16 volumio volumio[1294]: info: MPD running with PID1862 Aug 27 22:08:16 volumio volumio[1294]: ,establishing connection Aug 27 22:08:16 volumio python3[1296]: pushToastMessage -> 'Player successfully restarted' Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=info msg="zeroconf server listening on port 41521" Aug 27 22:08:16 volumio volumio[1294]: error: MPD error: The expression evaluated to a falsy value: Aug 27 22:08:16 volumio volumio[1294]: assert.ok(self.idling) Aug 27 22:08:16 volumio volumio[1294]: error: The expression evaluated to a falsy value: Aug 27 22:08:16 volumio volumio[1294]: assert.ok(self.idling) Aug 27 22:08:16 volumio go-librespot[1971]: time="2026-08-27T22:08:16+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 22:08:16 volumio volumio[1294]: error: MPD error: The expression evaluated to a falsy value: Aug 27 22:08:16 volumio volumio[1294]: assert.ok(self.idling) Aug 27 22:08:16 volumio volumio[1294]: error: The expression evaluated to a falsy value: Aug 27 22:08:16 volumio volumio[1294]: assert.ok(self.idling) Aug 27 22:08:16 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 27 22:08:16 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 27 22:08:16 volumio volumio[1294]: error: updateQueue error: null Aug 27 22:08:18 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Aug 27 22:08:18 volumio go-librespot[1971]: time="2026-08-27T22:08:18+01:00" level=debug msg="new websocket client" Aug 27 22:08:18 volumio volumio[1294]: info: Connection to go-librespot Websocket established Aug 27 22:08:20 volumio sudo[2113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Stephen,password=9617quad9617,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.68/Music /mnt/NAS/Icky-4 Aug 27 22:08:20 volumio sudo[2113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:21 volumio kernel: CIFS: Attempting to mount //192.168.1.68/Music Aug 27 22:08:21 volumio volumio[1294]: info: Getting Spotify volume Aug 27 22:08:21 volumio volumio[1294]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 27 22:08:21 volumio volumio[1294]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 22:08:25 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:25 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:25 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:25 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:26 volumio volumio[1294]: Cannot compose Albumart path Aug 27 22:08:27 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 27 22:08:27 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 27 22:08:27 volumio sudo[2113]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 22:08:27 volumio volumio[1294]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 27 22:08:27 volumio volumio[1294]: info: Received Get System Version Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 22:08:27 volumio volumio[1294]: info: Received Get System Info Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 22:08:27 volumio volumio[1294]: info: Discovery: Getting this device information Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:27 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:08:27 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 27 22:08:27 volumio volumio[1294]: info: Cannot mount NAS Icky-4 at system boot, trial number 3 ,retrying in 5 seconds Aug 27 22:08:28 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:28 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:28 volumio volumio[1294]: info: Aligning Spotify Volume to Volumio Volume Aug 27 22:08:28 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Aug 27 22:08:28 volumio volumio[1294]: info: CorePlayQueue::getTrack 0 Aug 27 22:08:28 volumio volumio[1294]: info: Setting Spotify Volume from Volumio: 40 Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 22:08:28 volumio volumio[1294]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 22:08:28 volumio volumio[1294]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 22:08:28 volumio volumio[1294]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 22:08:28 volumio volumio[1294]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 22:08:28 volumio volumio[1294]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 27 22:08:28 volumio volumio[1294]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 22:08:28 volumio volumio[1294]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 22:08:28 volumio volumio[1294]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 22:08:34 volumio upmpdcli[2146]: writing RSA key Aug 27 22:08:35 volumio volumio[1294]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 22:08:35 volumio volumio[1294]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 22:08:35 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:35 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:35 volumio volumio[1294]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 22:08:35 volumio volumio[1294]: info: MyVolumio login type: Token Aug 27 22:08:35 volumio volumio[1294]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 22:08:35 volumio volumio[1294]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 27 22:08:37 volumio volumio[1294]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 27 22:08:37 volumio volumio[1294]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 27 22:08:37 volumio volumio[1294]: info: Streaming services startup Aug 27 22:08:37 volumio volumio[1294]: info: Starting Streaming Daemon Aug 27 22:08:37 volumio volumio[1294]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 27 22:08:38 volumio sudo[2151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 22:08:38 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:38 volumio volumio[1294]: info: Sending Spotify command with payload to local API: /player/volume Aug 27 22:08:38 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root Aug 27 22:08:38 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 22:08:38 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:08:38 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 22:08:38 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Aug 27 22:08:38 volumio sudo[2161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Stephen,password=9617quad9617,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.68/Music /mnt/NAS/Icky-4 Aug 27 22:08:38 volumio volumio[1294]: error: Cannot start Volumio Streaming Daemon Aug 27 22:08:38 volumio volumio[1294]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 22:08:38 volumio volumio[1294]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 22:08:38 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:08:38 volumio volumio[1294]: info: Preload queue cleared Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: spotify:track:5ilaeDC35aGCDaQFo8QvN7 Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: spotify:track:3rMdFyxhMkS0h99yPBhcEg Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/09 Brothers In Arms.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/01 So Far Away.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Robbie Williams/Swing When You're Winning/07 - Well, did you evah.mp3 Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Robbie Williams/Swing When You're Winning/04 - Do nothin till you hear f.mp3 Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Robbie Williams/Swing When You're Winning/01 - I will talk and hollywood.mp3 Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Compilations/I Care 4 U/01 Back & Forth.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: music-library/NAS/Icky-4/iTunes/iTunes Music/Music/50 CENT/P.I.M.P. (Remix) CDS/02 PIMP(RMX)(CLEAN).MP3 Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/AC_DC/Let There Be Rock/08 Whole Lotta Rosie.mp3 Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/05 Why Worry.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/04 Your Latest Trick.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: music-library/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/01 So Far Away.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/On Every Street/04 Fade To Black.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/On Every Street/03 When It Comes To You.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/On Every Street/02 On Every Street.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/AC_DC/Back In Black/01 Hells Bells.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/AC_DC/Back In Black/03 What Do You Do for Money Honey.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/AC_DC/Back In Black/02 Shoot to Thrill.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Eva Cassidy/Live At Blues Alley/08 Fields Of Gold.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/On Every Street/01 Calling Elvis.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: music-library/NAS/Icky-4/iTunes/iTunes Music/Music/Al Jarreau/High Crime/01 Raging Waters.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/50 CENT/P.I.M.P. (Remix) CDS/02 PIMP(RMX)(CLEAN).MP3 Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Carole King/Tapestry/02 So Far Away.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Carole King/Tapestry/01 I Feel the Earth Move.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Bicep/Bicep/01 Orca.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/AC_DC/Back In Black/07 You Shook Me All Night Long.m4a Aug 27 22:08:38 volumio volumio[1294]: info: Preloading song: mnt/NAS/Icky-4/iTunes/iTunes Music/Music/AC_DC/Back In Black/06 Back In Black.m4a Aug 27 22:08:38 volumio kernel: CIFS: Attempting to mount //192.168.1.68/Music Aug 27 22:08:38 volumio volumio[1294]: info: Exploding uri spotify:track:5ilaeDC35aGCDaQFo8QvN7 in service spop Aug 27 22:08:38 volumio volumio[1294]: info: Exploding uri spotify:track:3rMdFyxhMkS0h99yPBhcEg in service spop Aug 27 22:08:38 volumio volumio[1294]: info: Exploding uri mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/09 Brothers In Arms.m4a in service mpd Aug 27 22:08:38 volumio volumio[1294]: error: scanFolder - failure to stat '/mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/09 Brothers In Arms.m4a' Aug 27 22:08:38 volumio volumio[1294]: info: Exploding uri mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/01 So Far Away.m4a in service mpd Aug 27 22:08:38 volumio volumio[1294]: error: scanFolder - failure to stat '/mnt/NAS/Icky-4/iTunes/iTunes Music/Music/Dire Straits/Brothers In Arms/01 So Far Away.m4a' Aug 27 22:08:38 volumio volumio[1294]: error: MyVolumio Custom Token format not valid, refreshing it Aug 27 22:08:38 volumio volumio[1294]: info: Exploding uri http://stream.radioparadise.com/beyond-flacm in service radio_paradise Aug 27 22:08:38 volumio volumio[1294]: error: [RadioParadise] explodeUri: No station found for uri "http://stream.radioparadise.com/beyond-flacm" Aug 27 22:08:38 volumio volumio[1294]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:08:38 volumio volumio[1294]: TypeError: Cannot read properties of undefined (reading 'then') Aug 27 22:08:38 volumio volumio[1294]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Aug 27 22:08:38 volumio volumio[1294]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Aug 27 22:08:38 volumio volumio[1294]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Aug 27 22:08:38 volumio volumio[1294]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Aug 27 22:08:38 volumio volumio[1294]: at listOnTimeout (node:internal/timers:573:17) Aug 27 22:08:38 volumio volumio[1294]: at process.processTimers (node:internal/timers:514:7) Aug 27 22:08:38 volumio volumio[1294]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:08:41 volumio sudo[2179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 22:07' Aug 27 22:08:41 volumio sudo[2179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"