Aug 26 00:50:00 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:00 volumio kernel: hwmon hwmon1: Voltage normalised Aug 26 00:50:01 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:02 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:03 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:04 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:04 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 26 00:50:06 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:07 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:09 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:10 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:11 volumio kernel: hwmon hwmon1: Voltage normalised Aug 26 00:50:11 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:12 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:13 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:14 volumio volumio[3044]: verbose: New Socket.io Connection to 192.168.0.87:3000 from 192.168.0.86 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 00:50:14 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:50:14 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:50:14 volumio volumio[3044]: info: Discovery: Getting this device information Aug 26 00:50:14 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:14 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:14 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:50:15 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:15 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 26 00:50:16 volumio volumio[3044]: verbose: New Socket.io Connection to 192.168.0.87 from 192.168.0.86 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:16 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 00:50:16 volumio volumio[3044]: info: Received Get System Info Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:50:16 volumio volumio[3044]: info: Discovery: Getting this device information Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:16 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:16 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:16 volumio volumio[3044]: info: Listing playlists Aug 26 00:50:16 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:16 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 00:50:17 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:18 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:20 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:20 volumio volumio[3044]: info: CoreCommandRouter::volumioPlay Aug 26 00:50:20 volumio volumio[3044]: info: CoreStateMachine::play index undefined Aug 26 00:50:20 volumio volumio[3044]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 00:50:20 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:20 volumio volumio[3044]: info: CoreStateMachine::startPlaybackTimer Aug 26 00:50:20 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:20 volumio volumio[3044]: info: [1787669420886] ControllerSpotify::clearAddPlayTrack Aug 26 00:50:20 volumio volumio[3044]: info: Sending Spotify command with payload to local API: /player/play Aug 26 00:50:21 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:22 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:23 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:24 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:25 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:25 volumio volumio[3044]: info: VolumeController::SetAlsaVolume35 Aug 26 00:50:25 volumio volumio[3044]: info: CoreStateMachine::pushState Aug 26 00:50:25 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:25 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 00:50:25 volumio volumio[3044]: info: CoreCommandRouter::volumioPushState Aug 26 00:50:25 volumio volumio[3044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Aug 26 00:50:25 volumio volumio[3044]: SPOTIFY: SPOTIFY VOLUME 30 Aug 26 00:50:25 volumio volumio[3044]: SPOTIFY: VOLUMIO VOLUME 35 Aug 26 00:50:25 volumio volumio[3044]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 00:50:25 volumio volumio[3044]: info: Setting Spotify Volume from Volumio: 35 Aug 26 00:50:26 volumio volumio[3044]: info: VolumeController::SetAlsaVolume45 Aug 26 00:50:26 volumio volumio[3044]: info: CoreStateMachine::pushState Aug 26 00:50:26 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:26 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 00:50:26 volumio volumio[3044]: info: CoreCommandRouter::volumioPushState Aug 26 00:50:26 volumio volumio[3044]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 26 00:50:26 volumio volumio[3044]: SPOTIFY: SPOTIFY VOLUME 35 Aug 26 00:50:26 volumio volumio[3044]: SPOTIFY: VOLUMIO VOLUME 45 Aug 26 00:50:26 volumio volumio[3044]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 26 00:50:26 volumio volumio[3044]: info: Setting Spotify Volume from Volumio: 45 Aug 26 00:50:27 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:28 volumio volumio[3044]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Aug 26 00:50:28 volumio volumio[3044]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 00:50:28 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:30 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:31 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:33 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:34 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:35 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:36 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:37 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:38 volumio kernel: hwmon hwmon1: Voltage normalised Aug 26 00:50:38 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:39 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:40 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 26 00:50:41 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:41 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 00:50:42 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:43 volumio volumio[3044]: info: CoreCommandRouter::Close All Modals sent Aug 26 00:50:43 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:44 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:46 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:46 volumio volumio[3044]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 00:50:47 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:48 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:49 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:50 volumio kernel: hwmon hwmon1: Voltage normalised Aug 26 00:50:51 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:52 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:53 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:54 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:54 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 26 00:50:55 volumio volumio[3044]: info: Received OAUTH Data Aug 26 00:50:55 volumio volumio[3044]: info: Executing Spotify Oauth Login Aug 26 00:50:55 volumio volumio[3044]: info: Saving Spotify Refresh Token Aug 26 00:50:55 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:56 volumio sudo[3671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 00:50:56 volumio volumio[3044]: info: New Spotify access tokenBQCwLMy0Ft... Aug 26 00:50:56 volumio volumio[3044]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 26 00:50:56 volumio sudo[3671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:50:56 volumio sudo[3671]: pam_unix(sudo:session): session closed for user root Aug 26 00:50:56 volumio sudo[3673]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 00:50:56 volumio sudo[3673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:50:56 volumio sudo[3673]: pam_unix(sudo:session): session closed for user root Aug 26 00:50:56 volumio volumio[3044]: verbose: New Socket.io Connection to 192.168.0.87 from 192.168.0.86 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 26 00:50:56 volumio volumio[3044]: SPOTIFY: User informations: {"account_id":"qqkDUiI0bZ","country":"AU","display_name":"Rohan","email":"rohanwalder@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1259087947"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/1259087947","id":"1259087947","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee859a6d2532d7f38d0c446f1b30","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b829a6d2532d7f38d0c446f1b30","width":64}],"product":"premium","type":"user","uri":"spotify:user:1259087947"} Aug 26 00:50:56 volumio volumio[3044]: info: Creating Spotify config file Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:50:56 volumio volumio[3044]: info: Spotify config file written Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 00:50:56 volumio sudo[3677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 26 00:50:56 volumio sudo[3677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:56 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 00:50:56 volumio volumio[3044]: info: Received Get System Info Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:50:56 volumio volumio[3044]: info: Discovery: Getting this device information Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:56 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:56 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:56 volumio volumio[3044]: info: Listing playlists Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 00:50:56 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 26 00:50:56 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 00:50:56 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 26 00:50:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:50:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:50:56 volumio go-librespot[3679]: go-librespot daemon starting... Aug 26 00:50:56 volumio sudo[3677]: pam_unix(sudo:session): session closed for user root Aug 26 00:50:56 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:56 volumio go-librespot[3682]: time="2026-08-26T00:50:56+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:50:56 volumio go-librespot[3682]: time="2026-08-26T00:50:56+10:00" level=debug msg="app state loaded" Aug 26 00:50:57 volumio go-librespot[3682]: time="2026-08-26T00:50:57+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:50:57 volumio volumio[3044]: info: Connection to go-librespot Websocket closed Aug 26 00:50:57 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 00:50:57 volumio volumio[3044]: info: New Spotify access tokenBQBulPZK91... Aug 26 00:50:57 volumio volumio[3044]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 26 00:50:57 volumio go-librespot[3682]: time="2026-08-26T00:50:57+10:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 00:50:57 volumio go-librespot[3682]: time="2026-08-26T00:50:57+10:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 00:50:57 volumio go-librespot[3682]: time="2026-08-26T00:50:57+10:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 00:50:57 volumio go-librespot[3682]: time="2026-08-26T00:50:57+10:00" level=info msg="zeroconf server listening on port 44965" Aug 26 00:50:57 volumio volumio[3044]: SPOTIFY: User informations: {"account_id":"qqkDUiI0bZ","country":"AU","display_name":"Rohan","email":"rohanwalder@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1259087947"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/1259087947","id":"1259087947","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee859a6d2532d7f38d0c446f1b30","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b829a6d2532d7f38d0c446f1b30","width":64}],"product":"premium","type":"user","uri":"spotify:user:1259087947"} Aug 26 00:50:57 volumio volumio[3044]: info: Spotify Successfully logged in Aug 26 00:50:57 volumio volumio[3044]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 00:50:57 volumio volumio[3044]: info: [1787669457635] CoreMusicLibrary::Adding element Spotify Aug 26 00:50:57 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 00:50:57 volumio volumio[3044]: Cannot find translation for source Spotify Aug 26 00:50:57 volumio go-librespot[3682]: time="2026-08-26T00:50:57+10:00" level=debug msg="obtained new client token: AAEk72F5iH3MwuB4507+UuzkNgNn7KFYWh5uyIw4lLnxG4Og8pYRt82hAhcfg/Mlx5u+qauXXeRQ+ZFZ5ZrEEFdgO3/qm1gbUTVfAQEBL05AHi+mkGvmFrtJheX5qrW6RoRGNAu98yUvbI8dC5NBDWncz0QXLMe3yeNiqmxxW83qJwLQ7nO0DFuhSJs7uZ3whGIicA71ZrSbb3MG1uUETT+6CiLXOVS5OMuCRwyLdpLLjjQCuLYAsUxjug==" Aug 26 00:50:58 volumio go-librespot[3682]: time="2026-08-26T00:50:58+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:50:58 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:58 volumio go-librespot[3682]: time="2026-08-26T00:50:58+10:00" level=debug msg="completed keyexchange" Aug 26 00:50:58 volumio go-librespot[3682]: time="2026-08-26T00:50:58+10:00" level=debug msg="completed challenge" Aug 26 00:50:58 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 00:50:58 volumio volumio[3044]: info: Received Get System Info Aug 26 00:50:58 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:50:58 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:50:58 volumio volumio[3044]: info: Discovery: Getting this device information Aug 26 00:50:58 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:50:58 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:50:58 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:50:58 volumio go-librespot[3682]: time="2026-08-26T00:50:58+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:50:58 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 00:50:58 volumio go-librespot[3682]: time="2026-08-26T00:50:58+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:50:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:50:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:50:58 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 26 00:50:58 volumio volumio[3044]: info: MyVolumio login type: Token Aug 26 00:50:59 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:50:59 volumio volumio[3044]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 26 00:51:00 volumio volumio[3044]: info: Initializing connection to go-librespot Websocket Aug 26 00:51:00 volumio volumio[3044]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 00:51:00 volumio volumio[3044]: info: go-librespot daemon successfully initialized Aug 26 00:51:00 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 00:51:00 volumio volumio[3044]: info: Received Get System Info Aug 26 00:51:00 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:51:00 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:51:00 volumio volumio[3044]: info: Discovery: Getting this device information Aug 26 00:51:00 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:51:00 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:51:00 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:51:00 volumio volumio[3044]: info: MyVolumio token set successfully Aug 26 00:51:00 volumio volumio[3044]: info: MYVOLUMIO: Adding device Aug 26 00:51:00 volumio volumio[3044]: info: MYVOLUMIO: Evaluating Server Aug 26 00:51:00 volumio volumio[3044]: info: MyVolumio status changed Aug 26 00:51:00 volumio volumio[3044]: info: Streaming services startup Aug 26 00:51:00 volumio volumio[3044]: info: Starting Streaming Daemon Aug 26 00:51:00 volumio volumio[3044]: info: Removing browser output: myVolumio user plan is not superstar Aug 26 00:51:00 volumio volumio[3044]: info: Removing audio output: Aug 26 00:51:00 volumio volumio[3044]: info: Stoppping Tunnel 1 Aug 26 00:51:00 volumio volumio[3044]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Aug 26 00:51:00 volumio sudo[3712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 00:51:00 volumio sudo[3712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:51:00 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Aug 26 00:51:00 volumio volumio[3044]: info: Creating Spotify config file Aug 26 00:51:00 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 00:51:00 volumio sudo[3714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 26 00:51:00 volumio sudo[3714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:51:01 volumio volumio[3044]: info: Spotify config file written Aug 26 00:51:01 volumio sudo[3712]: pam_unix(sudo:session): session closed for user root Aug 26 00:51:01 volumio sudo[3718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 26 00:51:01 volumio sudo[3718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:51:01 volumio volumio[3044]: error: Cannot start Volumio Streaming Daemon Aug 26 00:51:01 volumio volumio[3044]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 26 00:51:01 volumio volumio[3044]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 00:51:01 volumio sudo[3714]: pam_unix(sudo:session): session closed for user root Aug 26 00:51:01 volumio volumio[3044]: info: Remote SSH Stopped Aug 26 00:51:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:51:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:51:01 volumio go-librespot[3720]: go-librespot daemon starting... Aug 26 00:51:01 volumio sudo[3718]: pam_unix(sudo:session): session closed for user root Aug 26 00:51:01 volumio volumio[3044]: info: Setting Geolocation for MyVolumio to au1 Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:51:01 volumio go-librespot[3721]: time="2026-08-26T00:51:01+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:51:01 volumio go-librespot[3721]: time="2026-08-26T00:51:01+10:00" level=debug msg="app state loaded" Aug 26 00:51:01 volumio go-librespot[3721]: time="2026-08-26T00:51:01+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:51:01 volumio go-librespot[3721]: time="2026-08-26T00:51:01+10:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 00:51:01 volumio go-librespot[3721]: time="2026-08-26T00:51:01+10:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 00:51:01 volumio go-librespot[3721]: time="2026-08-26T00:51:01+10:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 00:51:01 volumio go-librespot[3721]: time="2026-08-26T00:51:01+10:00" level=info msg="zeroconf server listening on port 36953" Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 00:51:01 volumio volumio[3044]: info: Received Get System Info Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:51:01 volumio volumio[3044]: info: Discovery: Getting this device information Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:51:01 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:51:01 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:51:02 volumio go-librespot[3721]: time="2026-08-26T00:51:02+10:00" level=debug msg="obtained new client token: AAH/AeBwoj4GbEu8f8rjeVwo6hkY6p6ym58fUB3L1WsOSO73gBNmDlqL91o4ORbImmNonX3nt7swoQRqMUsRNTEoDAEnTlbs9kEzJZq7rzAOKXYx83685GfhAHIvm9FK8pCzBA8S9UAXiVVBgcebw8ZxvqauK1VhvwFngeJyv4eS2B6m9McZ91b0sz8M4/32PbpLqvExT8Gpo38q/VVkW5H70mWEsuLbD3/9fVXytuV5fTOVngm9tq4=" Aug 26 00:51:02 volumio volumio[3044]: info: Updating MyVolumio device info Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 00:51:02 volumio go-librespot[3721]: time="2026-08-26T00:51:02+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 00:51:02 volumio volumio[3044]: info: Received Get System Info Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:51:02 volumio volumio[3044]: info: Discovery: Getting this device information Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::volumioGetState Aug 26 00:51:02 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:51:02 volumio volumio[3044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:51:02 volumio volumio[3044]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 26 00:51:02 volumio go-librespot[3721]: time="2026-08-26T00:51:02+10:00" level=debug msg="completed keyexchange" Aug 26 00:51:02 volumio go-librespot[3721]: time="2026-08-26T00:51:02+10:00" level=debug msg="completed challenge" Aug 26 00:51:02 volumio go-librespot[3721]: time="2026-08-26T00:51:02+10:00" level=info msg="authenticated AP" username="12******47" Aug 26 00:51:02 volumio volumio[3044]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 26 00:51:02 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:51:03 volumio volumio[3044]: info: Initializing connection to go-librespot Websocket Aug 26 00:51:03 volumio go-librespot[3721]: time="2026-08-26T00:51:03+10:00" level=debug msg="new websocket client" Aug 26 00:51:03 volumio volumio[3044]: info: Connection to go-librespot Websocket established Aug 26 00:51:03 volumio volumio[3044]: info: Initializing connection to go-librespot Websocket Aug 26 00:51:03 volumio volumio[3044]: info: Connection to go-librespot Websocket established Aug 26 00:51:03 volumio go-librespot[3721]: time="2026-08-26T00:51:03+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 00:51:03 volumio volumio[3044]: info: Connection to go-librespot Websocket closed Aug 26 00:51:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 00:51:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 00:51:03 volumio volumio[3044]: info: Connection to go-librespot Websocket closed Aug 26 00:51:04 volumio volumio[3044]: info: go-librespot daemon successfully initialized Aug 26 00:51:04 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:51:04 volumio volumio[3044]: info: CoreCommandRouter::volumioPlay Aug 26 00:51:04 volumio volumio[3044]: info: CoreStateMachine::play index undefined Aug 26 00:51:04 volumio volumio[3044]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 00:51:04 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:51:04 volumio volumio[3044]: info: CoreStateMachine::startPlaybackTimer Aug 26 00:51:04 volumio volumio[3044]: info: CorePlayQueue::getTrack 0 Aug 26 00:51:04 volumio volumio[3044]: info: [1787669464804] ControllerSpotify::clearAddPlayTrack Aug 26 00:51:04 volumio volumio[3044]: info: Sending Spotify command with payload to local API: /player/play Aug 26 00:51:04 volumio volumio[3044]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 00:51:05 volumio kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -110 Aug 26 00:51:06 volumio volumio[3044]: info: Getting Spotify volume Aug 26 00:51:06 volumio volumio[3044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 00:51:06 volumio volumio[3044]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 00:51:06 volumio volumio[3044]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 26 00:51:06 volumio volumio[3044]: errno: -111, Aug 26 00:51:06 volumio volumio[3044]: code: 'ECONNREFUSED', Aug 26 00:51:06 volumio volumio[3044]: syscall: 'connect', Aug 26 00:51:06 volumio volumio[3044]: address: '127.0.0.1', Aug 26 00:51:06 volumio volumio[3044]: port: 9879, Aug 26 00:51:06 volumio volumio[3044]: response: undefined Aug 26 00:51:06 volumio volumio[3044]: } Aug 26 00:51:06 volumio volumio[3044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 00:51:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 26 00:51:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:51:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 00:51:06 volumio go-librespot[3756]: go-librespot daemon starting... Aug 26 00:51:06 volumio go-librespot[3757]: time="2026-08-26T00:51:06+10:00" level=info msg="running go-librespot 0.6.2" Aug 26 00:51:06 volumio go-librespot[3757]: time="2026-08-26T00:51:06+10:00" level=debug msg="app state loaded" Aug 26 00:51:06 volumio go-librespot[3757]: time="2026-08-26T00:51:06+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 00:51:07 volumio go-librespot[3757]: time="2026-08-26T00:51:07+10:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 00:51:07 volumio go-librespot[3757]: time="2026-08-26T00:51:07+10:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 00:51:07 volumio go-librespot[3757]: time="2026-08-26T00:51:07+10:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 00:51:07 volumio go-librespot[3757]: time="2026-08-26T00:51:07+10:00" level=info msg="zeroconf server listening on port 38777" Aug 26 00:51:07 volumio go-librespot[3757]: time="2026-08-26T00:51:07+10:00" level=debug msg="obtained new client token: AAGk1+KC9Yfze29r/rXiEYn/ri1KxYPpHGlN7OXsQUd60gFdoSlJz1zTpJugR06EpqD9cQnKAxuZzJP9QWEnATtRjHr/RALpxi22xicfs9+cYV7EhcbYZ+TuvVnYKTNjco/eGFwHN87IbrrZsP1W3mBggRl+EKG+XDSI0Riya7rkNi5mkOUKlsYMI3eO8MjNkRRcBmCyq3QJ1nl0TeJZhR52ZEURm2TysWGrxS56R23cLA+RkytNnJAyNg==" Aug 26 00:51:07 volumio sudo[3768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 00:50' Aug 26 00:51:07 volumio sudo[3768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 00:51:07 volumio go-librespot[3757]: time="2026-08-26T00:51:07+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" 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"