-- Logs begin at Thu 2024-08-29 12:13:50 UTC, end at Thu 2024-08-29 15:56:23 UTC. -- Aug 29 15:55:51 volumio ntpd[1658]: receive: Unexpected origin timestamp 0xea7ae49d.b5723bef does not match aorg 0000000000.00000000 from server@84.199.86.248 xmt 0xea7b1887.571bd294 Aug 29 15:55:51 volumio ntpd[1658]: receive: Unexpected origin timestamp 0xea7ae49d.b57415a4 does not match aorg 0000000000.00000000 from server@94.224.64.60 xmt 0xea7b1887.571813ec Aug 29 15:55:51 volumio ntpd[1658]: receive: Unexpected origin timestamp 0xea7ae49d.b56c5ead does not match aorg 0000000000.00000000 from server@77.109.90.72 xmt 0xea7b1887.628e3190 Aug 29 15:55:51 volumio volumio[1008]: info: Discovery: A device disappeared from network Aug 29 15:55:51 volumio volumio[1008]: info: Discovery: Device volumio disappeared from network Aug 29 15:55:51 volumio volumio[1008]: info: Discovery: A device disappeared from network Aug 29 15:55:51 volumio volumio[1008]: info: Discovery: adding 23c0db82-188f-4f22-b696-9c94b586576a Aug 29 15:55:51 volumio volumio[1008]: info: Discovery: Found device Volumio Aug 29 15:55:51 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:55:51 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:55:51 volumio volumio[1008]: info: Discovery: this is already registered, 23c0db82-188f-4f22-b696-9c94b586576a Aug 29 15:55:51 volumio volumio[1008]: info: Discovery: Found device Volumio Aug 29 15:55:51 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:55:51 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:55:52 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Aug 29 15:55:52 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Aug 29 15:55:52 volumio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 15:55:52 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:55:52 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Aug 29 15:55:52 volumio dhcpcd[798]: wlan0: probing for an IPv4LL address Aug 29 15:55:53 volumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 29 15:55:53 volumio volumio[1008]: info: Adding plugin bluetooth to MyMusic Plugins Aug 29 15:55:53 volumio volumio[1008]: info: Adding plugin multiroom to MyMusic Plugins Aug 29 15:55:53 volumio volumio[1008]: info: Adding plugin metavolumio to MyMusic Plugins Aug 29 15:55:53 volumio volumio[1008]: info: Adding plugin cd_controller to MyMusic Plugins Aug 29 15:55:53 volumio volumio[1008]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 29 15:55:53 volumio volumio[1008]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 29 15:55:53 volumio volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 29 15:55:55 volumio volumio[1008]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 29 15:55:55 volumio volumio[1008]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 29 15:55:55 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:55:55 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:55:55 volumio volumio[1008]: info: Starting MyVolumio Remote Streaming Endpoints Aug 29 15:55:55 volumio volumio[1008]: info: MyVolumio not started Aug 29 15:55:55 volumio volumio[1008]: info: Initializing device activation check Aug 29 15:55:55 volumio volumio[1008]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 29 15:55:55 volumio volumio[1008]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 29 15:55:57 volumio dhcpcd[798]: wlan0: using IPv4LL address 169.254.73.118 Aug 29 15:55:57 volumio dhcpcd[798]: wlan0: adding route to 169.254.0.0/16 Aug 29 15:55:57 volumio avahi-daemon[711]: Registering new address record for 169.254.73.118 on wlan0.IPv4. Aug 29 15:55:57 volumio volumio[1008]: info: Starting Streaming Service Transparent Proxy Aug 29 15:55:57 volumio volumio[1008]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 29 15:55:57 volumio volumio[1008]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 29 15:55:57 volumio volumio[1008]: info: Streaming services startup Aug 29 15:55:57 volumio volumio[1008]: info: Starting Streaming Daemon Aug 29 15:55:58 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 15:55:58 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:55:58 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Aug 29 15:55:58 volumio volumio[1008]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 29 15:55:58 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 29 15:55:58 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 29 15:55:58 volumio volumio[1008]: info: Refreshing Cached IP Addresses Aug 29 15:55:58 volumio sudo[1979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 29 15:55:58 volumio sudo[1979]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:55:58 volumio sudo[1979]: pam_unix(sudo:session): session closed for user root Aug 29 15:55:58 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 15:55:58 volumio sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 15:55:58 volumio sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:55:58 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:55:58 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root Aug 29 15:55:58 volumio sudo[1984]: pam_unix(sudo:session): session closed for user root Aug 29 15:55:58 volumio volumio[1008]: error: Cannot start Volumio Streaming Daemon Aug 29 15:55:58 volumio volumio[1008]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 15:55:58 volumio volumio[1008]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 15:55:58 volumio systemd[1]: upmpdcli.service: Succeeded. Aug 29 15:55:59 volumio volumio[1008]: STREAMING PROXY: Starting server on port 3245 Aug 29 15:55:59 volumio volumio[1008]: Node JS runtime: 14 Aug 29 15:55:59 volumio ntpd[1658]: Listen normally on 6 wlan0 169.254.73.118:123 Aug 29 15:55:59 volumio ntpd[1658]: new interface(s) found: waking up resolver Aug 29 15:55:59 volumio dbus-daemon[712]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.18' (uid=0 pid=1887 comm="/usr/lib/chromium/chromium --show-component-extens") Aug 29 15:55:59 volumio systemd[1]: Starting Bluetooth service... Aug 29 15:56:00 volumio kernel: Bluetooth: Core ver 2.22 Aug 29 15:56:00 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Aug 29 15:56:00 volumio kernel: Bluetooth: HCI device and connection manager initialized Aug 29 15:56:00 volumio kernel: Bluetooth: HCI socket layer initialized Aug 29 15:56:00 volumio kernel: Bluetooth: L2CAP socket layer initialized Aug 29 15:56:00 volumio kernel: Bluetooth: SCO socket layer initialized Aug 29 15:56:00 volumio bluetoothd[1993]: Bluetooth daemon 5.50 Aug 29 15:56:00 volumio bluetoothd[1993]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 29 15:56:00 volumio dbus-daemon[712]: [system] Successfully activated service 'org.bluez' Aug 29 15:56:00 volumio systemd[1]: Started Bluetooth service. Aug 29 15:56:00 volumio bluetoothd[1993]: Starting SDP server Aug 29 15:56:00 volumio bluetoothd[1993]: Excluding (cli) sap Aug 29 15:56:00 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 29 15:56:00 volumio kernel: Bluetooth: BNEP filters: protocol multicast Aug 29 15:56:00 volumio kernel: Bluetooth: BNEP socket layer initialized Aug 29 15:56:00 volumio bluetoothd[1993]: Bluetooth management interface 1.22 initialized Aug 29 15:56:00 volumio dbus-daemon[712]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.19' (uid=0 pid=1993 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 29 15:56:00 volumio systemd[1]: Starting Hostname Service... Aug 29 15:56:00 volumio dbus-daemon[712]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 29 15:56:00 volumio systemd[1]: Started Hostname Service. Aug 29 15:56:03 volumio volumio[1008]: 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: 3 Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 29 15:56:04 volumio volumio[1008]: info: Received Get System Info Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:04 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:04 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:04 volumio volumio-remote-updater[704]: No test mode Aug 29 15:56:04 volumio volumio-remote-updater[704]: No alpha test mode Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:05 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Aug 29 15:56:05 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:05 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Aug 29 15:56:05 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:05 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Aug 29 15:56:05 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:05 volumio volumio[1008]: info: Saving Spotify Acccount Aug 29 15:56:05 volumio volumio[1008]: info: Got Tidal Cloud Configuration Aug 29 15:56:05 volumio volumio[1008]: info: Got it Aug 29 15:56:05 volumio volumio[1008]: info: Got it Aug 29 15:56:05 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:05 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 29 15:56:05 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 15:56:06 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 29 15:56:06 volumio volumio[1008]: info: Loading i18n strings for locale en Aug 29 15:56:06 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 29 15:56:06 volumio volumio[1008]: info: Setting timezone to UTC Aug 29 15:56:06 volumio sudo[2122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 29 15:56:06 volumio sudo[2122]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:06 volumio sudo[2122]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:06 volumio sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Aug 29 15:56:06 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:06 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:06 volumio sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 29 15:56:06 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:06 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:06 volumio sudo[2133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Aug 29 15:56:06 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:06 volumio dbus-daemon[712]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=2134 comm="/usr/bin/timedatectl set-timezone UTC ") Aug 29 15:56:06 volumio systemd[1]: Starting Time & Date Service... Aug 29 15:56:06 volumio dbus-daemon[712]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 29 15:56:06 volumio systemd[1]: Started Time & Date Service. Aug 29 15:56:06 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:06 volumio volumio[1008]: Updating browse sources language Aug 29 15:56:06 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:06 volumio volumio[1008]: info: Fetching Streaming Services browse cache Aug 29 15:56:08 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 15:56:08 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:08 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:08 volumio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 15:56:08 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:08 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:08 volumio sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 15:56:08 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:08 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 29 15:56:08 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:08 volumio volumio[1008]: info: Upmpdcli Daemon Started Aug 29 15:56:08 volumio volumio[2145]: writing RSA key Aug 29 15:56:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 15:56:10 volumio volumio[1008]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 15:56:10 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:10 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 15:56:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:56:12 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 15:56:15 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:56:15 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:56:15 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 29 15:56:15 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 15:56:15 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 15:56:15 volumio volumio[1008]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 15:56:15 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 15:56:18 volumio volumio[1008]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 29 15:56:18 volumio volumio[1008]: info: Completed starting MyVolumio Plugin Aug 29 15:56:18 volumio volumio[1008]: info: BOOT COMPLETED Aug 29 15:56:18 volumio volumio[1008]: [Metrics] CommandRouter: 43s 766.43ms Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::volumiosetStartupVolume Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::Close All Modals sent Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::Close All Modals sent Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Aug 29 15:56:18 volumio volumio[1008]: verbose: New Socket.io Connection to 192.168.1.22:3000 from 192.168.1.7 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Aug 29 15:56:18 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Saving Spotify Acccount Aug 29 15:56:18 volumio volumio[1008]: info: Got Tidal Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: Got it Aug 29 15:56:18 volumio volumio[1008]: info: Got it Aug 29 15:56:18 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 15:56:18 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: info: Saving Spotify Acccount Aug 29 15:56:18 volumio volumio[1008]: info: Got Tidal Cloud Configuration Aug 29 15:56:18 volumio volumio[1008]: info: Got it Aug 29 15:56:18 volumio volumio[1008]: info: Got it Aug 29 15:56:18 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 29 15:56:18 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:18 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 29 15:56:18 volumio kernel: snd_hda_intel 0000:00:01.1: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj. Aug 29 15:56:18 volumio volumio-remote-updater[704]: [2024-08-29 15:56:18] [disconnect] Disconnect close local:[1000] remote:[1000] Aug 29 15:56:18 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Aug 29 15:56:18 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 15:56:18 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Aug 29 15:56:18 volumio systemd[1]: Stopped volumio-remote-updater.service. Aug 29 15:56:18 volumio systemd[1]: Started volumio-remote-updater.service. Aug 29 15:56:18 volumio volumio-remote-updater[2193]: Error: No active session Aug 29 15:56:18 volumio volumio-remote-updater[2193]: [2024-08-29 15:56:18] [connect] Successful connection Aug 29 15:56:18 volumio volumio-remote-updater[2193]: [2024-08-29 15:56:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724946978 101 Aug 29 15:56:18 volumio volumio[1008]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:19 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:19 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:19 volumio volumio[1008]: verbose: New Socket.io Connection to 192.168.1.22 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 14; SM-F731B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 15:56:19 volumio volumio[1008]: verbose: New Socket.io Connection to 192.168.1.22 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 14; SM-F731B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 15:56:19 volumio volumio[1008]: verbose: New Socket.io Connection to 192.168.1.22 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 14; SM-F731B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 29 15:56:19 volumio volumio[1008]: info: Received Get System Info Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:19 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 15:56:19 volumio volumio[1008]: info: Received Get System Info Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:19 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:19 volumio volumio[1008]: info: Listing playlists Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::volumioGetQueue Aug 29 15:56:19 volumio volumio[1008]: info: CoreStateMachine::getQueue Aug 29 15:56:19 volumio volumio[1008]: info: CorePlayQueue::getQueue Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 29 15:56:19 volumio volumio[1008]: info: Version has changed, forcing UI Reload Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::Reload Ui Aug 29 15:56:19 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 15:56:20 volumio volumio-remote-updater[2193]: No test mode Aug 29 15:56:20 volumio volumio-remote-updater[2193]: No alpha test mode Aug 29 15:56:20 volumio volumio[1008]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 15:56:20 volumio volumio[1008]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 15:56:20 volumio sudo[2200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 15:56:20 volumio sudo[2200]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:20 volumio sudo[2200]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:20 volumio sudo[2203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 15:56:20 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:20 volumio sudo[2203]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:20 volumio volumio[1008]: verbose: New Socket.io Connection to 192.168.1.22 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 14; SM-F731B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 29 15:56:20 volumio volumio[1008]: info: Received Get System Info Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:20 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:20 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:20 volumio volumio-remote-updater[2193]: No test mode Aug 29 15:56:20 volumio volumio-remote-updater[2193]: No alpha test mode Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:20 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Aug 29 15:56:20 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:20 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Aug 29 15:56:20 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:20 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Aug 29 15:56:20 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:20 volumio volumio[1008]: info: Saving Spotify Acccount Aug 29 15:56:20 volumio volumio[1008]: info: Got Tidal Cloud Configuration Aug 29 15:56:20 volumio volumio[1008]: info: Got it Aug 29 15:56:20 volumio volumio[1008]: info: Got it Aug 29 15:56:20 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 15:56:20 volumio volumio[1008]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:20 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 29 15:56:20 volumio volumio[1008]: info: Received Get System Info Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:20 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:20 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:20 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:21 volumio volumio-remote-updater[2193]: No test mode Aug 29 15:56:21 volumio volumio-remote-updater[2193]: No alpha test mode Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:21 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Aug 29 15:56:21 volumio volumio[1008]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 15:56:21 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:21 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Aug 29 15:56:21 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:21 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Aug 29 15:56:21 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:21 volumio volumio[1008]: info: Saving Spotify Acccount Aug 29 15:56:21 volumio volumio[1008]: info: Got Tidal Cloud Configuration Aug 29 15:56:21 volumio volumio[1008]: info: Got it Aug 29 15:56:21 volumio volumio[1008]: info: Got it Aug 29 15:56:21 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 15:56:21 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:21 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 29 15:56:22 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 29 15:56:22 volumio volumio[1008]: info: Loading i18n strings for locale fr Aug 29 15:56:22 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 29 15:56:22 volumio volumio[1008]: info: Setting timezone to Europe/Brussels Aug 29 15:56:22 volumio sudo[2214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 29 15:56:22 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:22 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:22 volumio sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Brussels /etc/localtime Aug 29 15:56:22 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:22 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:22 volumio sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 29 15:56:22 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:22 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:22 volumio sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Brussels Aug 29 15:56:22 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:22 volumio systemd-timedated[2135]: Failed to set time zone: Permission denied Aug 29 15:56:22 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:22 volumio volumio[1008]: Failed to set time zone: Access denied Aug 29 15:56:22 volumio volumio[1008]: info: Could not set timezone, retrying Aug 29 15:56:22 volumio volumio[1008]: Updating browse sources language Aug 29 15:56:22 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:22 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 15:56:22 volumio volumio[1008]: info: Fetching Streaming Services browse cache Aug 29 15:56:22 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 29 15:56:22 volumio volumio[1008]: info: Loading i18n strings for locale en Aug 29 15:56:22 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 29 15:56:22 volumio volumio[1008]: info: Setting timezone to UTC Aug 29 15:56:22 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 29 15:56:22 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:22 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:22 volumio sudo[2229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Aug 29 15:56:22 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:22 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:22 volumio sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 29 15:56:22 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:22 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:22 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Aug 29 15:56:22 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:23 volumio systemd-timedated[2135]: Failed to set time zone: Permission denied Aug 29 15:56:23 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:23 volumio volumio[1008]: Failed to set time zone: Access denied Aug 29 15:56:23 volumio volumio[1008]: info: Could not set timezone, retrying Aug 29 15:56:23 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 15:56:23 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:23 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 15:56:23 volumio sudo[2241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 15:56:23 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:23 volumio sudo[2241]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:23 volumio volumio[1008]: Updating browse sources language Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:23 volumio volumio[1008]: verbose: New Socket.io Connection to 192.168.1.22 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 14; SM-F731B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 29 15:56:23 volumio volumio[1008]: info: Received Get System Info Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 15:56:23 volumio volumio[1008]: info: Discovery: Getting this device information Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Aug 29 15:56:23 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 15:56:23 volumio volumio-remote-updater[2193]: No test mode Aug 29 15:56:23 volumio volumio-remote-updater[2193]: No alpha test mode Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 15:56:23 volumio volumio[1008]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 15:56:23 volumio volumio[1008]: info: Fetching Streaming Services browse cache Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:23 volumio volumio[1008]: info: Retrieving Cloud Streaming UI Aug 29 15:56:23 volumio volumio[1008]: info: Getting Tidal Cloud Configuration Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:23 volumio volumio[1008]: info: Getting Qobuz Cloud Configuration Aug 29 15:56:23 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:23 volumio volumio[1008]: info: Getting Spotify Cloud Configuration Aug 29 15:56:23 volumio volumio[1008]: info: Asking plugin for UI Config Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:23 volumio volumio[1008]: info: Saving Spotify Acccount Aug 29 15:56:23 volumio volumio[1008]: info: Got Tidal Cloud Configuration Aug 29 15:56:23 volumio volumio[1008]: info: Got it Aug 29 15:56:23 volumio volumio[1008]: info: Got it Aug 29 15:56:23 volumio volumio[1008]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 15:56:23 volumio volumio[1008]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 15:56:23 volumio volumio[1008]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 29 15:56:23 volumio sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Brussels Aug 29 15:56:23 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 15:56:23 volumio systemd-timedated[2135]: Failed to set time zone: Permission denied Aug 29 15:56:23 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root Aug 29 15:56:23 volumio volumio[1008]: Failed to set time zone: Access denied Aug 29 15:56:23 volumio volumio[1008]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 15:56:23 volumio volumio[1008]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/Brussels' Aug 29 15:56:23 volumio volumio[1008]: Failed to set time zone: Access denied Aug 29 15:56:23 volumio volumio[1008]: at checkExecSyncError (child_process.js:790:11) Aug 29 15:56:23 volumio volumio[1008]: at execSync (child_process.js:863:15) Aug 29 15:56:23 volumio volumio[1008]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1572:9) Aug 29 15:56:23 volumio volumio[1008]: at listOnTimeout (internal/timers.js:557:17) Aug 29 15:56:23 volumio volumio[1008]: at processTimers (internal/timers.js:500:7) { Aug 29 15:56:23 volumio volumio[1008]: status: 1, Aug 29 15:56:23 volumio volumio[1008]: signal: null, Aug 29 15:56:23 volumio volumio[1008]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Aug 29 15:56:23 volumio volumio[1008]: pid: 2247, Aug 29 15:56:23 volumio volumio[1008]: stdout: '', Aug 29 15:56:23 volumio volumio[1008]: stderr: 'Failed to set time zone: Access denied\n' Aug 29 15:56:23 volumio volumio[1008]: } Aug 29 15:56:23 volumio volumio[1008]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 15:56:23 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 15:55 Aug 29 15:56:23 volumio sudo[2259]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"