-- Logs begin at Sun 2023-01-01 00:01:29 UTC, end at Sat 2026-01-10 21:34:07 UTC. --
Jan 10 21:33:39 volumio systemd-timedated[1967]: Changed local time to Sat Jan 10 21:33:39 2026
Jan 10 21:33:39 volumio sudo[1965]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:39 volumio systemd[1]: Starting Daily apt download activities...
Jan 10 21:33:39 volumio volumio-time-update[840]: volumio-time-update-util: System time updated successfully.
Jan 10 21:33:39 volumio systemd[1]: Starting Daily man-db regeneration...
Jan 10 21:33:39 volumio systemd[1]: Started Volumio Time Update Utility.
Jan 10 21:33:39 volumio systemd[1]: Reached target Multi-User System.
Jan 10 21:33:39 volumio systemd[1]: Reached target Graphical Interface.
Jan 10 21:33:39 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jan 10 21:33:39 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jan 10 21:33:39 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Jan 10 21:33:39 volumio systemd[1]: man-db.service: Succeeded.
Jan 10 21:33:39 volumio systemd[1]: Started Daily man-db regeneration.
Jan 10 21:33:39 volumio systemd[1]: apt-daily.service: Succeeded.
Jan 10 21:33:39 volumio systemd[1]: Started Daily apt download activities.
Jan 10 21:33:39 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
Jan 10 21:33:39 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
Jan 10 21:33:39 volumio systemd[1]: Started Daily apt upgrade and clean activities.
Jan 10 21:33:39 volumio systemd[1]: Startup finished in 14.385s (kernel) + 18.501s (userspace) = 32.886s.
Jan 10 21:33:39 volumio ntpd[1943]: Soliciting pool server 195.90.182.235
Jan 10 21:33:39 volumio volumio[1165]: info: Discovery: adding b8633b65-d200-4886-9f7f-799022cba706
Jan 10 21:33:39 volumio volumio[1165]: info: Discovery: Found device Volumio
Jan 10 21:33:39 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:33:39 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:33:39 volumio volumio[1165]: info: Discovery: this is already registered, b8633b65-d200-4886-9f7f-799022cba706
Jan 10 21:33:39 volumio volumio[1165]: info: Discovery: Found device Volumio
Jan 10 21:33:39 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:33:39 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:33:39 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.0.27 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 3
Jan 10 21:33:39 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:33:39 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:33:39 volumio volumio[1165]: info: CoreCommandRouter::volumioGetQueue
Jan 10 21:33:39 volumio volumio[1165]: info: CoreStateMachine::getQueue
Jan 10 21:33:39 volumio volumio[1165]: info: CorePlayQueue::getQueue
Jan 10 21:33:39 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Jan 10 21:33:39 volumio volumio[1165]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object]
Jan 10 21:33:39 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction
Jan 10 21:33:39 volumio volumio[1165]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs
Jan 10 21:33:39 volumio volumio[1165]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object]
Jan 10 21:33:39 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection
Jan 10 21:33:40 volumio ntpd[1943]: Soliciting pool server 83.237.235.30
Jan 10 21:33:41 volumio ntpd[1943]: Soliciting pool server 109.195.84.246
Jan 10 21:33:41 volumio ntpd[1943]: Soliciting pool server 92.241.12.152
Jan 10 21:33:41 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection
Jan 10 21:33:41 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo
Jan 10 21:33:41 volumio dhcpcd[1015]: wlan0: probing for an IPv4LL address
Jan 10 21:33:41 volumio sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 10 21:33:41 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:41 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:41 volumio volumio[1165]: info: Volumio Network Manager: Network status updated: 1
Jan 10 21:33:41 volumio sudo[2106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 10 21:33:41 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:41 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:41 volumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 10 21:33:41 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:41 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:41 volumio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jan 10 21:33:41 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jan 10 21:33:41 volumio volumio[1165]: info: Adding plugin bluetooth to MyMusic Plugins
Jan 10 21:33:41 volumio volumio[1165]: info: Adding plugin multiroom to MyMusic Plugins
Jan 10 21:33:41 volumio volumio[1165]: info: Adding plugin metavolumio to MyMusic Plugins
Jan 10 21:33:41 volumio volumio[1165]: info: Adding plugin cd_controller to MyMusic Plugins
Jan 10 21:33:41 volumio volumio[1165]: info: Adding plugin smart_inputs to MyMusic Plugins
Jan 10 21:33:41 volumio volumio[1165]: info: Adding plugin tidalconnect to MyMusic Plugins
Jan 10 21:33:41 volumio volumio[1165]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jan 10 21:33:41 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Jan 10 21:33:41 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:42 volumio ntpd[1943]: Soliciting pool server 85.21.78.91
Jan 10 21:33:42 volumio ntpd[1943]: Soliciting pool server 89.109.251.23
Jan 10 21:33:42 volumio volumio[1165]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jan 10 21:33:42 volumio volumio[1165]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jan 10 21:33:42 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 10 21:33:42 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 10 21:33:42 volumio volumio[1165]: info: Starting MyVolumio Remote Streaming Endpoints
Jan 10 21:33:42 volumio volumio[1165]: info: MyVolumio not started
Jan 10 21:33:42 volumio volumio[1165]: info: Initializing device activation check
Jan 10 21:33:42 volumio volumio[1165]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jan 10 21:33:42 volumio volumio[1165]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jan 10 21:33:42 volumio ntpd[1943]: Soliciting pool server 89.109.251.24
Jan 10 21:33:42 volumio volumio[1165]: info: Starting Streaming Service Transparent Proxy
Jan 10 21:33:42 volumio volumio[1165]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jan 10 21:33:42 volumio volumio[1165]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jan 10 21:33:42 volumio volumio[1165]: info: Streaming services startup
Jan 10 21:33:42 volumio volumio[1165]: info: Starting Streaming Daemon
Jan 10 21:33:42 volumio sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 10 21:33:42 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:42 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:42 volumio volumio[1165]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jan 10 21:33:42 volumio volumio[1165]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=dfe07e4295805c35ecc28b3606e73488" http://updates.volumio.org/downloader-v1/track-device
Jan 10 21:33:42 volumio volumio[1165]: % Total % Received % Xferd Average Speed Time Time Time Current
Jan 10 21:33:42 volumio volumio[1165]: Dload Upload Total Spent Left Speed
Jan 10 21:33:42 volumio volumio[1165]: [132B blob data]
Jan 10 21:33:42 volumio volumio[1165]: retrying in 5 seconds, trial 0
Jan 10 21:33:42 volumio volumio[1165]: info: Volumio Calling Home
Jan 10 21:33:42 volumio volumio[1165]: info: CALLMETHOD: system_controller system setTestSystem true
Jan 10 21:33:42 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem
Jan 10 21:33:42 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates
Jan 10 21:33:42 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 10 21:33:42 volumio volumio[1165]: error: Cannot start Volumio Streaming Daemon
Jan 10 21:33:42 volumio volumio[1165]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 10 21:33:42 volumio volumio[1165]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 10 21:33:42 volumio volumio[1165]: info: Upmpdcli Daemon Started
Jan 10 21:33:42 volumio volumio[1165]: info: Device is now in test mode
Jan 10 21:33:43 volumio volumio[2114]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jan 10 21:33:43 volumio volumio[1165]: STREAMING PROXY: Starting server on port 3245
Jan 10 21:33:43 volumio volumio[1165]: Node JS runtime: 14
Jan 10 21:33:43 volumio ntpd[1943]: Soliciting pool server 195.54.177.84
Jan 10 21:33:43 volumio ntpd[1943]: Soliciting pool server 79.111.119.96
Jan 10 21:33:43 volumio ntpd[1943]: Soliciting pool server 45.141.102.99
Jan 10 21:33:43 volumio volumio[2114]: ...................................................................++++
Jan 10 21:33:43 volumio volumio[1165]: info: CALLMETHOD: system_controller system setTestSystem true
Jan 10 21:33:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem
Jan 10 21:33:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates
Jan 10 21:33:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 10 21:33:43 volumio volumio[1165]: info: Device is now in test mode
Jan 10 21:33:43 volumio volumio-remote-updater[832]: Test mode enabled
Jan 10 21:33:43 volumio volumio-remote-updater[832]: No alpha test mode
Jan 10 21:33:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 10 21:33:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 10 21:33:43 volumio volumio[2114]: ......................................................................++++
Jan 10 21:33:43 volumio volumio[2114]: e is 65537 (0x010001)
Jan 10 21:33:43 volumio volumio[2114]: writing RSA key
Jan 10 21:33:43 volumio volumio-remote-updater[832]: Test mode enabled
Jan 10 21:33:43 volumio volumio-remote-updater[832]: No alpha test mode
Jan 10 21:33:43 volumio volumio[1165]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for Streaming Services playback in case of network interruptions
\n
\nNEW ADDITIONS
\n\n- BLE-based onboarding setup
\n
\n","title":"Update v3.887","updateavailable":true}
Jan 10 21:33:43 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 10 21:33:43 volumio volumio[1165]: info: Volumio called home
Jan 10 21:33:44 volumio ntpd[1943]: Soliciting pool server 91.244.115.121
Jan 10 21:33:44 volumio ntpd[1943]: Soliciting pool server 217.170.87.229
Jan 10 21:33:44 volumio volumio[1165]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for Streaming Services playback in case of network interruptions
\n
\nNEW ADDITIONS
\n\n- BLE-based onboarding setup
\n
\n","title":"Update v3.887","updateavailable":true}
Jan 10 21:33:44 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 10 21:33:44 volumio volumio[1165]: info: CALLMETHOD: system_controller system setTestPlugins true
Jan 10 21:33:44 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins
Jan 10 21:33:44 volumio volumio[1165]: info: Plugins store is now in test mode
Jan 10 21:33:45 volumio ntpd[1943]: Soliciting pool server 51.250.68.198
Jan 10 21:33:45 volumio ntpd[1943]: Soliciting pool server 2a0d:8480:0:672::123
Jan 10 21:33:46 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
Jan 10 21:33:46 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
Jan 10 21:33:46 volumio volumio[1165]: info: Refreshing Cached IP Addresses
Jan 10 21:33:46 volumio sudo[2190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Jan 10 21:33:46 volumio sudo[2190]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:46 volumio sudo[2190]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:46 volumio sudo[2193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 10 21:33:46 volumio sudo[2193]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:46 volumio sudo[2193]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:46 volumio systemd[1]: upmpdcli.service: Succeeded.
Jan 10 21:33:46 volumio sudo[2196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 10 21:33:46 volumio sudo[2196]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:46 volumio sudo[2196]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:46 volumio dhcpcd[1015]: wlan0: using IPv4LL address 169.254.27.214
Jan 10 21:33:46 volumio avahi-daemon[836]: Registering new address record for 169.254.27.214 on wlan0.IPv4.
Jan 10 21:33:46 volumio dhcpcd[1015]: wlan0: adding route to 169.254.0.0/16
Jan 10 21:33:46 volumio kernel: ieee80211 phy0: brcmf_inetaddr_changed: fail to get arp ip table err:-52
Jan 10 21:33:48 volumio ntpd[1943]: Listen normally on 5 wlan0 169.254.27.214:123
Jan 10 21:33:48 volumio ntpd[1943]: new interface(s) found: waking up resolver
Jan 10 21:33:49 volumio ntpd[1943]: receive: Unexpected origin timestamp 0xed0d443c.24d9be36 does not match aorg 0000000000.00000000 from server@91.244.115.121 xmt 0xed0d443d.08c21e1d
Jan 10 21:33:51 volumio volumio[1165]: 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
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jan 10 21:33:51 volumio volumio[1165]: info: Received Get System Info
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 10 21:33:51 volumio volumio[1165]: info: Discovery: Getting this device information
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:33:51 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:33:51 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 10 21:33:51 volumio volumio-remote-updater[832]: Test mode enabled
Jan 10 21:33:51 volumio volumio-remote-updater[832]: No alpha test mode
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:33:52 volumio volumio[1165]: info: Retrieving Cloud Streaming UI
Jan 10 21:33:52 volumio volumio[1165]: info: Getting Tidal Cloud Configuration
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:33:52 volumio volumio[1165]: info: Getting Qobuz Cloud Configuration
Jan 10 21:33:52 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:33:52 volumio volumio[1165]: info: Getting Spotify Cloud Configuration
Jan 10 21:33:52 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:33:52 volumio volumio[1165]: info: Saving Spotify Acccount
Jan 10 21:33:52 volumio volumio[1165]: info: Got Tidal Cloud Configuration
Jan 10 21:33:52 volumio volumio[1165]: info: Got it
Jan 10 21:33:52 volumio volumio[1165]: info: Got it
Jan 10 21:33:52 volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:33:52 volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jan 10 21:33:52 volumio volumio[1165]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for Streaming Services playback in case of network interruptions
\n
\nNEW ADDITIONS
\n\n- BLE-based onboarding setup
\n
\n","title":"Update v3.887","updateavailable":true}
Jan 10 21:33:52 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 10 21:33:53 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jan 10 21:33:53 volumio volumio[1165]: info: Loading i18n strings for locale en
Jan 10 21:33:53 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jan 10 21:33:53 volumio volumio[1165]: info: Setting timezone to UTC
Jan 10 21:33:53 volumio sudo[2343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jan 10 21:33:53 volumio sudo[2343]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:53 volumio sudo[2343]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:53 volumio sudo[2346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Jan 10 21:33:53 volumio sudo[2346]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:53 volumio sudo[2346]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:53 volumio sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jan 10 21:33:53 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:53 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:53 volumio sudo[2352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jan 10 21:33:53 volumio sudo[2352]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:53 volumio systemd-timedated[1967]: Failed to set time zone: Permission denied
Jan 10 21:33:53 volumio sudo[2352]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:53 volumio volumio[1165]: Failed to set time zone: Access denied
Jan 10 21:33:53 volumio volumio[1165]: info: Could not set timezone, retrying
Jan 10 21:33:53 volumio volumio[1165]: Updating browse sources language
Jan 10 21:33:53 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 10 21:33:53 volumio volumio[1165]: info: Fetching Streaming Services browse cache
Jan 10 21:33:53 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 10 21:33:54 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Jan 10 21:33:54 volumio sudo[2355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jan 10 21:33:54 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:54 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:54 volumio volumio[1165]: info: CALLMETHOD: system_controller system enableSSH true
Jan 10 21:33:54 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH
Jan 10 21:33:54 volumio sudo[2358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service
Jan 10 21:33:54 volumio sudo[2358]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:54 volumio sudo[2358]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:54 volumio sudo[2360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service
Jan 10 21:33:54 volumio sudo[2360]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:54 volumio systemd[1]: Reloading.
Jan 10 21:33:54 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Jan 10 21:33:54 volumio systemd[1]: Reloading.
Jan 10 21:33:55 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Jan 10 21:33:55 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Jan 10 21:33:55 volumio systemd[1]: Reloading.
Jan 10 21:33:55 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Jan 10 21:33:55 volumio sudo[2360]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:55 volumio volumio[1165]: info: enable SSH service success
Jan 10 21:33:57 volumio sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 10 21:33:57 volumio sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:57 volumio sudo[2415]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:57 volumio sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 10 21:33:57 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:57 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:57 volumio sudo[2421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jan 10 21:33:57 volumio sudo[2421]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:33:57 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Jan 10 21:33:57 volumio sudo[2421]: pam_unix(sudo:session): session closed for user root
Jan 10 21:33:57 volumio volumio[1165]: info: Upmpdcli Daemon Started
Jan 10 21:33:57 volumio volumio[2423]: writing RSA key
Jan 10 21:33:58 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 10 21:33:58 volumio volumio[1165]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Jan 10 21:33:58 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:33:58 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 10 21:34:03 volumio volumio-remote-updater[832]: Test mode enabled
Jan 10 21:34:03 volumio volumio-remote-updater[832]: No alpha test mode
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 10 21:34:03 volumio volumio[1165]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jan 10 21:34:03 volumio volumio[1165]: info: Completed starting MyVolumio Plugin
Jan 10 21:34:03 volumio volumio[1165]: [Metrics] CommandRouter: 32s 531.25ms
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::volumiosetStartupVolume
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::Close All Modals sent
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Retrieving Cloud Streaming UI
Jan 10 21:34:03 volumio volumio[1165]: info: Getting Tidal Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Getting Qobuz Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Getting Spotify Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Saving Spotify Acccount
Jan 10 21:34:03 volumio volumio[1165]: info: Got Tidal Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: Got it
Jan 10 21:34:03 volumio volumio[1165]: info: Got it
Jan 10 21:34:03 volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Retrieving Cloud Streaming UI
Jan 10 21:34:03 volumio volumio[1165]: info: Getting Tidal Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Getting Qobuz Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Getting Spotify Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: info: Saving Spotify Acccount
Jan 10 21:34:03 volumio volumio[1165]: info: Got Tidal Cloud Configuration
Jan 10 21:34:03 volumio volumio[1165]: info: Got it
Jan 10 21:34:03 volumio volumio[1165]: info: Got it
Jan 10 21:34:03 volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jan 10 21:34:03 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:03 volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jan 10 21:34:03 volumio kernel: snd_hda_intel 0000:00:03.0: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj.
Jan 10 21:34:04 volumio sudo[2644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 10 21:34:04 volumio sudo[2644]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:04 volumio sudo[2644]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:04 volumio sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 10 21:34:04 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:04 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:04 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.0.27 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 5
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jan 10 21:34:04 volumio volumio[1165]: info: Received Get System Info
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 10 21:34:04 volumio volumio[1165]: info: Discovery: Getting this device information
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:34:04 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:04 volumio volumio[1165]: info: Retrieving Cloud Streaming UI
Jan 10 21:34:04 volumio volumio[1165]: info: Getting Tidal Cloud Configuration
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:04 volumio volumio[1165]: info: Getting Qobuz Cloud Configuration
Jan 10 21:34:04 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:04 volumio volumio[1165]: info: Getting Spotify Cloud Configuration
Jan 10 21:34:04 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:04 volumio volumio[1165]: info: Saving Spotify Acccount
Jan 10 21:34:04 volumio volumio[1165]: info: Got Tidal Cloud Configuration
Jan 10 21:34:04 volumio volumio[1165]: info: Got it
Jan 10 21:34:04 volumio volumio[1165]: info: Got it
Jan 10 21:34:04 volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:04 volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jan 10 21:34:04 volumio volumio-remote-updater[832]: Test mode enabled
Jan 10 21:34:04 volumio volumio-remote-updater[832]: No alpha test mode
Jan 10 21:34:04 volumio volumio[1165]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for Streaming Services playback in case of network interruptions
\n
\nNEW ADDITIONS
\n\n- BLE-based onboarding setup
\n
\n","title":"Update v3.887","updateavailable":true}
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jan 10 21:34:04 volumio volumio[1165]: info: Version has changed, forcing UI Reload
Jan 10 21:34:04 volumio volumio[1165]: info: CoreCommandRouter::Reload Ui
Jan 10 21:34:05 volumio sudo[2656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 10 21:34:05 volumio sudo[2656]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:05 volumio sudo[2656]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:05 volumio sudo[2658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 10 21:34:05 volumio sudo[2658]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:05 volumio sudo[2658]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:05 volumio volumio[1165]: verbose: New Socket.io Connection to 192.168.0.27 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 4
Jan 10 21:34:05 volumio volumio[1165]: 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: 5
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jan 10 21:34:05 volumio volumio[1165]: info: Received Get System Info
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 10 21:34:05 volumio volumio[1165]: info: Discovery: Getting this device information
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:34:05 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jan 10 21:34:05 volumio volumio[1165]: info: Received Get System Info
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 10 21:34:05 volumio volumio[1165]: info: Discovery: Getting this device information
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::volumioGetState
Jan 10 21:34:05 volumio volumio[1165]: info: CorePlayQueue::getTrack 0
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Retrieving Cloud Streaming UI
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Retrieving Cloud Streaming UI
Jan 10 21:34:05 volumio volumio[1165]: info: Getting Tidal Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Getting Qobuz Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Getting Spotify Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Saving Spotify Acccount
Jan 10 21:34:05 volumio volumio[1165]: info: Got Tidal Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: Got it
Jan 10 21:34:05 volumio volumio[1165]: info: Got it
Jan 10 21:34:05 volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jan 10 21:34:05 volumio volumio[1165]: info: Getting Tidal Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Getting Qobuz Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Getting Spotify Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: Asking plugin for UI Config
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: info: Saving Spotify Acccount
Jan 10 21:34:05 volumio volumio[1165]: info: Got Tidal Cloud Configuration
Jan 10 21:34:05 volumio volumio[1165]: info: Got it
Jan 10 21:34:05 volumio volumio[1165]: info: Got it
Jan 10 21:34:05 volumio volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jan 10 21:34:05 volumio volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin
Jan 10 21:34:05 volumio volumio[1165]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jan 10 21:34:05 volumio volumio-remote-updater[832]: Test mode enabled
Jan 10 21:34:05 volumio volumio-remote-updater[832]: No alpha test mode
Jan 10 21:34:06 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jan 10 21:34:06 volumio volumio[1165]: info: Loading i18n strings for locale ru
Jan 10 21:34:06 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jan 10 21:34:06 volumio volumio[1165]: info: Setting timezone to Europe/Moscow
Jan 10 21:34:06 volumio sudo[2669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jan 10 21:34:06 volumio sudo[2669]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio sudo[2669]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Moscow /etc/localtime
Jan 10 21:34:06 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio sudo[2672]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio sudo[2675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jan 10 21:34:06 volumio sudo[2675]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio sudo[2675]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio sudo[2678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Moscow
Jan 10 21:34:06 volumio sudo[2678]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio systemd-timedated[1967]: Failed to set time zone: Permission denied
Jan 10 21:34:06 volumio sudo[2678]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio volumio[1165]: Failed to set time zone: Access denied
Jan 10 21:34:06 volumio volumio[1165]: info: Could not set timezone, retrying
Jan 10 21:34:06 volumio volumio[1165]: Updating browse sources language
Jan 10 21:34:06 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 10 21:34:06 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jan 10 21:34:06 volumio volumio[1165]: info: Loading i18n strings for locale en
Jan 10 21:34:06 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jan 10 21:34:06 volumio volumio[1165]: info: Setting timezone to UTC
Jan 10 21:34:06 volumio sudo[2681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jan 10 21:34:06 volumio sudo[2681]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio sudo[2681]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio sudo[2684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Jan 10 21:34:06 volumio sudo[2684]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio sudo[2684]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jan 10 21:34:06 volumio sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio sudo[2687]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio sudo[2690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jan 10 21:34:06 volumio sudo[2690]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:06 volumio systemd-timedated[1967]: Failed to set time zone: Permission denied
Jan 10 21:34:06 volumio sudo[2690]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:06 volumio volumio[1165]: Failed to set time zone: Access denied
Jan 10 21:34:06 volumio volumio[1165]: info: Could not set timezone, retrying
Jan 10 21:34:06 volumio volumio[1165]: Updating browse sources language
Jan 10 21:34:06 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 10 21:34:06 volumio volumio[1165]: info: Fetching Streaming Services browse cache
Jan 10 21:34:06 volumio volumio-remote-updater[832]: Test mode enabled
Jan 10 21:34:06 volumio volumio-remote-updater[832]: No alpha test mode
Jan 10 21:34:06 volumio volumio[1165]: info: Fetching Streaming Services browse cache
Jan 10 21:34:07 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 10 21:34:07 volumio volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 10 21:34:07 volumio sudo[2697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Moscow
Jan 10 21:34:07 volumio sudo[2697]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 10 21:34:07 volumio systemd-timedated[1967]: Failed to set time zone: Permission denied
Jan 10 21:34:07 volumio sudo[2697]: pam_unix(sudo:session): session closed for user root
Jan 10 21:34:07 volumio volumio[1165]: Failed to set time zone: Access denied
Jan 10 21:34:07 volumio volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 10 21:34:07 volumio volumio[1165]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/Moscow'
Jan 10 21:34:07 volumio volumio[1165]: Failed to set time zone: Access denied
Jan 10 21:34:07 volumio volumio[1165]: at checkExecSyncError (child_process.js:790:11)
Jan 10 21:34:07 volumio volumio[1165]: at execSync (child_process.js:863:15)
Jan 10 21:34:07 volumio volumio[1165]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9)
Jan 10 21:34:07 volumio volumio[1165]: at listOnTimeout (internal/timers.js:557:17)
Jan 10 21:34:07 volumio volumio[1165]: at processTimers (internal/timers.js:500:7) {
Jan 10 21:34:07 volumio volumio[1165]: status: 1,
Jan 10 21:34:07 volumio volumio[1165]: signal: null,
Jan 10 21:34:07 volumio volumio[1165]: output: [ null, '', 'Failed to set time zone: Access denied\n' ],
Jan 10 21:34:07 volumio volumio[1165]: pid: 2696,
Jan 10 21:34:07 volumio volumio[1165]: stdout: '',
Jan 10 21:34:07 volumio volumio[1165]: stderr: 'Failed to set time zone: Access denied\n'
Jan 10 21:34:07 volumio volumio[1165]: }
Jan 10 21:34:07 volumio volumio[1165]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 10 21:34:07 volumio sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 21:33
Jan 10 21:34:07 volumio sudo[2708]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET"
VOLUMIO_VERSION="3.779"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"