-- Logs begin at Fri 2024-10-18 12:01:57 UTC, end at Fri 2024-10-18 12:06:09 UTC. -- Oct 18 12:05:37 volumio ntpd[1551]: receive: Unexpected origin timestamp 0xeabccd31.a9673e90 does not match aorg 0000000000.00000000 from server@202.80.227.130 xmt 0xeabccd91.1f9c5000 Oct 18 12:05:37 volumio ntpd[1551]: receive: Unexpected origin timestamp 0xeabccd31.a96ba56b does not match aorg 0000000000.00000000 from server@202.12.97.45 xmt 0xeabccd91.2104f9a9 Oct 18 12:05:39 volumio dbus-daemon[898]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 18 12:05:39 volumio systemd[1]: Started Time & Date Service. Oct 18 12:05:39 volumio sudo[1820]: pam_unix(sudo:session): session closed for user root Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:39 volumio volumio[1174]: info: Discovery: this is already registered, 102274b5-4dbe-448e-8d80-de5359174f5b Oct 18 12:05:39 volumio volumio[1174]: info: Discovery: Found device Volumio Oct 18 12:05:39 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 18 12:05:39 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 18 12:05:40 volumio sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 12:05:40 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 12:05:40 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:05:40 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Oct 18 12:05:40 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:40 volumio sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 12:05:40 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:05:40 volumio sudo[1826]: pam_unix(sudo:session): session closed for user root Oct 18 12:05:40 volumio volumio[1174]: Updating browse sources language Oct 18 12:05:40 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 12:05:40 volumio sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 18 12:05:40 volumio sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:05:40 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 18 12:05:40 volumio sudo[1830]: pam_unix(sudo:session): session closed for user root Oct 18 12:05:40 volumio volumio[1174]: info: Upmpdcli Daemon Started Oct 18 12:05:40 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:40 volumio volumio[1832]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 18 12:05:40 volumio volumio[1832]: ...++++ Oct 18 12:05:41 volumio volumio[1832]: ....................++++ Oct 18 12:05:41 volumio volumio[1832]: e is 65537 (0x010001) Oct 18 12:05:41 volumio volumio[1832]: writing RSA key Oct 18 12:05:41 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 12:05:42 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 18 12:05:42 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 18 12:05:42 volumio volumio[1174]: info: Adding plugin bluetooth to MyMusic Plugins Oct 18 12:05:42 volumio volumio[1174]: info: Adding plugin multiroom to MyMusic Plugins Oct 18 12:05:42 volumio volumio[1174]: info: Adding plugin metavolumio to MyMusic Plugins Oct 18 12:05:42 volumio volumio[1174]: info: Adding plugin cd_controller to MyMusic Plugins Oct 18 12:05:43 volumio volumio[1174]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 18 12:05:43 volumio volumio[1174]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 18 12:05:43 volumio volumio[1174]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 18 12:05:44 volumio volumio[1174]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 18 12:05:44 volumio volumio[1174]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 18 12:05:44 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 12:05:44 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 12:05:44 volumio volumio[1174]: info: Starting MyVolumio Remote Streaming Endpoints Oct 18 12:05:44 volumio volumio[1174]: info: MyVolumio not started Oct 18 12:05:44 volumio volumio[1174]: info: Initializing device activation check Oct 18 12:05:44 volumio volumio[1174]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 18 12:05:44 volumio volumio[1174]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 18 12:05:45 volumio volumio[1174]: info: Starting Streaming Service Transparent Proxy Oct 18 12:05:45 volumio volumio[1174]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 18 12:05:45 volumio volumio[1174]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 18 12:05:45 volumio volumio[1174]: info: Streaming services startup Oct 18 12:05:45 volumio volumio[1174]: info: Starting Streaming Daemon Oct 18 12:05:45 volumio sudo[2048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 12:05:45 volumio sudo[2048]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:05:45 volumio sudo[2048]: pam_unix(sudo:session): session closed for user root Oct 18 12:05:45 volumio volumio[1174]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 18 12:05:45 volumio volumio[1174]: error: Cannot start Volumio Streaming Daemon Oct 18 12:05:45 volumio volumio[1174]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 12:05:45 volumio volumio[1174]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 12:05:45 volumio volumio[1174]: STREAMING PROXY: Starting server on port 3245 Oct 18 12:05:45 volumio volumio[1174]: Node JS runtime: 14 Oct 18 12:05:47 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 18 12:05:52 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 12:05:52 volumio volumio[1174]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 18 12:05:52 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 18 12:05:52 volumio volumio[1174]: info: Received Get System Version Oct 18 12:05:52 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 12:05:52 volumio volumio[1174]: info: Received Get System Info Oct 18 12:05:52 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 12:05:52 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 12:05:52 volumio volumio[1174]: info: Discovery: Getting this device information Oct 18 12:05:52 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 18 12:05:52 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 18 12:05:52 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 12:05:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 12:05:59 volumio volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 18 12:05:59 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 18 12:05:59 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 18 12:06:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 12:06:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 12:06:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 12:06:00 volumio volumio-remote-updater[893]: No test mode Oct 18 12:06:00 volumio volumio-remote-updater[893]: No alpha test mode Oct 18 12:06:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 12:06:01 volumio volumio[1174]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 12:06:01 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 12:06:04 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 12:06:04 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 12:06:04 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 18 12:06:04 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 18 12:06:04 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 18 12:06:04 volumio volumio[1174]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 12:06:04 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 12:06:05 volumio volumio[1174]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 18 12:06:05 volumio volumio[1174]: info: Completed starting MyVolumio Plugin Oct 18 12:06:05 volumio volumio[1174]: info: BOOT COMPLETED Oct 18 12:06:05 volumio volumio[1174]: [Metrics] CommandRouter: 40s 742.36ms Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::volumiosetStartupVolume Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::Close All Modals sent Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::Close All Modals sent Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Retrieving Cloud Streaming UI Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Retrieving Cloud Streaming UI Oct 18 12:06:05 volumio volumio[1174]: info: Getting Tidal Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Getting Qobuz Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Getting Spotify Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Saving Spotify Acccount Oct 18 12:06:05 volumio volumio[1174]: info: Got Tidal Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: Got it Oct 18 12:06:05 volumio volumio[1174]: info: Got it Oct 18 12:06:05 volumio volumio[1174]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 12:06:05 volumio volumio[1174]: info: Getting Tidal Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Getting Qobuz Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Getting Spotify Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: info: Saving Spotify Acccount Oct 18 12:06:05 volumio volumio[1174]: info: Got Tidal Cloud Configuration Oct 18 12:06:05 volumio volumio[1174]: info: Got it Oct 18 12:06:05 volumio volumio[1174]: info: Got it Oct 18 12:06:05 volumio volumio[1174]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 18 12:06:05 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:05 volumio volumio[1174]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 18 12:06:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 18 12:06:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 12:06:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 18 12:06:06 volumio volumio[1174]: info: Version has changed, forcing UI Reload Oct 18 12:06:06 volumio volumio[1174]: info: CoreCommandRouter::Reload Ui Oct 18 12:06:07 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 12:06:07 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:07 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:07 volumio sudo[2067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 12:06:07 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:07 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:07 volumio volumio[1174]: 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 Oct 18 12:06:07 volumio volumio[1174]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.8 UA: Mozilla/5.0 (Linux; Android 14; ALI-NX1 Build/HONORALI-N21; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 18 12:06:07 volumio volumio[1174]: info: Received Get System Info Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 12:06:07 volumio volumio[1174]: info: Discovery: Getting this device information Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 18 12:06:07 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 12:06:07 volumio volumio-remote-updater[893]: No test mode Oct 18 12:06:07 volumio volumio-remote-updater[893]: No alpha test mode Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Retrieving Cloud Streaming UI Oct 18 12:06:07 volumio volumio[1174]: info: Getting Tidal Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Getting Qobuz Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Getting Spotify Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Saving Spotify Acccount Oct 18 12:06:07 volumio volumio[1174]: info: Got Tidal Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: Got it Oct 18 12:06:07 volumio volumio[1174]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 18 12:06:07 volumio volumio[1174]: info: Received Get System Info Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 12:06:07 volumio volumio[1174]: info: Discovery: Getting this device information Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 18 12:06:07 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Retrieving Cloud Streaming UI Oct 18 12:06:07 volumio volumio[1174]: info: Getting Tidal Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Getting Qobuz Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Getting Spotify Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: Asking plugin for UI Config Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: info: Saving Spotify Acccount Oct 18 12:06:07 volumio volumio[1174]: info: Got Tidal Cloud Configuration Oct 18 12:06:07 volumio volumio[1174]: info: Got it Oct 18 12:06:07 volumio volumio[1174]: info: Got it Oct 18 12:06:07 volumio volumio[1174]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 12:06:07 volumio volumio[1174]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 18 12:06:07 volumio volumio-remote-updater[893]: No test mode Oct 18 12:06:07 volumio volumio-remote-updater[893]: No alpha test mode Oct 18 12:06:07 volumio volumio[1174]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 12:06:07 volumio volumio[1174]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 12:06:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 12:06:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 18 12:06:08 volumio volumio[1174]: info: Loading i18n strings for locale th Oct 18 12:06:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 18 12:06:08 volumio volumio[1174]: info: Setting timezone to Asia/Bangkok Oct 18 12:06:08 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 18 12:06:08 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio sudo[2086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Oct 18 12:06:08 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio sudo[2086]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 18 12:06:08 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Oct 18 12:06:08 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio systemd-timedated[1822]: Failed to set time zone: Permission denied Oct 18 12:06:08 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio volumio[1174]: Failed to set time zone: Access denied Oct 18 12:06:08 volumio volumio[1174]: info: Could not set timezone, retrying Oct 18 12:06:08 volumio volumio[1174]: Updating browse sources language Oct 18 12:06:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 12:06:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 18 12:06:08 volumio volumio[1174]: info: Loading i18n strings for locale en Oct 18 12:06:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 18 12:06:08 volumio volumio[1174]: info: Setting timezone to UTC Oct 18 12:06:08 volumio sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 18 12:06:08 volumio sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio sudo[2095]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Oct 18 12:06:08 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 18 12:06:08 volumio sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio sudo[2101]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Oct 18 12:06:08 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:08 volumio systemd-timedated[1822]: Failed to set time zone: Permission denied Oct 18 12:06:08 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:08 volumio volumio[1174]: Failed to set time zone: Access denied Oct 18 12:06:08 volumio volumio[1174]: info: Could not set timezone, retrying Oct 18 12:06:08 volumio volumio[1174]: info: Fetching Streaming Services browse cache Oct 18 12:06:08 volumio volumio[1174]: Updating browse sources language Oct 18 12:06:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 12:06:09 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 12:06:09 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 12:06:09 volumio volumio[1174]: info: Fetching Streaming Services browse cache Oct 18 12:06:09 volumio sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Oct 18 12:06:09 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 12:06:09 volumio systemd-timedated[1822]: Failed to set time zone: Permission denied Oct 18 12:06:09 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Oct 18 12:06:09 volumio volumio[1174]: Failed to set time zone: Access denied Oct 18 12:06:09 volumio volumio[1174]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 12:06:09 volumio volumio[1174]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Asia/Bangkok' Oct 18 12:06:09 volumio volumio[1174]: Failed to set time zone: Access denied Oct 18 12:06:09 volumio volumio[1174]: at checkExecSyncError (child_process.js:790:11) Oct 18 12:06:09 volumio volumio[1174]: at execSync (child_process.js:863:15) Oct 18 12:06:09 volumio volumio[1174]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Oct 18 12:06:09 volumio volumio[1174]: at listOnTimeout (internal/timers.js:557:17) Oct 18 12:06:09 volumio volumio[1174]: at processTimers (internal/timers.js:500:7) { Oct 18 12:06:09 volumio volumio[1174]: status: 1, Oct 18 12:06:09 volumio volumio[1174]: signal: null, Oct 18 12:06:09 volumio volumio[1174]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Oct 18 12:06:09 volumio volumio[1174]: pid: 2106, Oct 18 12:06:09 volumio volumio[1174]: stdout: '', Oct 18 12:06:09 volumio volumio[1174]: stderr: 'Failed to set time zone: Access denied\n' Oct 18 12:06:09 volumio volumio[1174]: } Oct 18 12:06:09 volumio volumio[1174]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 12:06:09 volumio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 12:05 Oct 18 12:06:09 volumio sudo[2118]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"