-- Logs begin at Sat 2025-09-06 21:26:30 BST, end at Sat 2025-09-06 21:29:35 BST. -- Sep 06 21:28:08 volumio sshd[1550]: pam_unix(sshd:session): session closed for user volumio Sep 06 21:28:08 volumio systemd[1]: session-1.scope: Succeeded. Sep 06 21:28:08 volumio systemd-logind[683]: Session 1 logged out. Waiting for processes to exit. Sep 06 21:28:08 volumio systemd-logind[683]: Removed session 1. Sep 06 21:28:18 volumio systemd[1]: Stopping User Manager for UID 1000... Sep 06 21:28:18 volumio volumio[1079]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 21:28:18 volumio systemd[1554]: Stopped target Default. Sep 06 21:28:18 volumio systemd[1554]: Stopped target Basic System. Sep 06 21:28:18 volumio systemd[1554]: Stopped target Paths. Sep 06 21:28:18 volumio systemd[1554]: Stopped target Sockets. Sep 06 21:28:18 volumio systemd[1554]: gpg-agent-browser.socket: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). Sep 06 21:28:18 volumio systemd[1554]: dirmngr.socket: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Closed GnuPG network certificate management daemon. Sep 06 21:28:18 volumio systemd[1554]: gpg-agent-ssh.socket: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Closed GnuPG cryptographic agent (ssh-agent emulation). Sep 06 21:28:18 volumio systemd[1554]: gpg-agent-extra.socket: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Closed GnuPG cryptographic agent and passphrase cache (restricted). Sep 06 21:28:18 volumio systemd[1554]: dbus.socket: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Closed D-Bus User Message Bus Socket. Sep 06 21:28:18 volumio systemd[1554]: Stopped target Timers. Sep 06 21:28:18 volumio systemd[1554]: pulseaudio.socket: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Closed Sound System. Sep 06 21:28:18 volumio systemd[1554]: gpg-agent.socket: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Closed GnuPG cryptographic agent and passphrase cache. Sep 06 21:28:18 volumio systemd[1554]: Reached target Shutdown. Sep 06 21:28:18 volumio systemd[1554]: systemd-exit.service: Succeeded. Sep 06 21:28:18 volumio systemd[1554]: Started Exit the Session. Sep 06 21:28:18 volumio systemd[1554]: Reached target Exit the Session. Sep 06 21:28:18 volumio systemd[1555]: pam_unix(systemd-user:session): session closed for user volumio Sep 06 21:28:18 volumio systemd[1]: user@1000.service: Succeeded. Sep 06 21:28:18 volumio systemd[1]: Stopped User Manager for UID 1000. Sep 06 21:28:19 volumio systemd[1]: Stopping User Runtime Directory /run/user/1000... Sep 06 21:28:19 volumio volumio[1079]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 21:28:19 volumio systemd[1]: run-user-1000.mount: Succeeded. Sep 06 21:28:19 volumio systemd[1]: user-runtime-dir@1000.service: Succeeded. Sep 06 21:28:19 volumio systemd[1]: Stopped User Runtime Directory /run/user/1000. Sep 06 21:28:19 volumio systemd[1]: Removed slice User Slice of UID 1000. Sep 06 21:28:22 volumio sshd[2046]: Accepted password for volumio from 192.168.0.191 port 62931 ssh2 Sep 06 21:28:22 volumio sshd[2046]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 06 21:28:22 volumio systemd[1]: Created slice User Slice of UID 1000. Sep 06 21:28:22 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Sep 06 21:28:22 volumio volumio[1079]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 21:28:22 volumio systemd-logind[683]: New session 3 of user volumio. Sep 06 21:28:22 volumio volumio[1079]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 21:28:22 volumio volumio[1079]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 21:28:22 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Sep 06 21:28:22 volumio systemd[1]: Starting User Manager for UID 1000... Sep 06 21:28:22 volumio volumio[1079]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 21:28:22 volumio systemd[2049]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Sep 06 21:28:22 volumio systemd[2049]: Listening on GnuPG cryptographic agent and passphrase cache. Sep 06 21:28:22 volumio systemd[2049]: Reached target Timers. Sep 06 21:28:22 volumio systemd[2049]: Starting D-Bus User Message Bus Socket. Sep 06 21:28:22 volumio systemd[2049]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Sep 06 21:28:22 volumio systemd[2049]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Sep 06 21:28:22 volumio systemd[2049]: Listening on GnuPG network certificate management daemon. Sep 06 21:28:22 volumio systemd[2049]: Listening on Sound System. Sep 06 21:28:22 volumio systemd[2049]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Sep 06 21:28:22 volumio systemd[2049]: Reached target Paths. Sep 06 21:28:22 volumio systemd[2049]: Listening on D-Bus User Message Bus Socket. Sep 06 21:28:22 volumio systemd[2049]: Reached target Sockets. Sep 06 21:28:22 volumio systemd[2049]: Reached target Basic System. Sep 06 21:28:22 volumio systemd[2049]: Reached target Default. Sep 06 21:28:22 volumio systemd[2049]: Startup finished in 122ms. Sep 06 21:28:22 volumio systemd[1]: Started User Manager for UID 1000. Sep 06 21:28:22 volumio systemd[1]: Started Session 3 of user volumio. Sep 06 21:28:36 volumio sudo[2131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 21:28:36 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:28:36 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root Sep 06 21:28:36 volumio sudo[2133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 21:28:36 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:28:36 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Sep 06 21:28:36 volumio volumio[1079]: verbose: New Socket.io Connection to 192.168.0.164 from 192.168.0.191 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 06 21:28:36 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 21:28:36 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:28:36 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Sep 06 21:28:36 volumio sudo[2139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 21:28:36 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:28:36 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Sep 06 21:28:36 volumio volumio[1079]: verbose: New Socket.io Connection to 192.168.0.164 from 192.168.0.191 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 06 21:28:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 06 21:28:36 volumio volumio[1079]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 21:28:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 21:28:36 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:28:36 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 06 21:28:37 volumio volumio[1079]: info: Received Get System Info Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 21:28:37 volumio volumio[1079]: info: Discovery: Getting this device information Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:28:37 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:28:37 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:28:37 volumio volumio[1079]: info: Listing playlists Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 06 21:28:37 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:28:37 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:28:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 06 21:28:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 21:28:38 volumio volumio[1079]: info: Received Get System Info Sep 06 21:28:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 21:28:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 21:28:38 volumio volumio[1079]: info: Discovery: Getting this device information Sep 06 21:28:38 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:28:38 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:28:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 21:28:40 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 21:28:40 volumio volumio[1079]: info: Received Get System Info Sep 06 21:28:40 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 21:28:40 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 21:28:40 volumio volumio[1079]: info: Discovery: Getting this device information Sep 06 21:28:40 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:28:40 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:28:40 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:28:41 volumio volumio[1079]: info: Retrieving Cloud Streaming UI Sep 06 21:28:41 volumio volumio[1079]: info: Getting Tidal Cloud Configuration Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:28:41 volumio volumio[1079]: info: Getting Qobuz Cloud Configuration Sep 06 21:28:41 volumio volumio[1079]: info: Asking plugin for UI Config Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:28:41 volumio volumio[1079]: info: Getting Spotify Cloud Configuration Sep 06 21:28:41 volumio volumio[1079]: info: Asking plugin for UI Config Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:28:41 volumio volumio[1079]: info: Saving Spotify Acccount Sep 06 21:28:41 volumio volumio[1079]: info: Got it Sep 06 21:28:41 volumio volumio[1079]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 06 21:28:41 volumio volumio[1079]: info: Got Tidal Cloud Configuration Sep 06 21:28:41 volumio volumio[1079]: info: Got it Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:28:41 volumio volumio[1079]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 21:28:41 volumio volumio[1079]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 21:28:42 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 21:28:42 volumio mpd[1505]: exception: No database Sep 06 21:28:42 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 06 21:28:44 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 06 21:28:44 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand rescan Sep 06 21:28:46 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 21:28:46 volumio mpd[1505]: exception: No database Sep 06 21:28:46 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 06 21:28:50 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 21:28:50 volumio mpd[1505]: exception: No database Sep 06 21:28:54 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 21:28:54 volumio mpd[1505]: exception: No database Sep 06 21:28:55 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 21:28:55 volumio volumio[1079]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 06 21:28:55 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 06 21:28:55 volumio volumio[1079]: info: Received Get System Version Sep 06 21:28:55 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 21:28:55 volumio volumio[1079]: info: Received Get System Info Sep 06 21:28:55 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 21:28:55 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 21:28:55 volumio volumio[1079]: info: Discovery: Getting this device information Sep 06 21:28:55 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:28:55 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:28:55 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 21:28:58 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 21:28:58 volumio mpd[1505]: exception: No database Sep 06 21:29:02 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 21:29:02 volumio mpd[1505]: exception: No database Sep 06 21:29:06 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:29:06 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:29:10 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 06 21:29:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 06 21:29:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 06 21:29:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 06 21:29:10 volumio volumio[1079]: info: Received Get System Version Sep 06 21:29:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 21:29:17 volumio volumio-remote-updater[648]: No test mode Sep 06 21:29:17 volumio volumio-remote-updater[648]: No alpha test mode Sep 06 21:29:17 volumio volumio[1079]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 06 21:29:17 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 06 21:29:20 volumio volumio[1079]: info: CoreCommandRouter::Close All Modals sent Sep 06 21:29:31 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Sep 06 21:29:31 volumio volumio[1079]: info: CoreCommandRouter::Reload Ui Sep 06 21:29:31 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 21:29:31 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:31 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:31 volumio sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 21:29:31 volumio sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:31 volumio sudo[2249]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:31 volumio volumio[1079]: verbose: New Socket.io Connection to 192.168.0.164 from 192.168.0.191 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 06 21:29:32 volumio volumio[1079]: No protocol specified Sep 06 21:29:32 volumio volumio[1079]: xcb_connection_has_error() returned true Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 06 21:29:32 volumio volumio[1079]: info: Received Get System Info Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 21:29:32 volumio volumio[1079]: info: Discovery: Getting this device information Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:29:32 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 21:29:32 volumio volumio-remote-updater[648]: No test mode Sep 06 21:29:32 volumio volumio-remote-updater[648]: No alpha test mode Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Retrieving Cloud Streaming UI Sep 06 21:29:32 volumio volumio[1079]: info: Getting Tidal Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Getting Qobuz Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: Asking plugin for UI Config Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Getting Spotify Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: Asking plugin for UI Config Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Saving Spotify Acccount Sep 06 21:29:32 volumio volumio[1079]: info: Got it Sep 06 21:29:32 volumio volumio[1079]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 06 21:29:32 volumio volumio[1079]: info: Got Tidal Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: Got it Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 06 21:29:32 volumio volumio[1079]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 06 21:29:32 volumio volumio[1079]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 06 21:29:32 volumio volumio[1079]: No protocol specified Sep 06 21:29:32 volumio volumio[1079]: xcb_connection_has_error() returned true Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 06 21:29:32 volumio volumio[1079]: info: Received Get System Info Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 21:29:32 volumio volumio[1079]: info: Discovery: Getting this device information Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Sep 06 21:29:32 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 21:29:32 volumio volumio-remote-updater[648]: No test mode Sep 06 21:29:32 volumio volumio-remote-updater[648]: No alpha test mode Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 21:29:32 volumio volumio[1079]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Retrieving Cloud Streaming UI Sep 06 21:29:32 volumio volumio[1079]: info: Getting Tidal Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Getting Qobuz Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: Asking plugin for UI Config Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Getting Spotify Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: Asking plugin for UI Config Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: info: Saving Spotify Acccount Sep 06 21:29:32 volumio volumio[1079]: info: Got it Sep 06 21:29:32 volumio volumio[1079]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 06 21:29:32 volumio volumio[1079]: info: Got Tidal Cloud Configuration Sep 06 21:29:32 volumio volumio[1079]: info: Got it Sep 06 21:29:32 volumio volumio[1079]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 21:29:32 volumio volumio[1079]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 06 21:29:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 06 21:29:33 volumio volumio[1079]: info: Loading i18n strings for locale en Sep 06 21:29:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 06 21:29:33 volumio volumio[1079]: info: Setting timezone to Europe/London Sep 06 21:29:33 volumio sudo[2263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 06 21:29:33 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Sep 06 21:29:33 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio sudo[2269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 06 21:29:33 volumio sudo[2269]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio sudo[2269]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Sep 06 21:29:33 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.50' (uid=0 pid=2273 comm="/usr/bin/timedatectl set-timezone Europe/London ") Sep 06 21:29:33 volumio systemd[1]: Starting Time & Date Service... Sep 06 21:29:33 volumio dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 06 21:29:33 volumio systemd[1]: Started Time & Date Service. Sep 06 21:29:33 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio volumio[1079]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 21:29:33 volumio volumio[1079]: Updating browse sources language Sep 06 21:29:33 volumio volumio[1079]: Cannot find translation for source Randomizer Sep 06 21:29:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 21:29:33 volumio volumio[1079]: Cannot find translation for source Randomizer Sep 06 21:29:33 volumio volumio[1079]: info: Fetching Streaming Services browse cache Sep 06 21:29:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 06 21:29:33 volumio volumio[1079]: info: Loading i18n strings for locale en Sep 06 21:29:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 06 21:29:33 volumio volumio[1079]: info: Setting timezone to UTC Sep 06 21:29:33 volumio sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 06 21:29:33 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio sudo[2276]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Sep 06 21:29:33 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 06 21:29:33 volumio sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio sudo[2282]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio sudo[2285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Sep 06 21:29:33 volumio sudo[2285]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:33 volumio systemd-timedated[2274]: Failed to set time zone: Permission denied Sep 06 21:29:33 volumio sudo[2285]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:33 volumio volumio[1079]: Failed to set time zone: Access denied Sep 06 21:29:33 volumio volumio[1079]: info: Could not set timezone, retrying Sep 06 21:29:33 volumio volumio[1079]: Updating browse sources language Sep 06 21:29:33 volumio volumio[1079]: Cannot find translation for source Randomizer Sep 06 21:29:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 21:29:33 volumio volumio[1079]: Cannot find translation for source Randomizer Sep 06 21:29:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 21:29:34 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 21:29:34 volumio volumio[1079]: info: Fetching Streaming Services browse cache Sep 06 21:29:34 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 06 21:29:34 volumio volumio[1079]: info: Loading i18n strings for locale en Sep 06 21:29:34 volumio volumio[1079]: Updating browse sources language Sep 06 21:29:34 volumio volumio[1079]: Cannot find translation for source Randomizer Sep 06 21:29:34 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 21:29:34 volumio volumio[1079]: Cannot find translation for source Randomizer Sep 06 21:29:34 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 06 21:29:34 volumio volumio[1079]: info: Setting timezone to Europe/London Sep 06 21:29:34 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 06 21:29:34 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:34 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:34 volumio sudo[2291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Sep 06 21:29:34 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:34 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:34 volumio sudo[2294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 06 21:29:34 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:34 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:34 volumio sudo[2297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Sep 06 21:29:34 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:34 volumio systemd-timedated[2274]: Failed to set time zone: Permission denied Sep 06 21:29:34 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:34 volumio volumio[1079]: Failed to set time zone: Access denied Sep 06 21:29:34 volumio volumio[1079]: info: Could not set timezone, retrying Sep 06 21:29:34 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 06 21:29:34 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 06 21:29:34 volumio sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 06 21:29:34 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:34 volumio sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Sep 06 21:29:34 volumio sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 21:29:34 volumio systemd-timedated[2274]: Failed to set time zone: Permission denied Sep 06 21:29:34 volumio sudo[2303]: pam_unix(sudo:session): session closed for user root Sep 06 21:29:34 volumio volumio[1079]: Failed to set time zone: Access denied Sep 06 21:29:34 volumio volumio[1079]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 21:29:34 volumio volumio[1079]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC' Sep 06 21:29:34 volumio volumio[1079]: Failed to set time zone: Access denied Sep 06 21:29:34 volumio volumio[1079]: at checkExecSyncError (child_process.js:790:11) Sep 06 21:29:34 volumio volumio[1079]: at execSync (child_process.js:863:15) Sep 06 21:29:34 volumio volumio[1079]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9) Sep 06 21:29:34 volumio volumio[1079]: at listOnTimeout (internal/timers.js:557:17) Sep 06 21:29:34 volumio volumio[1079]: at processTimers (internal/timers.js:500:7) { Sep 06 21:29:34 volumio volumio[1079]: status: 1, Sep 06 21:29:34 volumio volumio[1079]: signal: null, Sep 06 21:29:34 volumio volumio[1079]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Sep 06 21:29:34 volumio volumio[1079]: pid: 2301, Sep 06 21:29:34 volumio volumio[1079]: stdout: '', Sep 06 21:29:34 volumio volumio[1079]: stderr: 'Failed to set time zone: Access denied\n' Sep 06 21:29:34 volumio volumio[1079]: } Sep 06 21:29:34 volumio volumio[1079]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 21:29:35 volumio sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-06 21:28 Sep 06 21:29:35 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"