Feb 25 12:21:00 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:01 volumio sudo[4649]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 12:21:01 volumio sudo[4649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:21:01 volumio sudo[4651]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 12:21:01 volumio sudo[4649]: pam_unix(sudo:session): session closed for user root Feb 25 12:21:01 volumio sudo[4651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:21:01 volumio sudo[4651]: pam_unix(sudo:session): session closed for user root Feb 25 12:21:01 volumio volumio[1372]: verbose: New Socket.io Connection to 192.168.0.87 from 192.168.0.23 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Feb 25 12:21:01 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:01 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 12:21:01 volumio volumio[1372]: info: Received Get System Info Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 12:21:01 volumio volumio[1372]: info: Discovery: Getting this device information Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:01 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:01 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:01 volumio volumio[1372]: info: Listing playlists Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 12:21:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 12:21:02 volumio sudo[4655]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 12:21:02 volumio sudo[4655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:21:02 volumio sudo[4655]: pam_unix(sudo:session): session closed for user root Feb 25 12:21:02 volumio sudo[4657]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 12:21:02 volumio sudo[4657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:21:02 volumio sudo[4657]: pam_unix(sudo:session): session closed for user root Feb 25 12:21:02 volumio volumio[1372]: verbose: New Socket.io Connection to 192.168.0.87 from 192.168.0.23 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:02 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 12:21:02 volumio volumio[1372]: info: Received Get System Info Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 12:21:02 volumio volumio[1372]: info: Discovery: Getting this device information Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:02 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:02 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:02 volumio volumio[1372]: info: Listing playlists Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 12:21:02 volumio go-librespot[2512]: time="2026-02-25T12:21:02+10:00" level=debug msg="fetched chunk 10/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:21:02 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 12:21:03 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:04 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 25 12:21:04 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 12:21:04 volumio volumio[1372]: info: Received Get System Info Feb 25 12:21:04 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 12:21:04 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 12:21:04 volumio volumio[1372]: info: Discovery: Getting this device information Feb 25 12:21:04 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:04 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:04 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 12:21:04 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:05 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:06 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 12:21:06 volumio volumio[1372]: info: Received Get System Info Feb 25 12:21:06 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 12:21:06 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 12:21:06 volumio volumio[1372]: info: Discovery: Getting this device information Feb 25 12:21:06 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:06 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:06 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 12:21:06 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:07 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:09 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:10 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:11 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:12 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 12:21:12 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:13 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:14 volumio ntpd[1084]: PROTO: 220.158.215.20 unlink local addr 192.168.0.87 -> Feb 25 12:21:15 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:15 volumio go-librespot[2512]: time="2026-02-25T12:21:15+10:00" level=debug msg="fetched chunk 11/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:21:16 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:17 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:18 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:19 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:21 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 12:21:21 volumio volumio[1372]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 25 12:21:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 25 12:21:21 volumio volumio[1372]: info: Received Get System Version Feb 25 12:21:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 12:21:21 volumio volumio[1372]: info: Received Get System Info Feb 25 12:21:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 12:21:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 12:21:21 volumio volumio[1372]: info: Discovery: Getting this device information Feb 25 12:21:21 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Feb 25 12:21:21 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 12:21:22 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:22 volumio go-librespot[2512]: time="2026-02-25T12:21:22+10:00" level=trace msg="received accesspoint ping" Feb 25 12:21:22 volumio go-librespot[2512]: time="2026-02-25T12:21:22+10:00" level=trace msg="received accesspoint pong ack" Feb 25 12:21:22 volumio ntpd[1084]: PROTO: 103.126.53.123 unlink local addr 192.168.0.87 -> Feb 25 12:21:23 volumio go-librespot[2512]: time="2026-02-25T12:21:23+10:00" level=trace msg="sent dealer ping" Feb 25 12:21:23 volumio go-librespot[2512]: time="2026-02-25T12:21:23+10:00" level=trace msg="received dealer pong" Feb 25 12:21:23 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:24 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:25 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:27 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:27 volumio volumio[1372]: info: VolumeController::SetAlsaVolume55 Feb 25 12:21:27 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:27 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:27 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:27 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 80 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 55 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:27 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 55 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 25 12:21:27 volumio volumio[1372]: info: VolumeController::SetAlsaVolume59 Feb 25 12:21:27 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:27 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:27 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:27 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 55 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 59 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:27 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 59 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Feb 25 12:21:27 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Feb 25 12:21:28 volumio volumio[1372]: info: VolumeController::SetAlsaVolume60 Feb 25 12:21:28 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:28 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:28 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:28 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:28 volumio go-librespot[2512]: time="2026-02-25T12:21:28+10:00" level=debug msg="fetched chunk 12/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 59 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 60 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 59 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 60 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 59 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 60 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 25 12:21:28 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:28 volumio volumio[1372]: info: VolumeController::SetAlsaVolume63 Feb 25 12:21:28 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:28 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:28 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:28 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 59 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 63 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:28 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 63 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Feb 25 12:21:28 volumio volumio[1372]: info: VolumeController::SetAlsaVolume66 Feb 25 12:21:28 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:28 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:28 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:28 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 63 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 66 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:28 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 66 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Feb 25 12:21:28 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Feb 25 12:21:29 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:30 volumio volumio[1372]: SPOTIFY: SETTING SPOTIFY VOLUME 66 Feb 25 12:21:30 volumio volumio[1372]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 12:21:30 volumio go-librespot[2512]: time="2026-02-25T12:21:30+10:00" level=debug msg="update volume requested to 43253/65535" Feb 25 12:21:30 volumio go-librespot[2512]: time="2026-02-25T12:21:30+10:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 25 12:21:30 volumio go-librespot[2512]: time="2026-02-25T12:21:30+10:00" level=trace msg="emitting websocket event: volume" Feb 25 12:21:30 volumio volumio[1372]: SPOTIFY: received: {"type":"volume","data":{"value":66,"max":100}} Feb 25 12:21:30 volumio volumio[1372]: SPOTIFY: RECEIVED SPOTIFY VOLUME 66 Feb 25 12:21:30 volumio volumio[1372]: SPOTIFY: received: {"type":"volume","data":{"value":66,"max":100}} Feb 25 12:21:30 volumio volumio[1372]: SPOTIFY: RECEIVED SPOTIFY VOLUME 66 Feb 25 12:21:30 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:32 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 12:21:33 volumio volumio[1372]: info: Getting Alsa Cards List without I2S DAC Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 12:21:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 12:21:33 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:34 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:35 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:36 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:38 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:38 volumio volumio[1372]: info: VolumeController::SetAlsaVolume62 Feb 25 12:21:38 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:38 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:38 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:38 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:38 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Feb 25 12:21:38 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 66 Feb 25 12:21:38 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 62 Feb 25 12:21:38 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:38 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 62 Feb 25 12:21:38 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Feb 25 12:21:38 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Feb 25 12:21:39 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:39 volumio volumio[1372]: info: VolumeController::SetAlsaVolume52 Feb 25 12:21:39 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:39 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:39 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:39 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 62 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 52 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:39 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 52 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Feb 25 12:21:39 volumio volumio[1372]: info: VolumeController::SetAlsaVolume43 Feb 25 12:21:39 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:39 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:39 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:39 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 52 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 43 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:39 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 43 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Feb 25 12:21:39 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Feb 25 12:21:39 volumio volumio[1372]: info: VolumeController::SetAlsaVolume38 Feb 25 12:21:39 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:39 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:39 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:39 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 43 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 38 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:40 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 38 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Feb 25 12:21:40 volumio go-librespot[2512]: time="2026-02-25T12:21:40+10:00" level=debug msg="fetched chunk 13/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:21:40 volumio volumio[1372]: info: VolumeController::SetAlsaVolume23 Feb 25 12:21:40 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:40 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:40 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:40 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 38 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 23 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:40 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 23 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 Feb 25 12:21:40 volumio volumio[1372]: info: VolumeController::SetAlsaVolume14 Feb 25 12:21:40 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:40 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:40 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:40 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 23 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 14 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:40 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 14 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Feb 25 12:21:40 volumio volumio[1372]: info: VolumeController::SetAlsaVolume4 Feb 25 12:21:40 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:40 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:40 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:40 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 4 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 14 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 4 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:40 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 4 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 4 Feb 25 12:21:40 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 4 Feb 25 12:21:41 volumio volumio[1372]: info: VolumeController::SetAlsaVolume-1 Feb 25 12:21:41 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:41 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:41 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:41 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:41 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:41 volumio volumio[1372]: info: VolumeController::SetAlsaVolume5 Feb 25 12:21:41 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:41 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:41 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:41 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 4 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 5 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 4 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 5 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 4 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 5 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 25 12:21:42 volumio volumio[1372]: info: VolumeController::SetAlsaVolume15 Feb 25 12:21:42 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:42 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 4 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 15 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:42 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 15 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Feb 25 12:21:42 volumio volumio[1372]: info: VolumeController::SetAlsaVolume19 Feb 25 12:21:42 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:42 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 15 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 19 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:42 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 19 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Feb 25 12:21:42 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:42 volumio volumio[1372]: info: VolumeController::SetAlsaVolume29 Feb 25 12:21:42 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:42 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 19 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 29 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:42 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 29 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 25 12:21:42 volumio volumio[1372]: info: VolumeController::SetAlsaVolume38 Feb 25 12:21:42 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:42 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:42 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 29 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 38 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:42 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 38 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Feb 25 12:21:42 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Feb 25 12:21:43 volumio volumio[1372]: info: VolumeController::SetAlsaVolume43 Feb 25 12:21:43 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:43 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:43 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:43 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 38 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 43 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:43 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 43 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Feb 25 12:21:43 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:43 volumio volumio[1372]: info: VolumeController::SetAlsaVolume47 Feb 25 12:21:43 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:43 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:43 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:43 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 43 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 47 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:43 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 47 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Feb 25 12:21:43 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Feb 25 12:21:43 volumio volumio[1372]: info: VolumeController::SetAlsaVolume52 Feb 25 12:21:43 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:43 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:43 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:43 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 47 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 52 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:44 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 52 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Feb 25 12:21:44 volumio volumio[1372]: info: VolumeController::SetAlsaVolume62 Feb 25 12:21:44 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:44 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:44 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:44 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 52 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 62 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:44 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 62 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Feb 25 12:21:44 volumio volumio[1372]: info: VolumeController::SetAlsaVolume71 Feb 25 12:21:44 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:44 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:44 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:44 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 62 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 71 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:44 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 71 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:21:44 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:21:44 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:45 volumio volumio[1372]: info: VolumeController::SetAlsaVolume76 Feb 25 12:21:45 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:45 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:45 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:45 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:45 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -5 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 71 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 76 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:45 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 76 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Feb 25 12:21:45 volumio volumio[1372]: info: VolumeController::SetAlsaVolume86 Feb 25 12:21:45 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:45 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:45 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:45 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 76 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 80 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:45 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 80 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:45 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:46 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:46 volumio volumio[1372]: info: VolumeController::SetAlsaVolume84 Feb 25 12:21:46 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:46 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:46 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:46 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:46 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:46 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:46 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:47 volumio volumio[1372]: info: VolumeController::SetAlsaVolume85 Feb 25 12:21:47 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:47 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:47 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:47 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:47 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:47 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:47 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 25 12:21:47 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:47 volumio volumio[1372]: SPOTIFY: SETTING SPOTIFY VOLUME 80 Feb 25 12:21:47 volumio volumio[1372]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 12:21:47 volumio volumio[1372]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Feb 25 12:21:48 volumio volumio[1372]: info: VolumeController::SetAlsaVolume75 Feb 25 12:21:48 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:48 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:48 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:48 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 80 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 75 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:48 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 75 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 25 12:21:48 volumio volumio[1372]: info: VolumeController::SetAlsaVolume71 Feb 25 12:21:48 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:21:48 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:21:48 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:21:48 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: SPOTIFY VOLUME 75 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: VOLUMIO VOLUME 71 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 25 12:21:48 volumio volumio[1372]: info: Setting Spotify Volume from Volumio: 71 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:21:48 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:21:48 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:49 volumio volumio[1372]: SPOTIFY: SETTING SPOTIFY VOLUME 71 Feb 25 12:21:49 volumio volumio[1372]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 12:21:49 volumio go-librespot[2512]: time="2026-02-25T12:21:49+10:00" level=debug msg="update volume requested to 46529/65535" Feb 25 12:21:49 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:50 volumio go-librespot[2512]: time="2026-02-25T12:21:50+10:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 25 12:21:50 volumio go-librespot[2512]: time="2026-02-25T12:21:50+10:00" level=trace msg="emitting websocket event: volume" Feb 25 12:21:50 volumio volumio[1372]: SPOTIFY: received: {"type":"volume","data":{"value":71,"max":100}} Feb 25 12:21:50 volumio volumio[1372]: SPOTIFY: RECEIVED SPOTIFY VOLUME 71 Feb 25 12:21:50 volumio volumio[1372]: SPOTIFY: received: {"type":"volume","data":{"value":71,"max":100}} Feb 25 12:21:50 volumio volumio[1372]: SPOTIFY: RECEIVED SPOTIFY VOLUME 71 Feb 25 12:21:51 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:52 volumio go-librespot[2512]: time="2026-02-25T12:21:52+10:00" level=debug msg="fetched chunk 14/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:21:52 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:53 volumio go-librespot[2512]: time="2026-02-25T12:21:53+10:00" level=trace msg="sent dealer ping" Feb 25 12:21:53 volumio go-librespot[2512]: time="2026-02-25T12:21:53+10:00" level=trace msg="received dealer pong" Feb 25 12:21:54 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:55 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:56 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:57 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:58 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:21:59 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:01 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:02 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:02 volumio volumio[1372]: info: CoreCommandRouter::volumioPause Feb 25 12:22:02 volumio volumio[1372]: info: CoreStateMachine::pause Feb 25 12:22:02 volumio volumio[1372]: info: CoreStateMachine::stPlaybackTimer Feb 25 12:22:02 volumio volumio[1372]: info: CoreStateMachine::servicePause Feb 25 12:22:02 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:02 volumio volumio[1372]: info: CoreCommandRouter::servicePause Feb 25 12:22:02 volumio volumio[1372]: info: Spotify Received pause Feb 25 12:22:02 volumio volumio[1372]: SPOTIFY: SPOTIFY PAUSE Feb 25 12:22:02 volumio volumio[1372]: SPOTIFY: {"status":"play","position":5,"title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d0000b273bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","codec":"ogg","seek":131855,"duration":268,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":71,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Feb 25 12:22:02 volumio volumio[1372]: info: Sending Spotify command to local API: /player/pause Feb 25 12:22:02 volumio go-librespot[2512]: time="2026-02-25T12:22:02+10:00" level=debug msg="pause track at 146807ms" Feb 25 12:22:02 volumio go-librespot[2512]: time="2026-02-25T12:22:02+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 25 12:22:03 volumio go-librespot[2512]: time="2026-02-25T12:22:03+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 25 12:22:03 volumio go-librespot[2512]: time="2026-02-25T12:22:03+10:00" level=trace msg="emitting websocket event: paused" Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:0fJdOcZ4Wp3zTBjlKXlGr5","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","play_origin":"go-librespot"}} Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":147001,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:03 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Feb 25 12:22:03 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:03 volumio volumio[1372]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":147001,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:03 volumio volumio[1372]: verbose: CURRENT POSITION 5 Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::syncState stateService pause Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus pause Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:03 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:03 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:22:03 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::stPlaybackTimer Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:0fJdOcZ4Wp3zTBjlKXlGr5","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","play_origin":"go-librespot"}} Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":147001,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:03 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Feb 25 12:22:03 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:03 volumio volumio[1372]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":147001,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:03 volumio volumio[1372]: verbose: CURRENT POSITION 5 Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::syncState stateService pause Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus pause Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:03 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:03 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:03 volumio volumio[1372]: info: CoreStateMachine::stPlaybackTimer Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:03 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:03 volumio volumio[1372]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Feb 25 12:22:03 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:04 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:05 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:07 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:08 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:09 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:10 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:11 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:13 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::volumioSeek Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::seek Feb 25 12:22:14 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:14 volumio volumio[1372]: info: TRACKBLOCK {"uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","service":"spop","name":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","type":"song","duration":268,"albumart":"https://i.scdn.co/image/ab67616d0000b273bd569217f48c0d942c615d15","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify","channels":2} Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::startPlaybackTimer Feb 25 12:22:14 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:14 volumio volumio[1372]: info: Spotify seek to: 234000 Feb 25 12:22:14 volumio volumio[1372]: info: Sending Spotify command with payload to local API: /player/seek Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:14 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=debug msg="seek track to 234000ms" Feb 25 12:22:14 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=debug msg="fetched chunk 19/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=trace msg="seek to 234000ms (diff: 29ms, samples: 10319400, bytes: 10188652)" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=debug msg="fetched chunk 20/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=debug msg="fetched chunk 21/22, size: 524288" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=debug msg="fetched chunk 22/22, size: 98228" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 25 12:22:14 volumio go-librespot[2512]: time="2026-02-25T12:22:14+10:00" level=trace msg="emitting websocket event: seek" Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:0fJdOcZ4Wp3zTBjlKXlGr5","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","position":234000,"duration":268626,"play_origin":"go-librespot"}} Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":234000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Feb 25 12:22:14 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:14 volumio volumio[1372]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":234000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:14 volumio volumio[1372]: verbose: CURRENT POSITION 5 Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::syncState stateService pause Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus pause Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:14 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::stPlaybackTimer Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:0fJdOcZ4Wp3zTBjlKXlGr5","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","position":234000,"duration":268626,"play_origin":"go-librespot"}} Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":234000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Feb 25 12:22:14 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:14 volumio volumio[1372]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":234000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:14 volumio volumio[1372]: verbose: CURRENT POSITION 5 Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::syncState stateService pause Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus pause Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:14 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:14 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:14 volumio volumio[1372]: info: CoreStateMachine::stPlaybackTimer Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:14 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:15 volumio volumio[1372]: info: CoreCommandRouter::volumioSeek Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::seek Feb 25 12:22:15 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:15 volumio volumio[1372]: info: TRACKBLOCK {"uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","service":"spop","name":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","type":"song","duration":268,"albumart":"https://i.scdn.co/image/ab67616d0000b273bd569217f48c0d942c615d15","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify","channels":2} Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::startPlaybackTimer Feb 25 12:22:15 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:15 volumio volumio[1372]: info: Spotify seek to: 230000 Feb 25 12:22:15 volumio volumio[1372]: info: Sending Spotify command with payload to local API: /player/seek Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:15 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:15 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 12:22:15 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio go-librespot[2512]: time="2026-02-25T12:22:15+10:00" level=debug msg="seek track to 230000ms" Feb 25 12:22:15 volumio go-librespot[2512]: time="2026-02-25T12:22:15+10:00" level=trace msg="seek to 230000ms (diff: 124ms, samples: 10143000, bytes: 10005575)" uri="spotify:track:5PRN8ql17R8rcKnHo6Ajlk" Feb 25 12:22:15 volumio go-librespot[2512]: time="2026-02-25T12:22:15+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 25 12:22:15 volumio go-librespot[2512]: time="2026-02-25T12:22:15+10:00" level=trace msg="emitting websocket event: seek" Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:0fJdOcZ4Wp3zTBjlKXlGr5","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","position":230000,"duration":268626,"play_origin":"go-librespot"}} Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":230000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:15 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Feb 25 12:22:15 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:15 volumio volumio[1372]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":230000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:15 volumio volumio[1372]: verbose: CURRENT POSITION 5 Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::syncState stateService pause Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus pause Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:15 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:15 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::stPlaybackTimer Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:0fJdOcZ4Wp3zTBjlKXlGr5","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","position":230000,"duration":268626,"play_origin":"go-librespot"}} Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: PUSH STATE SPOTIFY Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":230000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:15 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Feb 25 12:22:15 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:15 volumio volumio[1372]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Feeding Line","artist":"Boy & Bear","album":"Moonfire","albumart":"https://i.scdn.co/image/ab67616d00001e02bd569217f48c0d942c615d15","uri":"spotify:track:5PRN8ql17R8rcKnHo6Ajlk","trackType":"spotify","seek":230000,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 25 12:22:15 volumio volumio[1372]: verbose: CURRENT POSITION 5 Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::syncState stateService pause Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus pause Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::pushState Feb 25 12:22:15 volumio volumio[1372]: info: CorePlayQueue::getTrack 5 Feb 25 12:22:15 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Feb 25 12:22:15 volumio volumio[1372]: info: CoreStateMachine::stPlaybackTimer Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio volumio[1372]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 Feb 25 12:22:15 volumio ntpd[1084]: PROTO: 27.124.125.250 unlink local addr 192.168.0.87 -> Feb 25 12:22:16 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 12:22:16 volumio volumio[1372]: info: Getting Alsa Cards List without I2S DAC Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 12:22:16 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 12:22:17 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:18 volumio ntpd[1084]: PROTO: 162.159.200.1 unlink local addr 192.168.0.87 -> Feb 25 12:22:18 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:19 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:21 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 12:22:21 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 12:22:22 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:23 volumio go-librespot[2512]: time="2026-02-25T12:22:23+10:00" level=trace msg="sent dealer ping" Feb 25 12:22:23 volumio go-librespot[2512]: time="2026-02-25T12:22:23+10:00" level=trace msg="received dealer pong" Feb 25 12:22:23 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:24 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:25 volumio sudo[4854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 25 12:22:25 volumio sudo[4854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:22:25 volumio sudo[4856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 25 12:22:25 volumio sudo[4856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:22:25 volumio sudo[4854]: pam_unix(sudo:session): session closed for user root Feb 25 12:22:25 volumio sudo[4858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 25 12:22:25 volumio sudo[4858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:22:25 volumio volumio[1372]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 25 12:22:25 volumio volumio[1372]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 12:22:25 volumio sudo[4861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 25 12:22:25 volumio sudo[4861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:22:25 volumio volumio[1372]: TypeError: Cannot read properties of undefined (reading 'split') Feb 25 12:22:25 volumio volumio[1372]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Feb 25 12:22:25 volumio volumio[1372]: at ChildProcess.exithandler (node:child_process:413:7) Feb 25 12:22:25 volumio volumio[1372]: at ChildProcess.emit (node:events:514:28) Feb 25 12:22:25 volumio volumio[1372]: at maybeClose (node:internal/child_process:1105:16) Feb 25 12:22:25 volumio volumio[1372]: at Socket. (node:internal/child_process:457:11) Feb 25 12:22:25 volumio volumio[1372]: at Socket.emit (node:events:514:28) Feb 25 12:22:25 volumio volumio[1372]: at Pipe. (node:net:337:12) Feb 25 12:22:25 volumio volumio[1372]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 12:22:25 volumio systemd[1]: Reloading. Feb 25 12:22:26 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Feb 25 12:22:26 volumio sudo[4896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 12:21' Feb 25 12:22:26 volumio sudo[4896]: 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"