-- Logs begin at Sat 2025-01-11 18:19:10 UTC, end at Sat 2025-01-11 18:21:30 UTC. -- Jan 11 18:21:10 volumio ntpd[1582]: receive: Unexpected origin timestamp 0xeb2d34b1.2eb09ef9 does not match aorg 0000000000.00000000 from server@23.186.168.3 xmt 0xeb2d3516.3a2a15ce Jan 11 18:21:10 volumio ntpd[1582]: receive: Unexpected origin timestamp 0xeb2d34b1.2eb1249a does not match aorg 0000000000.00000000 from server@67.217.246.204 xmt 0xeb2d3516.3b747a32 Jan 11 18:21:10 volumio ntpd[1582]: receive: Unexpected origin timestamp 0xeb2d34b1.2eadde4e does not match aorg 0000000000.00000000 from server@74.6.168.73 xmt 0xeb2d3516.406f34ee Jan 11 18:21:11 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1682 comm="/usr/lib/chromium/chromium --show-component-extens") Jan 11 18:21:11 volumio systemd[1]: Starting Bluetooth service... Jan 11 18:21:11 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jan 11 18:21:13 volumio kernel: Bluetooth: Core ver 2.22 Jan 11 18:21:13 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Jan 11 18:21:13 volumio kernel: Bluetooth: HCI device and connection manager initialized Jan 11 18:21:13 volumio kernel: Bluetooth: HCI socket layer initialized Jan 11 18:21:13 volumio kernel: Bluetooth: L2CAP socket layer initialized Jan 11 18:21:13 volumio kernel: Bluetooth: SCO socket layer initialized Jan 11 18:21:13 volumio bluetoothd[1718]: Bluetooth daemon 5.50 Jan 11 18:21:13 volumio bluetoothd[1718]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 11 18:21:13 volumio dbus-daemon[705]: [system] Successfully activated service 'org.bluez' Jan 11 18:21:13 volumio systemd[1]: Started Bluetooth service. Jan 11 18:21:13 volumio bluetoothd[1718]: Starting SDP server Jan 11 18:21:13 volumio bluetoothd[1718]: Excluding (cli) sap Jan 11 18:21:13 volumio bluetoothd[1718]: Bluetooth management interface 1.22 initialized Jan 11 18:21:13 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.13' (uid=0 pid=1718 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 11 18:21:13 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 11 18:21:13 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jan 11 18:21:13 volumio kernel: Bluetooth: BNEP socket layer initialized Jan 11 18:21:13 volumio systemd[1]: Starting Hostname Service... Jan 11 18:21:13 volumio dbus-daemon[705]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 11 18:21:13 volumio systemd[1]: Started Hostname Service. Jan 11 18:21:14 volumio volumio[927]: 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: 12 Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jan 11 18:21:14 volumio volumio[927]: info: Received Get System Info Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 18:21:14 volumio volumio[927]: info: Discovery: Getting this device information Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Jan 11 18:21:14 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 18:21:14 volumio volumio-remote-updater[700]: No test mode Jan 11 18:21:14 volumio volumio-remote-updater[700]: No alpha test mode Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:14 volumio volumio[927]: info: Retrieving Cloud Streaming UI Jan 11 18:21:14 volumio volumio[927]: info: Getting Tidal Cloud Configuration Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:14 volumio volumio[927]: info: Getting Qobuz Cloud Configuration Jan 11 18:21:14 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:14 volumio volumio[927]: info: Getting Spotify Cloud Configuration Jan 11 18:21:14 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:14 volumio volumio[927]: info: Saving Spotify Acccount Jan 11 18:21:14 volumio volumio[927]: info: Got Tidal Cloud Configuration Jan 11 18:21:14 volumio volumio[927]: info: Got it Jan 11 18:21:14 volumio volumio[927]: info: Got it Jan 11 18:21:14 volumio volumio[927]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:14 volumio volumio[927]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 18:21:14 volumio volumio[927]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

 

