Jan 26 20:32:51 volumio ntpd[1145]: CLOCK: time stepped by 340.816540 Jan 26 20:32:51 volumio ntpd[1145]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 26 20:32:53 volumio volumio[1259]: info: MYVOLUMIO: Adding device Jan 26 20:32:53 volumio volumio[1259]: info: MYVOLUMIO: Evaluating Server Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:32:55 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:55 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:32:55 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.83:3000 from 192.168.1.81 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 20:32:55 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.83:3000 from 192.168.1.81 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:32:55 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:55 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:32:55 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:55 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 26 20:32:55 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 26 20:32:55 volumio sudo[1854]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 20:32:55 volumio sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 26 20:32:55 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:55 volumio sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:55 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:55 volumio sudo[1845]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 20:32:55 volumio sudo[1845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:55 volumio sudo[1851]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 20:32:55 volumio sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:32:55 volumio sudo[1845]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:56 volumio sudo[1851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:56 volumio volumio[1259]: info: Setting Geolocation for MyVolumio to as1 Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:32:56 volumio sudo[1861]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:32:56 volumio sudo[1859]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:32:56 volumio sudo[1859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:56 volumio sudo[1851]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio sudo[1859]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio sudo[1840]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:32:56 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:56 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:32:56 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:56 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:56 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 20:32:56 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.83:3000 from 192.168.1.81 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 26 20:32:56 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 26 20:32:56 volumio volumio[1259]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 26 20:32:56 volumio sudo[1880]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:32:56 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:56 volumio sudo[1880]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio sudo[1882]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:32:56 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:56 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 12; LMR-LX9 Build/HUAWEILMR-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/114.0.5735.196 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 26 20:32:56 volumio sudo[1886]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 20:32:56 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:56 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:56 volumio sudo[1888]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 20:32:56 volumio sudo[1888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 20:32:56 volumio sudo[1888]: pam_unix(sudo:session): session closed for user root Jan 26 20:32:57 volumio volumio[1259]: info: Updating MyVolumio device info Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:32:57 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 12; LMR-LX9 Build/HUAWEILMR-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/114.0.5735.196 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:57 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::volumioGetQueue Jan 26 20:32:57 volumio volumio[1259]: info: CoreStateMachine::getQueue Jan 26 20:32:57 volumio volumio[1259]: info: CorePlayQueue::getQueue Jan 26 20:32:57 volumio volumio[1259]: info: Listing playlists Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 20:32:57 volumio volumio[1259]: info: Received Get System Info Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:32:57 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:57 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:57 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 20:32:57 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 26 20:32:57 volumio volumio[1259]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 26 20:32:58 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 26 20:32:58 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 20:32:58 volumio volumio[1259]: info: Received Get System Info Jan 26 20:32:58 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:32:58 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:32:58 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:32:58 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:58 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:32:58 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:32:59 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 26 20:32:59 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 20:32:59 volumio volumio[1259]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 26 20:32:59 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:32:59 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:33:00 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 20:33:00 volumio volumio[1259]: info: Received Get System Info Jan 26 20:33:00 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 20:33:00 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 20:33:00 volumio volumio[1259]: info: Discovery: Getting this device information Jan 26 20:33:00 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Jan 26 20:33:00 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Jan 26 20:33:00 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 20:33:00 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:33:02 volumio volumio[1259]: info: Retrieving Cloud Streaming UI Jan 26 20:33:02 volumio volumio[1259]: info: Getting Tidal Cloud Configuration Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:33:02 volumio volumio[1259]: info: Getting Qobuz Cloud Configuration Jan 26 20:33:02 volumio volumio[1259]: info: Asking plugin for UI Config Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:33:02 volumio volumio[1259]: info: Getting Spotify Cloud Configuration Jan 26 20:33:02 volumio volumio[1259]: info: Asking plugin for UI Config Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:33:02 volumio volumio[1259]: info: Saving Spotify Acccount Jan 26 20:33:02 volumio volumio[1259]: info: Got Tidal Cloud Configuration Jan 26 20:33:02 volumio volumio[1259]: info: Got it Jan 26 20:33:02 volumio volumio[1259]: info: Got it Jan 26 20:33:02 volumio volumio[1259]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:33:02 volumio volumio[1259]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 20:33:02 volumio volumio[1259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 20:33:03 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 20:33:03 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 20:33:04 volumio volumio-remote-updater[949]: Test mode disabled Jan 26 20:33:04 volumio volumio-remote-updater[949]: Alpha mode disabled Jan 26 20:33:04 volumio volumio-remote-updater[949]: Alpha legacy test mode disabled Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 26 20:33:04 volumio volumio[1259]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 26 20:33:04 volumio volumio[1259]: info: Completed starting MyVolumio Plugin Jan 26 20:33:04 volumio volumio[1259]: [Metrics] CommandRouter: 32s 438.05ms Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::volumiosetStartupVolume Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::Close All Modals sent Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::Close All Modals sent Jan 26 20:33:04 volumio volumio[1259]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 26 20:33:04 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 26 20:33:05 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 20:33:05 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 20:33:05 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 20:33:07 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 20:33:08 volumio volumio[1259]: info: Getting Alsa Cards List without I2S DAC Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 26 20:33:08 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 20:33:11 volumio volumio[1259]: info: BOOT COMPLETED Jan 26 20:33:14 volumio go-librespot[1674]: time="2026-01-26T20:33:14+08:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Jan 26 20:33:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 20:33:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 20:33:14 volumio volumio[1259]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 20:33:14 volumio volumio[1259]: Error: socket hang up Jan 26 20:33:14 volumio volumio[1259]: at connResetException (node:internal/errors:720:14) Jan 26 20:33:14 volumio volumio[1259]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 26 20:33:14 volumio volumio[1259]: at Socket.emit (node:events:526:35) Jan 26 20:33:14 volumio volumio[1259]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 26 20:33:14 volumio volumio[1259]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 26 20:33:14 volumio volumio[1259]: code: 'ECONNRESET', Jan 26 20:33:14 volumio volumio[1259]: response: undefined Jan 26 20:33:14 volumio volumio[1259]: } Jan 26 20:33:14 volumio volumio[1259]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 20:33:14 volumio sudo[1958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 20:32' Jan 26 20:33:14 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"