May 28 03:08:00 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -110 May 28 03:08:01 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:02 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:03 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:04 volumio-tv sudo[2570]: pam_unix(sudo:session): session closed for user root May 28 03:08:04 volumio-tv volumio[2144]: info: Cannot mount NAS media at system boot, trial number 3 ,retrying in 5 seconds May 28 03:08:04 volumio-tv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 03:08:04 volumio-tv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 28 03:08:04 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -110 May 28 03:08:04 volumio-tv volumio[2144]: info: VolumeController:: Volume=49 Mute =false May 28 03:08:04 volumio-tv volumio[2144]: info: CoreStateMachine::pushState May 28 03:08:04 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:04 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioPushState May 28 03:08:05 volumio-tv volumio[2144]: info: [squeezelite_mc] Server discovery started May 28 03:08:05 volumio-tv volumio[2144]: info: [squeezelite_mc] Player finder started May 28 03:08:05 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 03:08:05 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 03:08:05 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 03:08:05 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 03:08:05 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing aplay -D hw:4 --nonblock -f MPEG /dev/zero 2>&1 || true May 28 03:08:06 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:07 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:08 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:09 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -110 May 28 03:08:09 volumio-tv volumio[2144]: info: VolumeController:: Volume=49 Mute =false May 28 03:08:09 volumio-tv volumio[2144]: info: CoreStateMachine::pushState May 28 03:08:09 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:09 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioPushState May 28 03:08:09 volumio-tv volumio[2144]: info: [squeezelite_mc] Card 4 supports the following ALSA formats: ["S16_LE","S32_LE"] May 28 03:08:09 volumio-tv volumio[2144]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio-tv","dsdFormat":null,"card":"4","mixerType":"Hardware","mixer":"Digital"} May 28 03:08:09 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 28 03:08:09 volumio-tv sudo[2595]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 28 03:08:09 volumio-tv sudo[2595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:09 volumio-tv sudo[2595]: pam_unix(sudo:session): session closed for user root May 28 03:08:09 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing alsactl -L -R nrestore May 28 03:08:09 volumio-tv sudo[2599]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore May 28 03:08:09 volumio-tv sudo[2599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:09 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:09 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:09 volumio-tv volumio[2144]: info: Listing playlists May 28 03:08:09 volumio-tv volumio[2144]: info: Listing playlists May 28 03:08:09 volumio-tv sudo[2602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.1.11/media /mnt/NAS/media May 28 03:08:09 volumio-tv sudo[2602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:09 volumio-tv kernel: CIFS: Attempting to mount //192.168.1.11/media May 28 03:08:10 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:11 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:12 volumio-tv sudo[2599]: pam_unix(sudo:session): session closed for user root May 28 03:08:12 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -110 May 28 03:08:12 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 28 03:08:12 volumio-tv sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 28 03:08:12 volumio-tv sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:12 volumio-tv sudo[2608]: pam_unix(sudo:session): session closed for user root May 28 03:08:12 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true May 28 03:08:12 volumio-tv sudo[2611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 28 03:08:13 volumio-tv sudo[2611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:13 volumio-tv systemd[1]: Reloading. May 28 03:08:13 volumio-tv systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal May 28 03:08:14 volumio-tv systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 03:08:14 volumio-tv systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 03:08:14 volumio-tv sudo[2611]: pam_unix(sudo:session): session closed for user root May 28 03:08:14 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 03:08:14 volumio-tv sudo[2644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 03:08:14 volumio-tv sudo[2644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:14 volumio-tv sudo[2644]: pam_unix(sudo:session): session closed for user root May 28 03:08:14 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true May 28 03:08:14 volumio-tv sudo[2648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite May 28 03:08:14 volumio-tv sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:14 volumio-tv systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... May 28 03:08:14 volumio-tv systemd[1]: squeezelite.service: Deactivated successfully. May 28 03:08:14 volumio-tv systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. May 28 03:08:14 volumio-tv sudo[2648]: pam_unix(sudo:session): session closed for user root May 28 03:08:14 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 03:08:14 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:08:14 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 03:08:15 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 03:08:15 volumio-tv sudo[2654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 03:08:15 volumio-tv sudo[2654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:15 volumio-tv sudo[2654]: pam_unix(sudo:session): session closed for user root May 28 03:08:15 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log May 28 03:08:15 volumio-tv sudo[2658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log May 28 03:08:15 volumio-tv sudo[2658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:15 volumio-tv sudo[2658]: pam_unix(sudo:session): session closed for user root May 28 03:08:15 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true May 28 03:08:15 volumio-tv sudo[2661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite May 28 03:08:15 volumio-tv sudo[2661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:16 volumio-tv systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... May 28 03:08:16 volumio-tv systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. May 28 03:08:16 volumio-tv sudo[2661]: pam_unix(sudo:session): session closed for user root May 28 03:08:16 volumio-tv sudo[2602]: pam_unix(sudo:session): session closed for user root May 28 03:08:16 volumio-tv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 03:08:16 volumio-tv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 28 03:08:16 volumio-tv volumio[2144]: info: Cannot mount NAS media at system boot, trial number 4 ,retrying in 5 seconds May 28 03:08:16 volumio-tv volumio[2144]: error: Plugin music_service squeezelite_mc failed to complete 'onStart' in a timely fashion May 28 03:08:16 volumio-tv volumio[2144]: info: ------------------------------------------- May 28 03:08:16 volumio-tv volumio[2144]: info: ----- MyVolumio plugins startup ---- May 28 03:08:16 volumio-tv volumio[2144]: info: ------------------------------------------- May 28 03:08:16 volumio-tv volumio[2144]: info: [MyVolumio PluginManager] Fetching plans data.... May 28 03:08:16 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 03:08:16 volumio-tv sudo[2666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 03:08:16 volumio-tv sudo[2666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:16 volumio-tv sudo[2666]: pam_unix(sudo:session): session closed for user root May 28 03:08:17 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:17 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 03:08:17 volumio-tv sudo[2669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 03:08:17 volumio-tv sudo[2669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:17 volumio-tv sudo[2669]: pam_unix(sudo:session): session closed for user root May 28 03:08:18 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 03:08:18 volumio-tv go-librespot[2480]: time="2026-05-28T03:08:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: i/o timeout (Client.Timeout exceeded while awaiting headers)" May 28 03:08:18 volumio-tv systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 28 03:08:18 volumio-tv systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 28 03:08:18 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:18 volumio-tv sudo[2672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 03:08:18 volumio-tv sudo[2672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:18 volumio-tv sudo[2672]: pam_unix(sudo:session): session closed for user root May 28 03:08:18 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 03:08:19 volumio-tv sudo[2675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 03:08:19 volumio-tv sudo[2675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:19 volumio-tv sudo[2675]: pam_unix(sudo:session): session closed for user root May 28 03:08:19 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:19 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:19 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:19 volumio-tv volumio[2144]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 03:08:19 volumio-tv sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 03:08:19 volumio-tv sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:19 volumio-tv sudo[2678]: pam_unix(sudo:session): session closed for user root May 28 03:08:20 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -110 May 28 03:08:21 volumio-tv sudo[2683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.1.11/media /mnt/NAS/media May 28 03:08:21 volumio-tv sudo[2683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:08:21 volumio-tv kernel: CIFS: Attempting to mount //192.168.1.11/media May 28 03:08:21 volumio-tv systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 28 03:08:21 volumio-tv systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 28 03:08:21 volumio-tv systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 28 03:08:21 volumio-tv go-librespot[2687]: go-librespot daemon starting... May 28 03:08:21 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:21+02:00" level=info msg="running go-librespot 0.6.2" May 28 03:08:21 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:21+02:00" level=debug msg="app state loaded" May 28 03:08:21 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 28 03:08:25 volumio-tv volumio[2144]: info: Discovery: adding a811ef17-492e-4872-a496-30874620c5b0 May 28 03:08:25 volumio-tv volumio[2144]: info: Discovery: Found device Volumio-TV May 28 03:08:25 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:25 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:25 volumio-tv volumio[2144]: info: Discovery: this is already registered, a811ef17-492e-4872-a496-30874620c5b0 May 28 03:08:25 volumio-tv volumio[2144]: info: Discovery: Found device Volumio-TV May 28 03:08:25 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:25 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:25 volumio-tv volumio[2144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 28 03:08:27 volumio-tv sudo[2683]: pam_unix(sudo:session): session closed for user root May 28 03:08:27 volumio-tv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 03:08:27 volumio-tv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 28 03:08:27 volumio-tv volumio[2144]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 28 03:08:29 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:29 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:29 volumio-tv volumio[2144]: info: Listing playlists May 28 03:08:29 volumio-tv volumio[2144]: info: Listing playlists May 28 03:08:36 volumio-tv volumio[2144]: error: MyVolumio Plugin failed to start in a timely fashion May 28 03:08:36 volumio-tv volumio[2144]: [Metrics] CommandRouter: 75s 143.98ms May 28 03:08:36 volumio-tv volumio[2144]: info: CoreCommandRouter::volumiosetStartupVolume May 28 03:08:36 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 03:08:36 volumio-tv volumio[2144]: info: VolumeController:: Setting startup Volume 30 May 28 03:08:36 volumio-tv volumio[2144]: info: VolumeController::SetAlsaVolume30 May 28 03:08:36 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:08:36 volumio-tv volumio[2144]: info: CoreCommandRouter::Close All Modals sent May 28 03:08:36 volumio-tv volumio[2144]: info: CoreCommandRouter::Close All Modals sent May 28 03:08:36 volumio-tv volumio[2144]: info: CoreStateMachine::pushState May 28 03:08:36 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:36 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 03:08:36 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioPushState May 28 03:08:37 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:37 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 28 03:08:37 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 03:08:37 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 28 03:08:38 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:39 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:39 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:39 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:39 volumio-tv ntpd[998]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 28 03:08:39 volumio-tv ntpd[998]: DNS: dns_check: DNS error: -11, System error May 28 03:08:39 volumio-tv ntpd[998]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 May 28 03:08:40 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -110 May 28 03:08:40 volumio-tv ntpd[998]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 03:08:40 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 28 03:08:40 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 28 03:08:40 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 28 03:08:40 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:40+02:00" level=info msg="zeroconf server listening on port 40289" May 28 03:08:41 volumio-tv volumio[2144]: info: New Spotify access tokenBQA1GoV4Fw... May 28 03:08:41 volumio-tv volumio[2144]: info: Spotify credentials grant success - running version from March 24, 2019 May 28 03:08:41 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:42 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:43 volumio-tv volumio[2144]: info: BOOT COMPLETED May 28 03:08:43 volumio-tv volumio[2144]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=dbf75214656688914e4adc4644a6527e" http://updates.volumio.org/downloader-v1/track-device May 28 03:08:43 volumio-tv volumio[2144]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 03:08:43 volumio-tv volumio[2144]: Dload Upload Total Spent Left Speed May 28 03:08:43 volumio-tv volumio[2144]: [3.6K blob data] May 28 03:08:43 volumio-tv volumio[2144]: retrying in 5 seconds, trial 0 May 28 03:08:43 volumio-tv volumio[2144]: info: Volumio Calling Home May 28 03:08:43 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:44 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -110 May 28 03:08:45 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:47 volumio-tv ntpd[998]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 28 03:08:47 volumio-tv ntpd[998]: DNS: Pool taking: 152.53.15.127 May 28 03:08:47 volumio-tv ntpd[998]: DNS: Pool taking: 91.206.8.36 May 28 03:08:47 volumio-tv ntpd[998]: DNS: Pool taking: 83.215.130.11 May 28 03:08:47 volumio-tv ntpd[998]: DNS: Pool taking: 37.252.189.117 May 28 03:08:47 volumio-tv ntpd[998]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 28 03:08:47 volumio-tv go-librespot[2688]: time="2026-05-28T03:08:47+02:00" level=debug msg="new websocket client" May 28 03:08:47 volumio-tv volumio[2144]: info: Connection to go-librespot Websocket established May 28 03:08:47 volumio-tv volumio[2144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 28 03:08:49 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:49 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:08:49 volumio-tv volumio[2144]: info: Listing playlists May 28 03:08:49 volumio-tv volumio[2144]: info: Listing playlists May 28 03:08:50 volumio-tv kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -110 May 28 03:08:50 volumio-tv volumio[2144]: info: Getting Spotify volume May 28 03:08:52 volumio-tv volumio[2144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 28 03:08:53 volumio-tv volumio[2144]: info: Volumio called home May 28 03:08:58 volumio-tv volumio[2144]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 28 03:08:59 volumio-tv volumio[2144]: SPOTIFY: User informations: {"account_id":"loqi8yfI65","country":"AT","display_name":"martin.weilhartner","email":"martin.weilhartner@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31c2dvramrfsua5u3iooqpjtgvc4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31c2dvramrfsua5u3iooqpjtgvc4","id":"31c2dvramrfsua5u3iooqpjtgvc4","images":[],"product":"premium","type":"user","uri":"spotify:user:31c2dvramrfsua5u3iooqpjtgvc4"} May 28 03:08:59 volumio-tv volumio[2144]: info: Spotify Successfully logged in May 28 03:08:59 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 03:08:59 volumio-tv volumio[2144]: info: [1779930539238] CoreMusicLibrary::Adding element Spotify May 28 03:08:59 volumio-tv volumio[2144]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 03:08:59 volumio-tv volumio[2144]: Cannot find translation for source Spotify May 28 03:08:59 volumio-tv volumio[2144]: info: CoreCommandRouter::volumioGetState May 28 03:08:59 volumio-tv volumio[2144]: info: CorePlayQueue::getTrack 0 May 28 03:09:01 volumio-tv go-librespot[2688]: time="2026-05-28T03:09:01+02: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\": unexpected EOF" May 28 03:09:01 volumio-tv systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 28 03:09:01 volumio-tv systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 28 03:09:01 volumio-tv volumio[2144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 03:09:01 volumio-tv volumio[2144]: Error: socket hang up May 28 03:09:01 volumio-tv volumio[2144]: at connResetException (node:internal/errors:720:14) May 28 03:09:01 volumio-tv volumio[2144]: at Socket.socketOnEnd (node:_http_client:519:23) May 28 03:09:01 volumio-tv volumio[2144]: at Socket.emit (node:events:526:35) May 28 03:09:01 volumio-tv volumio[2144]: at endReadableNT (node:internal/streams/readable:1376:12) May 28 03:09:01 volumio-tv volumio[2144]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 28 03:09:01 volumio-tv volumio[2144]: code: 'ECONNRESET', May 28 03:09:01 volumio-tv volumio[2144]: response: undefined May 28 03:09:01 volumio-tv volumio[2144]: } May 28 03:09:01 volumio-tv volumio[2144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 03:09:04 volumio-tv systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 28 03:09:04 volumio-tv systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 28 03:09:04 volumio-tv systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 28 03:09:04 volumio-tv go-librespot[2774]: go-librespot daemon starting... May 28 03:09:04 volumio-tv go-librespot[2776]: time="2026-05-28T03:09:04+02:00" level=info msg="running go-librespot 0.6.2" May 28 03:09:04 volumio-tv go-librespot[2776]: time="2026-05-28T03:09:04+02:00" level=debug msg="app state loaded" May 28 03:09:04 volumio-tv go-librespot[2776]: time="2026-05-28T03:09:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 28 03:09:04 volumio-tv sudo[2783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 03:08' May 28 03:09:04 volumio-tv sudo[2783]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"