","title":"Update v3.779","updateavailable":true} Jan 11 18:21:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 11 18:21:15 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jan 11 18:21:15 volumio volumio[927]: info: Loading i18n strings for locale en Jan 11 18:21:15 volumio volumio[927]: Updating browse sources language Jan 11 18:21:15 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 18:21:15 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 11 18:21:15 volumio volumio[927]: info: Setting timezone to UTC Jan 11 18:21:15 volumio sudo[1858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 11 18:21:15 volumio sudo[1858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:15 volumio sudo[1858]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:15 volumio sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jan 11 18:21:15 volumio sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:15 volumio sudo[1861]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:15 volumio sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 11 18:21:15 volumio sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:15 volumio sudo[1864]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:15 volumio sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jan 11 18:21:15 volumio sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:15 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=1868 comm="/usr/bin/timedatectl set-timezone UTC ") Jan 11 18:21:15 volumio systemd[1]: Starting Time & Date Service... Jan 11 18:21:15 volumio dbus-daemon[705]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 11 18:21:15 volumio systemd[1]: Started Time & Date Service. Jan 11 18:21:15 volumio sudo[1867]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:16 volumio volumio[927]: info: Fetching Streaming Services browse cache Jan 11 18:21:16 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 18:21:18 volumio sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 18:21:18 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:18 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:18 volumio sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 18:21:18 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:18 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:18 volumio sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 11 18:21:18 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:18 volumio sudo[1878]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:18 volumio volumio[927]: info: Upmpdcli Daemon Started Jan 11 18:21:21 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 18:21:21 volumio volumio[927]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 11 18:21:21 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Jan 11 18:21:21 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Jan 11 18:21:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 11 18:21:23 volumio volumio[927]: info: Update: [object Object] Jan 11 18:21:23 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity Jan 11 18:21:23 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:21:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem Jan 11 18:21:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates Jan 11 18:21:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 18:21:25 volumio volumio-remote-updater[700]: PROGRESS: 0, STATUS: "Starting update", ETA: "6m" Jan 11 18:21:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: updater_comm , notifyProgress Jan 11 18:21:25 volumio volumio-remote-updater[700]: /bin/rm: cannot remove '/imgpart/rck*': No such file or directory Jan 11 18:21:25 volumio volumio-remote-updater[700]: /bin/rm: cannot remove '/imgpart/*.part': No such file or directory Jan 11 18:21:25 volumio volumio-remote-updater[700]: /bin/rm: cannot remove '/imgpart/*.zs-old': No such file or directory Jan 11 18:21:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 11 18:21:25 volumio volumio-remote-updater[700]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m" Jan 11 18:21:25 volumio volumio[927]: info: Killing processes that might interfere with OTA Updates Jan 11 18:21:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:25 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-keyboard Jan 11 18:21:25 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:25 volumio volumio[927]: updateProgress Jan 11 18:21:25 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-window-manager Jan 11 18:21:25 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:25 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:25 volumio volumio[927]: { Jan 11 18:21:25 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:25 volumio volumio[927]: eta: '5m', Jan 11 18:21:25 volumio volumio[927]: progress: 5, Jan 11 18:21:25 volumio volumio[927]: status: 'Preparing update' Jan 11 18:21:25 volumio volumio[927]: } Jan 11 18:21:25 volumio volumio[927]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-keyboard Jan 11 18:21:25 volumio volumio[927]: matchbox-keyboard: no process found Jan 11 18:21:25 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:25 volumio volumio[927]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-window-manager Jan 11 18:21:25 volumio volumio[927]: matchbox-window-manager: no process found Jan 11 18:21:25 volumio volumio-remote-updater[700]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m" Jan 11 18:21:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:25 volumio volumio[927]: updateProgress Jan 11 18:21:25 volumio volumio[927]: { Jan 11 18:21:25 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:25 volumio volumio[927]: eta: '5m', Jan 11 18:21:25 volumio volumio[927]: progress: 5, Jan 11 18:21:25 volumio volumio[927]: status: 'Preparing update' Jan 11 18:21:25 volumio volumio[927]: } Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:26 volumio volumio[927]: updateProgress Jan 11 18:21:26 volumio volumio[927]: { Jan 11 18:21:26 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:26 volumio volumio[927]: eta: '5m', Jan 11 18:21:26 volumio volumio[927]: progress: 10, Jan 11 18:21:26 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:26 volumio volumio[927]: } Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:26 volumio volumio[927]: updateProgress Jan 11 18:21:26 volumio volumio[927]: { Jan 11 18:21:26 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:26 volumio volumio[927]: eta: '5m', Jan 11 18:21:26 volumio volumio[927]: progress: 10, Jan 11 18:21:26 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:26 volumio volumio[927]: } Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:26 volumio volumio[927]: updateProgress Jan 11 18:21:26 volumio volumio[927]: { Jan 11 18:21:26 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:26 volumio volumio[927]: eta: '5m', Jan 11 18:21:26 volumio volumio[927]: progress: 10, Jan 11 18:21:26 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:26 volumio volumio[927]: } Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:26 volumio volumio[927]: updateProgress Jan 11 18:21:26 volumio volumio[927]: { Jan 11 18:21:26 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:26 volumio volumio[927]: eta: '5m', Jan 11 18:21:26 volumio volumio[927]: progress: 10, Jan 11 18:21:26 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:26 volumio volumio[927]: } Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:26 volumio volumio[927]: updateProgress Jan 11 18:21:26 volumio volumio[927]: { Jan 11 18:21:26 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:26 volumio volumio[927]: eta: '5m', Jan 11 18:21:26 volumio volumio[927]: progress: 10, Jan 11 18:21:26 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:26 volumio volumio[927]: } Jan 11 18:21:26 volumio volumio[927]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 11 18:21:26 volumio volumio[927]: info: Completed starting MyVolumio Plugin Jan 11 18:21:26 volumio volumio[927]: info: BOOT COMPLETED Jan 11 18:21:26 volumio volumio[927]: [Metrics] CommandRouter: 32s 735.11ms Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::volumiosetStartupVolume Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::Close All Modals sent Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::Close All Modals sent Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Retrieving Cloud Streaming UI Jan 11 18:21:26 volumio volumio[927]: info: Getting Tidal Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Getting Qobuz Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Getting Spotify Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Saving Spotify Acccount Jan 11 18:21:26 volumio volumio[927]: info: Got Tidal Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: Got it Jan 11 18:21:26 volumio volumio[927]: info: Got it Jan 11 18:21:26 volumio volumio[927]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Retrieving Cloud Streaming UI Jan 11 18:21:26 volumio volumio[927]: info: Getting Tidal Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Getting Qobuz Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Getting Spotify Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: info: Saving Spotify Acccount Jan 11 18:21:26 volumio volumio[927]: info: Got Tidal Cloud Configuration Jan 11 18:21:26 volumio volumio[927]: info: Got it Jan 11 18:21:26 volumio volumio[927]: info: Got it Jan 11 18:21:26 volumio volumio[927]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:26 volumio volumio[927]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 18:21:26 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 11 18:21:27 volumio volumio-remote-updater[700]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:27 volumio volumio-remote-updater[700]: PROGRESS: 12, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:27 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:27 volumio volumio[927]: updateProgress Jan 11 18:21:27 volumio volumio[927]: { Jan 11 18:21:27 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:27 volumio volumio[927]: eta: '5m', Jan 11 18:21:27 volumio volumio[927]: progress: 12, Jan 11 18:21:27 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:27 volumio volumio[927]: } Jan 11 18:21:27 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:27 volumio volumio[927]: updateProgress Jan 11 18:21:27 volumio volumio[927]: { Jan 11 18:21:27 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:27 volumio volumio[927]: eta: '5m', Jan 11 18:21:27 volumio volumio[927]: progress: 12, Jan 11 18:21:27 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:27 volumio volumio[927]: } Jan 11 18:21:27 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 11 18:21:27 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:21:27 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 11 18:21:27 volumio volumio[927]: info: Version has changed, forcing UI Reload Jan 11 18:21:27 volumio volumio[927]: info: CoreCommandRouter::Reload Ui Jan 11 18:21:27 volumio sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 18:21:27 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:27 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:27 volumio sudo[2102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 18:21:27 volumio sudo[2102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:27 volumio sudo[2102]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:27 volumio volumio[927]: verbose: New Socket.io Connection to 192.168.1.156 from 192.168.1.234 UA: Mozilla/5.0 (Linux; Android 14; SM-S908U Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.201 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jan 11 18:21:28 volumio volumio[927]: info: Received Get System Info Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 18:21:28 volumio volumio[927]: info: Discovery: Getting this device information Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Jan 11 18:21:28 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Retrieving Cloud Streaming UI Jan 11 18:21:28 volumio volumio[927]: info: Getting Tidal Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Getting Qobuz Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Getting Spotify Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Saving Spotify Acccount Jan 11 18:21:28 volumio volumio[927]: info: Got Tidal Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: Got it Jan 11 18:21:28 volumio volumio[927]: info: Got it Jan 11 18:21:28 volumio volumio[927]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 18:21:28 volumio volumio[927]: 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: 13 Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jan 11 18:21:28 volumio volumio-remote-updater[700]: PROGRESS: 12, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:28 volumio volumio-remote-updater[700]: PROGRESS: 15, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jan 11 18:21:28 volumio volumio[927]: info: Received Get System Info Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 18:21:28 volumio volumio[927]: info: Discovery: Getting this device information Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Jan 11 18:21:28 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Retrieving Cloud Streaming UI Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:28 volumio volumio[927]: updateProgress Jan 11 18:21:28 volumio volumio[927]: { Jan 11 18:21:28 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:28 volumio volumio[927]: eta: '5m', Jan 11 18:21:28 volumio volumio[927]: progress: 15, Jan 11 18:21:28 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:28 volumio volumio[927]: } Jan 11 18:21:28 volumio volumio[927]: info: Getting Tidal Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Getting Qobuz Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Getting Spotify Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: Asking plugin for UI Config Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: info: Saving Spotify Acccount Jan 11 18:21:28 volumio volumio[927]: info: Got Tidal Cloud Configuration Jan 11 18:21:28 volumio volumio[927]: info: Got it Jan 11 18:21:28 volumio volumio[927]: info: Got it Jan 11 18:21:28 volumio volumio[927]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 18:21:28 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:28 volumio volumio[927]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jan 11 18:21:29 volumio volumio[927]: info: Loading i18n strings for locale en Jan 11 18:21:29 volumio volumio[927]: Updating browse sources language Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 11 18:21:29 volumio volumio[927]: info: Setting timezone to America/New_York Jan 11 18:21:29 volumio sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 11 18:21:29 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Jan 11 18:21:29 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 11 18:21:29 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio volumio-remote-updater[700]: PROGRESS: 15, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:29 volumio volumio-remote-updater[700]: PROGRESS: 17, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:29 volumio volumio-remote-updater[700]: PROGRESS: 17, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:29 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Jan 11 18:21:29 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio systemd-timedated[1869]: Failed to set time zone: Permission denied Jan 11 18:21:29 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio volumio[927]: Failed to set time zone: Access denied Jan 11 18:21:29 volumio volumio[927]: info: Could not set timezone, retrying Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 11 18:21:29 volumio volumio[927]: updateProgress Jan 11 18:21:29 volumio volumio[927]: { Jan 11 18:21:29 volumio volumio[927]: downloadSpeed: '', Jan 11 18:21:29 volumio volumio[927]: eta: '5m', Jan 11 18:21:29 volumio volumio[927]: progress: 17, Jan 11 18:21:29 volumio volumio[927]: status: 'Creating backup' Jan 11 18:21:29 volumio volumio[927]: } Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jan 11 18:21:29 volumio volumio[927]: info: Loading i18n strings for locale en Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 11 18:21:29 volumio volumio[927]: info: Setting timezone to UTC Jan 11 18:21:29 volumio sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 11 18:21:29 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Jan 11 18:21:29 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 11 18:21:29 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Jan 11 18:21:29 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:29 volumio systemd-timedated[1869]: Failed to set time zone: Permission denied Jan 11 18:21:29 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:29 volumio volumio[927]: Failed to set time zone: Access denied Jan 11 18:21:29 volumio volumio[927]: info: Could not set timezone, retrying Jan 11 18:21:29 volumio volumio[927]: Updating browse sources language Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 18:21:29 volumio volumio[927]: info: Fetching Streaming Services browse cache Jan 11 18:21:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 18:21:29 volumio volumio[927]: info: Fetching Streaming Services browse cache Jan 11 18:21:30 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 18:21:30 volumio sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Jan 11 18:21:30 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 18:21:30 volumio volumio-remote-updater[700]: PROGRESS: 17, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:30 volumio volumio-remote-updater[700]: PROGRESS: 20, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:30 volumio volumio-remote-updater[700]: PROGRESS: 20, STATUS: "Creating backup", ETA: "5m" Jan 11 18:21:30 volumio systemd-timedated[1869]: Failed to set time zone: Permission denied Jan 11 18:21:30 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Jan 11 18:21:30 volumio volumio[927]: Failed to set time zone: Access denied Jan 11 18:21:30 volumio volumio[927]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 18:21:30 volumio volumio[927]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'America/New_York' Jan 11 18:21:30 volumio volumio[927]: Failed to set time zone: Access denied Jan 11 18:21:30 volumio volumio[927]: at checkExecSyncError (child_process.js:790:11) Jan 11 18:21:30 volumio volumio[927]: at execSync (child_process.js:863:15) Jan 11 18:21:30 volumio volumio[927]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1562:9) Jan 11 18:21:30 volumio volumio[927]: at listOnTimeout (internal/timers.js:557:17) Jan 11 18:21:30 volumio volumio[927]: at processTimers (internal/timers.js:500:7) { Jan 11 18:21:30 volumio volumio[927]: status: 1, Jan 11 18:21:30 volumio volumio[927]: signal: null, Jan 11 18:21:30 volumio volumio[927]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Jan 11 18:21:30 volumio volumio[927]: pid: 2137, Jan 11 18:21:30 volumio volumio[927]: stdout: '', Jan 11 18:21:30 volumio volumio[927]: stderr: 'Failed to set time zone: Access denied\n' Jan 11 18:21:30 volumio volumio[927]: } Jan 11 18:21:30 volumio volumio[927]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 18:21:30 volumio sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 18:20 Jan 11 18:21:30 volumio sudo[2149]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"