-- Logs begin at Thu 2025-05-01 05:55:07 PDT, end at Thu 2025-05-01 12:55:24 PDT. --
May 01 12:55:13 volumio systemd-timedated[1675]: Changed local time to Thu May 1 19:55:13 2025
May 01 12:55:13 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root
May 01 12:55:13 volumio volumio-time-update[730]: volumio-time-update-util: System time updated successfully.
May 01 12:55:13 volumio volumio[980]: info: Discovery: A device disappeared from network
May 01 12:55:13 volumio volumio[980]: info: Discovery: Device volumio disappeared from network
May 01 12:55:13 volumio systemd[1]: Started Volumio Time Update Utility.
May 01 12:55:13 volumio systemd[1]: Reached target Multi-User System.
May 01 12:55:13 volumio systemd[1]: Reached target Graphical Interface.
May 01 12:55:13 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
May 01 12:55:13 volumio volumio[980]: info: Discovery: adding 07395c85-2e0d-4e11-8106-90b22276379e
May 01 12:55:13 volumio volumio[980]: info: Discovery: Found device Volumio
May 01 12:55:13 volumio volumio[980]: info: CoreCommandRouter::volumioGetState
May 01 12:55:13 volumio volumio[980]: info: CorePlayQueue::getTrack 0
May 01 12:55:13 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
May 01 12:55:13 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
May 01 12:55:13 volumio systemd[1]: Startup finished in 13.369s (kernel) + 11.639s (userspace) = 25.008s.
May 01 12:55:13 volumio ntpd[1642]: Soliciting pool server 142.202.190.19
May 01 12:55:13 volumio ntpd[1642]: Soliciting pool server 198.71.50.75
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 01 12:55:14 volumio volumio[980]: info: Adding plugin bluetooth to MyMusic Plugins
May 01 12:55:14 volumio volumio[980]: info: Adding plugin multiroom to MyMusic Plugins
May 01 12:55:14 volumio volumio[980]: info: Adding plugin metavolumio to MyMusic Plugins
May 01 12:55:14 volumio volumio[980]: info: Adding plugin cd_controller to MyMusic Plugins
May 01 12:55:14 volumio volumio[980]: info: Adding plugin smart_inputs to MyMusic Plugins
May 01 12:55:14 volumio volumio[980]: info: Adding plugin tidalconnect to MyMusic Plugins
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 01 12:55:14 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 12:55:14 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 01 12:55:14 volumio volumio[980]: info: Starting MyVolumio Remote Streaming Endpoints
May 01 12:55:14 volumio volumio[980]: info: MyVolumio not started
May 01 12:55:14 volumio volumio[980]: info: Initializing device activation check
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 01 12:55:14 volumio volumio[980]: info: Starting Streaming Service Transparent Proxy
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 01 12:55:14 volumio volumio[980]: info: Streaming services startup
May 01 12:55:14 volumio volumio[980]: info: Starting Streaming Daemon
May 01 12:55:14 volumio sudo[1687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 01 12:55:14 volumio sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:14 volumio sudo[1687]: pam_unix(sudo:session): session closed for user root
May 01 12:55:14 volumio volumio[980]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 01 12:55:14 volumio volumio[980]: error: Cannot start Volumio Streaming Daemon
May 01 12:55:14 volumio volumio[980]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 01 12:55:14 volumio volumio[980]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 01 12:55:14 volumio volumio[980]: info: Volumio Network Manager: Network status updated: 1
May 01 12:55:14 volumio volumio[980]: STREAMING PROXY: Starting server on port 3245
May 01 12:55:14 volumio volumio[980]: Node JS runtime: 14
May 01 12:55:14 volumio sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 12:55:14 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:14 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root
May 01 12:55:14 volumio sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 12:55:14 volumio sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:14 volumio sudo[1712]: pam_unix(sudo:session): session closed for user root
May 01 12:55:14 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 01 12:55:14 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
May 01 12:55:14 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root
May 01 12:55:14 volumio volumio[980]: info: Upmpdcli Daemon Started
May 01 12:55:14 volumio ntpd[1642]: Soliciting pool server 216.31.17.12
May 01 12:55:14 volumio ntpd[1642]: Soliciting pool server 2001:470:1f20:bb:123:123:123:123
May 01 12:55:14 volumio volumio[1719]: Generating RSA private key, 4096 bit long modulus (2 primes)
May 01 12:55:15 volumio volumio[980]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.799&uuid=ec5b5ac4ee1b8e98de263b914dd0814a" http://updates.volumio.org/downloader-v1/track-device
May 01 12:55:15 volumio volumio[980]: % Total % Received % Xferd Average Speed Time Time Time Current
May 01 12:55:15 volumio volumio[980]: Dload Upload Total Spent Left Speed
May 01 12:55:15 volumio volumio[980]: [132B blob data]
May 01 12:55:15 volumio volumio[980]: retrying in 5 seconds, trial 0
May 01 12:55:15 volumio volumio[980]: info: Volumio Calling Home
May 01 12:55:15 volumio volumio[980]: info: Volumio called home
May 01 12:55:16 volumio volumio[1719]: .........................................................................................................................................................................................................................................................................................................++++
May 01 12:55:16 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
May 01 12:55:16 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
May 01 12:55:16 volumio volumio[980]: info: Refreshing Cached IP Addresses
May 01 12:55:16 volumio sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
May 01 12:55:16 volumio sudo[1739]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:16 volumio sudo[1739]: pam_unix(sudo:session): session closed for user root
May 01 12:55:16 volumio sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 12:55:16 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:16 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root
May 01 12:55:16 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 12:55:16 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:16 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root
May 01 12:55:16 volumio volumio[1719]: ...............................................................................................................................................................................++++
May 01 12:55:16 volumio volumio[1719]: e is 65537 (0x010001)
May 01 12:55:20 volumio ntpd[1642]: receive: Unexpected origin timestamp 0xebbe5027.c9c9ab8c does not match aorg 0000000000.00000000 from server@208.113.130.146 xmt 0xebbe5028.bbbc9b22
May 01 12:55:20 volumio ntpd[1642]: receive: Unexpected origin timestamp 0xebbe5027.c9caabf2 does not match aorg 0000000000.00000000 from server@141.11.228.173 xmt 0xebbe5028.bdcfe2ae
May 01 12:55:20 volumio ntpd[1642]: receive: Unexpected origin timestamp 0xebbe5027.c9ca5abf does not match aorg 0000000000.00000000 from server@166.88.142.52 xmt 0xebbe5028.bd445993
May 01 12:55:20 volumio ntpd[1642]: receive: Unexpected origin timestamp 0xebbe5027.c9c73f52 does not match aorg 0000000000.00000000 from server@216.31.17.12 xmt 0xebbe5028.c83dcf52
May 01 12:55:21 volumio dbus-daemon[735]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=0 pid=1788 comm="/usr/lib/chromium/chromium --show-component-extens")
May 01 12:55:21 volumio systemd[1]: Starting Bluetooth service...
May 01 12:55:21 volumio sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 01 12:55:21 volumio sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:21 volumio sudo[1861]: pam_unix(sudo:session): session closed for user root
May 01 12:55:21 volumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 01 12:55:21 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:21 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root
May 01 12:55:21 volumio volumio[980]: verbose: New Socket.io Connection to 192.168.180.214 from 192.168.180.207 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::volumioGetVisibleSources
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
May 01 12:55:21 volumio bluetoothd[1824]: Bluetooth daemon 5.50
May 01 12:55:21 volumio bluetoothd[1824]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
May 01 12:55:21 volumio dbus-daemon[735]: [system] Successfully activated service 'org.bluez'
May 01 12:55:21 volumio systemd[1]: Started Bluetooth service.
May 01 12:55:21 volumio kernel: Bluetooth: Core ver 2.22
May 01 12:55:21 volumio kernel: NET: Registered PF_BLUETOOTH protocol family
May 01 12:55:21 volumio kernel: Bluetooth: HCI device and connection manager initialized
May 01 12:55:21 volumio kernel: Bluetooth: HCI socket layer initialized
May 01 12:55:21 volumio kernel: Bluetooth: L2CAP socket layer initialized
May 01 12:55:21 volumio kernel: Bluetooth: SCO socket layer initialized
May 01 12:55:21 volumio bluetoothd[1824]: Starting SDP server
May 01 12:55:21 volumio bluetoothd[1824]: Excluding (cli) sap
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
May 01 12:55:21 volumio volumio[980]: info: Received Get System Info
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 12:55:21 volumio volumio[980]: info: Discovery: Getting this device information
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::volumioGetState
May 01 12:55:21 volumio volumio[980]: info: CorePlayQueue::getTrack 0
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 12:55:21 volumio volumio-remote-updater[741]: No test mode
May 01 12:55:21 volumio volumio-remote-updater[741]: No alpha test mode
May 01 12:55:21 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
May 01 12:55:21 volumio kernel: Bluetooth: BNEP filters: protocol multicast
May 01 12:55:21 volumio kernel: Bluetooth: BNEP socket layer initialized
May 01 12:55:21 volumio bluetoothd[1824]: Bluetooth management interface 1.22 initialized
May 01 12:55:21 volumio dbus-daemon[735]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.16' (uid=0 pid=1824 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:21 volumio volumio[980]: info: Retrieving Cloud Streaming UI
May 01 12:55:21 volumio volumio[980]: info: Getting Tidal Cloud Configuration
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:21 volumio volumio[980]: info: Getting Qobuz Cloud Configuration
May 01 12:55:21 volumio volumio[980]: info: Asking plugin for UI Config
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:21 volumio volumio[980]: info: Getting Spotify Cloud Configuration
May 01 12:55:21 volumio volumio[980]: info: Asking plugin for UI Config
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:21 volumio volumio[980]: info: Saving Spotify Acccount
May 01 12:55:21 volumio volumio[980]: info: Got Tidal Cloud Configuration
May 01 12:55:21 volumio volumio[980]: info: Got it
May 01 12:55:21 volumio volumio[980]: info: Got it
May 01 12:55:21 volumio volumio[980]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:21 volumio volumio[980]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
May 01 12:55:21 volumio systemd[1]: Starting Hostname Service...
May 01 12:55:21 volumio dbus-daemon[735]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 01 12:55:21 volumio systemd[1]: Started Hostname Service.
May 01 12:55:21 volumio volumio[980]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Slow down CD read speed
\n- Fix NAS Mounting
\n
\nNEW ADDITIONS
\n\n- Revert TIDAL Connect to working version
\n- Fix for USB DAC not recognized after OTA update
\n
\n","title":"Update v3.804","updateavailable":true}
May 01 12:55:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 01 12:55:22 volumio volumio[980]: 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: 4
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::volumioGetVisibleSources
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
May 01 12:55:22 volumio volumio[980]: info: Received Get System Info
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 01 12:55:22 volumio volumio[980]: info: Discovery: Getting this device information
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::volumioGetState
May 01 12:55:22 volumio volumio[980]: info: CorePlayQueue::getTrack 0
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 01 12:55:22 volumio volumio-remote-updater[741]: No test mode
May 01 12:55:22 volumio volumio-remote-updater[741]: No alpha test mode
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:22 volumio volumio[980]: info: Retrieving Cloud Streaming UI
May 01 12:55:22 volumio volumio[980]: info: Getting Tidal Cloud Configuration
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:22 volumio volumio[980]: info: Getting Qobuz Cloud Configuration
May 01 12:55:22 volumio volumio[980]: info: Asking plugin for UI Config
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:22 volumio volumio[980]: info: Getting Spotify Cloud Configuration
May 01 12:55:22 volumio volumio[980]: info: Asking plugin for UI Config
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:22 volumio volumio[980]: info: Saving Spotify Acccount
May 01 12:55:22 volumio volumio[980]: info: Got Tidal Cloud Configuration
May 01 12:55:22 volumio volumio[980]: info: Got it
May 01 12:55:22 volumio volumio[980]: info: Got it
May 01 12:55:22 volumio volumio[980]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin
May 01 12:55:22 volumio volumio[980]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
May 01 12:55:22 volumio volumio[980]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Slow down CD read speed
\n- Fix NAS Mounting
\n
\nNEW ADDITIONS
\n\n- Revert TIDAL Connect to working version
\n- Fix for USB DAC not recognized after OTA update
\n
\n","title":"Update v3.804","updateavailable":true}
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
May 01 12:55:22 volumio volumio[980]: info: Loading i18n strings for locale en
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
May 01 12:55:22 volumio volumio[980]: info: Setting timezone to America/Los_Angeles
May 01 12:55:22 volumio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
May 01 12:55:22 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:22 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root
May 01 12:55:22 volumio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Los_Angeles /etc/localtime
May 01 12:55:22 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:22 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root
May 01 12:55:22 volumio systemd[1]: Starting Daily apt download activities...
May 01 12:55:22 volumio sudo[1974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
May 01 12:55:22 volumio sudo[1974]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:22 volumio sudo[1974]: pam_unix(sudo:session): session closed for user root
May 01 12:55:22 volumio sudo[1977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Los_Angeles
May 01 12:55:22 volumio sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:22 volumio systemd-timedated[1675]: Failed to set time zone: Permission denied
May 01 12:55:22 volumio sudo[1977]: pam_unix(sudo:session): session closed for user root
May 01 12:55:22 volumio volumio[980]: Failed to set time zone: Access denied
May 01 12:55:22 volumio volumio[980]: info: Could not set timezone, retrying
May 01 12:55:22 volumio volumio[980]: Updating browse sources language
May 01 12:55:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 12:55:22 volumio systemd[1]: apt-daily.service: Succeeded.
May 01 12:55:22 volumio systemd[1]: Started Daily apt download activities.
May 01 12:55:22 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
May 01 12:55:22 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
May 01 12:55:22 volumio systemd[1]: Started Daily apt upgrade and clean activities.
May 01 12:55:23 volumio volumio[980]: info: Fetching Streaming Services browse cache
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
May 01 12:55:23 volumio volumio[980]: info: Loading i18n strings for locale en
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
May 01 12:55:23 volumio volumio[980]: info: Setting timezone to UTC
May 01 12:55:23 volumio sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
May 01 12:55:23 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
May 01 12:55:23 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
May 01 12:55:23 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
May 01 12:55:23 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio systemd-timedated[1675]: Failed to set time zone: Permission denied
May 01 12:55:23 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio volumio[980]: Failed to set time zone: Access denied
May 01 12:55:23 volumio volumio[980]: info: Could not set timezone, retrying
May 01 12:55:23 volumio volumio[980]: Updating browse sources language
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
May 01 12:55:23 volumio volumio[980]: info: Loading i18n strings for locale en
May 01 12:55:23 volumio volumio[980]: Updating browse sources language
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
May 01 12:55:23 volumio volumio[980]: info: Setting timezone to America/Los_Angeles
May 01 12:55:23 volumio sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
May 01 12:55:23 volumio sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2103]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio sudo[2106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Los_Angeles /etc/localtime
May 01 12:55:23 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
May 01 12:55:23 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Los_Angeles
May 01 12:55:23 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio systemd-timedated[1675]: Failed to set time zone: Permission denied
May 01 12:55:23 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio volumio[980]: Failed to set time zone: Access denied
May 01 12:55:23 volumio volumio[980]: info: Could not set timezone, retrying
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
May 01 12:55:23 volumio sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
May 01 12:55:23 volumio sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2115]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio volumio[980]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
May 01 12:55:23 volumio volumio[980]: wlan0 Interface doesn't support scanning.
May 01 12:55:23 volumio volumio[980]: info: Cannot use regular scanning, forcing with ap-force
May 01 12:55:23 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
May 01 12:55:23 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio volumio[980]: command failed: No such device (-19)
May 01 12:55:23 volumio volumio[980]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
May 01 12:55:23 volumio volumio[980]: command failed: No such device (-19)
May 01 12:55:23 volumio sudo[2125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Los_Angeles
May 01 12:55:23 volumio sudo[2125]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:23 volumio sudo[2125]: pam_unix(sudo:session): session closed for user root
May 01 12:55:23 volumio volumio[980]: info: Fetching Streaming Services browse cache
May 01 12:55:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 01 12:55:23 volumio volumio[980]: info: MyVolumio login type: Token
May 01 12:55:23 volumio volumio[980]: info: Fetching Streaming Services browse cache
May 01 12:55:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 12:55:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 01 12:55:24 volumio volumio[980]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 01 12:55:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 01 12:55:24 volumio volumio[980]: info: MyVolumio login type: Token
May 01 12:55:24 volumio sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
May 01 12:55:24 volumio sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 01 12:55:24 volumio systemd-timedated[1675]: Failed to set time zone: Permission denied
May 01 12:55:24 volumio sudo[2128]: pam_unix(sudo:session): session closed for user root
May 01 12:55:24 volumio volumio[980]: Failed to set time zone: Access denied
May 01 12:55:24 volumio volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 01 12:55:24 volumio volumio[980]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC'
May 01 12:55:24 volumio volumio[980]: Failed to set time zone: Access denied
May 01 12:55:24 volumio volumio[980]: at checkExecSyncError (child_process.js:790:11)
May 01 12:55:24 volumio volumio[980]: at execSync (child_process.js:863:15)
May 01 12:55:24 volumio volumio[980]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9)
May 01 12:55:24 volumio volumio[980]: at listOnTimeout (internal/timers.js:557:17)
May 01 12:55:24 volumio volumio[980]: at processTimers (internal/timers.js:500:7) {
May 01 12:55:24 volumio volumio[980]: status: 1,
May 01 12:55:24 volumio volumio[980]: signal: null,
May 01 12:55:24 volumio volumio[980]: output: [ null, '', 'Failed to set time zone: Access denied\n' ],
May 01 12:55:24 volumio volumio[980]: pid: 2127,
May 01 12:55:24 volumio volumio[980]: stdout: '',
May 01 12:55:24 volumio volumio[980]: stderr: 'Failed to set time zone: Access denied\n'
May 01 12:55:24 volumio volumio[980]: }
May 01 12:55:24 volumio volumio[980]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 01 12:55:24 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 12:54
May 01 12:55:24 volumio sudo[2141]: 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="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST"
VOLUMIO_VERSION="3.799"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"