Aug 28 15:53:03 kamo-volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 28 15:53:19 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 15:53:19 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 15:53:22 kamo-volumio go-librespot[1379]: time="2026-08-28T15:53:22+09:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Aug 28 15:53:22 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 28 15:53:22 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 15:53:22 kamo-volumio volumio[1055]: Cannot find translation for source YouTube2 Aug 28 15:53:22 kamo-volumio volumio[1055]: Cannot find translation for source YouTube Music Aug 28 15:53:22 kamo-volumio volumio[1055]: Cannot find translation for source 80s80s Radio Aug 28 15:53:22 kamo-volumio volumio[1055]: info: Disabling plugin spop Aug 28 15:53:22 kamo-volumio volumio[1055]: info: Done. Aug 28 15:53:22 kamo-volumio sudo[1725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Aug 28 15:53:22 kamo-volumio sudo[1725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:53:22 kamo-volumio volumio[1055]: info: Connection to go-librespot Websocket closed Aug 28 15:53:22 kamo-volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 28 15:53:22 kamo-volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 28 15:53:22 kamo-volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:53:22 kamo-volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 28 15:53:22 kamo-volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 28 15:53:22 kamo-volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 28 15:53:22 kamo-volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 28 15:53:22 kamo-volumio sudo[1725]: pam_unix(sudo:session): session closed for user root Aug 28 15:53:23 kamo-volumio volumio[1055]: info: Enabling plugin spop Aug 28 15:53:23 kamo-volumio volumio[1055]: info: Loading plugin "spop"... Aug 28 15:53:23 kamo-volumio volumio[1055]: info: PLUGIN START: spop Aug 28 15:53:23 kamo-volumio volumio[1055]: info: Creating Spotify config file Aug 28 15:53:23 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 15:53:23 kamo-volumio volumio[1055]: info: Done. Aug 28 15:53:23 kamo-volumio volumio[1055]: info: Spotify config file written Aug 28 15:53:23 kamo-volumio sudo[1728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 28 15:53:23 kamo-volumio sudo[1728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:53:23 kamo-volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 28 15:53:23 kamo-volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 28 15:53:23 kamo-volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:53:23 kamo-volumio go-librespot[1730]: go-librespot daemon starting... Aug 28 15:53:23 kamo-volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Aug 28 15:53:23 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:23+09:00" level=info msg="running go-librespot 0.4.0" Aug 28 15:53:23 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:23+09:00" level=debug msg="app state loaded" Aug 28 15:53:23 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:23+09:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 15:53:23 kamo-volumio volumio[1055]: info: No need to fix Spotify hosts Aug 28 15:53:23 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:23+09: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 28 15:53:23 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:23+09: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 28 15:53:23 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:23+09: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 28 15:53:23 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:23+09:00" level=info msg="zeroconf server listening on port 40505" Aug 28 15:53:24 kamo-volumio volumio[1055]: info: An error occurred while refreshing Spotify Token Error: Bad Request Aug 28 15:53:24 kamo-volumio volumio[1055]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 15:53:26 kamo-volumio volumio[1055]: info: go-librespot daemon successfully initialized Aug 28 15:53:29 kamo-volumio volumio[1055]: info: Initializing connection to go-librespot Websocket Aug 28 15:53:29 kamo-volumio go-librespot[1731]: time="2026-08-28T15:53:29+09:00" level=debug msg="new websocket client" Aug 28 15:53:29 kamo-volumio volumio[1055]: info: Connection to go-librespot Websocket established Aug 28 15:53:32 kamo-volumio volumio[1055]: info: Getting Spotify volume Aug 28 15:53:32 kamo-volumio volumio[1055]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 28 15:53:32 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioGetState Aug 28 15:53:32 kamo-volumio volumio[1055]: info: CorePlayQueue::getTrack 0 Aug 28 15:53:32 kamo-volumio volumio[1055]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 15:53:52 kamo-volumio volumio[1055]: info: Received OAUTH Data Aug 28 15:53:52 kamo-volumio volumio[1055]: info: Executing Spotify Oauth Login Aug 28 15:53:52 kamo-volumio volumio[1055]: info: Saving Spotify Refresh Token Aug 28 15:53:52 kamo-volumio volumio[1055]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 28 15:53:52 kamo-volumio volumio[1055]: SPOTIFY: BQDDN3D6SBbhgbtUm9B7aLQMKTYyS-2sFsdzloT-hjUNJLtB8dTj9Ea5F4Wdi0NuexQtocs3mfLt2t0R99-7uGJEW12IuUeYkW-scaIFgOg4o_oueyxaLDbPh0A4YY5dyQ3YlbIZljFJvE4DvqtU1ducKBacXBnoJ1LN9qQsz7Ksx5u62mv4btAShgnYmpogwho0Xr9PcNsdjbGONLvnyVDH5xtJq9BxeqaaU3t-kzCHFNgBTgGzzFzeJIgR52d-X5Rb7ybPLdH8MwQpPbCjLhn9kkTF_ftHKUIeN4CznMQ1aZBOeQWdiqSZMB0 Aug 28 15:53:52 kamo-volumio volumio[1055]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 28 15:53:52 kamo-volumio volumio[1055]: info: New Spotify access token = BQDDN3D6SBbhgbtUm9B7aLQMKTYyS-2sFsdzloT-hjUNJLtB8dTj9Ea5F4Wdi0NuexQtocs3mfLt2t0R99-7uGJEW12IuUeYkW-scaIFgOg4o_oueyxaLDbPh0A4YY5dyQ3YlbIZljFJvE4DvqtU1ducKBacXBnoJ1LN9qQsz7Ksx5u62mv4btAShgnYmpogwho0Xr9PcNsdjbGONLvnyVDH5xtJq9BxeqaaU3t-kzCHFNgBTgGzzFzeJIgR52d-X5Rb7ybPLdH8MwQpPbCjLhn9kkTF_ftHKUIeN4CznMQ1aZBOeQWdiqSZMB0 Aug 28 15:53:52 kamo-volumio volumio[1055]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 28 15:53:52 kamo-volumio volumio[1055]: SPOTIFY: User informations: {"account_id":"C8sEun4ZZi","country":"JP","display_name":"かわうそ","email":"igakisi510a@icloud.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ffhbv2n3dnad6ohtbgtcwur5ym"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ffhbv2n3dnad6ohtbgtcwur5ym","id":"31ffhbv2n3dnad6ohtbgtcwur5ym","images":[],"product":"free","type":"user","uri":"spotify:user:31ffhbv2n3dnad6ohtbgtcwur5ym"} Aug 28 15:53:52 kamo-volumio volumio[1055]: info: Creating Spotify config file Aug 28 15:53:52 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 15:53:52 kamo-volumio volumio[1055]: info: Spotify config file written Aug 28 15:53:52 kamo-volumio sudo[1785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 28 15:53:52 kamo-volumio sudo[1785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:53:53 kamo-volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 28 15:53:53 kamo-volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 28 15:53:53 kamo-volumio systemd[1]: go-librespot-daemon.service: Killing process 1732 (go-librespot) with signal SIGKILL. Aug 28 15:53:53 kamo-volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 28 15:53:53 kamo-volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:53:53 kamo-volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:53:53 kamo-volumio go-librespot[1790]: go-librespot daemon starting... Aug 28 15:53:53 kamo-volumio sudo[1785]: pam_unix(sudo:session): session closed for user root Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=info msg="running go-librespot 0.4.0" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=debug msg="app state loaded" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 15:53:53 kamo-volumio dbus-daemon[554]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.26' (uid=0 pid=1788 comm="timedatectl show --property=NTPSynchronized --valu") Aug 28 15:53:53 kamo-volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 28 15:53:53 kamo-volumio volumio[1055]: info: Connection to go-librespot Websocket closed Aug 28 15:53:53 kamo-volumio volumio[1055]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 28 15:53:53 kamo-volumio volumio[1055]: SPOTIFY: BQCMPVrtbXc4XNBOQVE4wRFI-e8PuQp5oUcA848knSiSRM6LXFTHGQkmPc6NKF-yWdPWhiHyUgHZ9TiLpDdWTjK3RAAShL5Mg4cRBV_X2lcGDtVuPJqy4QUBFhVHkYMBpGalRegRc3mbKvUO7FyZn7LF1EHgsv4JQb_ncygafIL5PG4dedSpRkvHQ7jX45OEAQWvbY51F11ZMWEeYK5OZh1XvnRgS7unig_kRUr4WiOZPJUREAX9p7KEO7HU_6ANwVIEiWNnNtFYu6u28SayuC4uO_BbPn-vuS4__MAATPKpAiEiXppAYp6_-v8 Aug 28 15:53:53 kamo-volumio volumio[1055]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 28 15:53:53 kamo-volumio volumio[1055]: info: New Spotify access token = BQCMPVrtbXc4XNBOQVE4wRFI-e8PuQp5oUcA848knSiSRM6LXFTHGQkmPc6NKF-yWdPWhiHyUgHZ9TiLpDdWTjK3RAAShL5Mg4cRBV_X2lcGDtVuPJqy4QUBFhVHkYMBpGalRegRc3mbKvUO7FyZn7LF1EHgsv4JQb_ncygafIL5PG4dedSpRkvHQ7jX45OEAQWvbY51F11ZMWEeYK5OZh1XvnRgS7unig_kRUr4WiOZPJUREAX9p7KEO7HU_6ANwVIEiWNnNtFYu6u28SayuC4uO_BbPn-vuS4__MAATPKpAiEiXppAYp6_-v8 Aug 28 15:53:53 kamo-volumio volumio[1055]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=info msg="zeroconf server listening on port 34609" Aug 28 15:53:53 kamo-volumio dbus-daemon[554]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 28 15:53:53 kamo-volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 28 15:53:53 kamo-volumio setdatetime-helper.sh[1787]: Time is already synchronized. Aug 28 15:53:53 kamo-volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 28 15:53:53 kamo-volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=debug msg="obtained new client token: AAFpUeROT0GFSuAak7O4ojJF/auuLbuyV9RrZxRcmLdACS7VbHwZnW265uFhbPOYBkaajSx4yQ5ZJt/rKvm7MtKEnoyFAPcZ8YKy+g0KXjbhrqnHSn7EKhFMs3RndyCQxJHpmOGESmG91/NSShNkqE2O1YWF5SD1Tt/EvkxxK65585NierazmsfgvGhH5ho1+i5jmQ1NQix8je5FD6P4qV0IpDEAVRpszhFEU2Utnn3k3efqv1W6Cu7SSgA=" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 28 15:53:53 kamo-volumio volumio[1055]: SPOTIFY: User informations: {"account_id":"C8sEun4ZZi","country":"JP","display_name":"かわうそ","email":"igakisi510a@icloud.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ffhbv2n3dnad6ohtbgtcwur5ym"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ffhbv2n3dnad6ohtbgtcwur5ym","id":"31ffhbv2n3dnad6ohtbgtcwur5ym","images":[],"product":"free","type":"user","uri":"spotify:user:31ffhbv2n3dnad6ohtbgtcwur5ym"} Aug 28 15:53:53 kamo-volumio volumio[1055]: info: Spotify Successfully logged in Aug 28 15:53:53 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 15:53:53 kamo-volumio volumio[1055]: info: [1787900033809] CoreMusicLibrary::Adding element Spotify Aug 28 15:53:53 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 15:53:53 kamo-volumio volumio[1055]: Cannot find translation for source YouTube2 Aug 28 15:53:53 kamo-volumio volumio[1055]: Cannot find translation for source YouTube Music Aug 28 15:53:53 kamo-volumio volumio[1055]: Cannot find translation for source 80s80s Radio Aug 28 15:53:53 kamo-volumio volumio[1055]: Cannot find translation for source Spotify Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=debug msg="completed keyexchange" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=debug msg="completed challenge" Aug 28 15:53:53 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:53+09:00" level=info msg="authenticated AP" username="qy****13" Aug 28 15:53:54 kamo-volumio go-librespot[1791]: time="2026-08-28T15:53:54+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 15:53:54 kamo-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 15:53:54 kamo-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 15:53:54 kamo-volumio sudo[1803]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 15:53:54 kamo-volumio sudo[1803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:53:54 kamo-volumio sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 15:53:54 kamo-volumio sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:53:54 kamo-volumio sudo[1803]: pam_unix(sudo:session): session closed for user root Aug 28 15:53:54 kamo-volumio sudo[1805]: pam_unix(sudo:session): session closed for user root Aug 28 15:53:54 kamo-volumio volumio[1055]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.68 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioGetState Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CorePlayQueue::getTrack 0 Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 15:53:55 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 15:53:56 kamo-volumio volumio[1055]: info: Initializing connection to go-librespot Websocket Aug 28 15:53:56 kamo-volumio volumio[1055]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 15:53:56 kamo-volumio volumio[1055]: info: go-librespot daemon successfully initialized Aug 28 15:53:57 kamo-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 28 15:53:57 kamo-volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:53:57 kamo-volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:53:57 kamo-volumio go-librespot[1822]: go-librespot daemon starting... Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=info msg="running go-librespot 0.4.0" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=debug msg="app state loaded" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09: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 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09: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 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09: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 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=info msg="zeroconf server listening on port 38741" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=debug msg="obtained new client token: AAHYZrJHLvROUwHU8KScelon4VGyjIRGpkGNddUVkdGeVe2kPnRDjBUayDNueDITmob+N6cXFdigAeyM8Vj9D3K8QonUvVt6Hjtn+NrUHM0mykzM9PoP3JLdg5BmNcEQY1x8SkXq1y8W45uTyvigHCG+057cfuLEtVaMJk37mPC1g0q5O8TeAR5HoWcrChgYxDxdKHP3BnDPPM3zAgr6+PveGAMbJr5kYmpk5QfqULeDflyQB1ypZ4OEKbo=" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=debug msg="completed keyexchange" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=debug msg="completed challenge" Aug 28 15:53:57 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:57+09:00" level=info msg="authenticated AP" username="qy****13" Aug 28 15:53:58 kamo-volumio go-librespot[1823]: time="2026-08-28T15:53:58+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 15:53:58 kamo-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 15:53:58 kamo-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 15:53:58 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 15:53:58 kamo-volumio volumio[1055]: info: Received Get System Info Aug 28 15:53:58 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 15:53:58 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 15:53:58 kamo-volumio volumio[1055]: info: Discovery: Getting this device information Aug 28 15:53:58 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioGetState Aug 28 15:53:58 kamo-volumio volumio[1055]: info: CorePlayQueue::getTrack 0 Aug 28 15:53:58 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 15:53:59 kamo-volumio volumio[1055]: info: Initializing connection to go-librespot Websocket Aug 28 15:53:59 kamo-volumio volumio[1055]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 15:53:59 kamo-volumio volumio[1055]: info: Initializing connection to go-librespot Websocket Aug 28 15:53:59 kamo-volumio volumio[1055]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 15:54:01 kamo-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 28 15:54:01 kamo-volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:54:01 kamo-volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:54:01 kamo-volumio go-librespot[1831]: go-librespot daemon starting... Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=info msg="running go-librespot 0.4.0" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=debug msg="app state loaded" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09: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 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09: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 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09: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 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=info msg="zeroconf server listening on port 41497" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=debug msg="obtained new client token: AAHMwaWyOFC5P7CKVb1jG+a21plC1c46zv/byK5r3YE1l6ovU0GmEgBuq3REY4LYdsePRIuqD72OkEZJVzMJgk4hEsMe+xkoV5zi7WfMdTpkBEbgRdJ1jVaIbhD50+oVP9psszvGhhOxWdaaG93nKFmtE3Iedt1UX+sOC5M8tllCNsHw6+2kr9xa4HsKI6FPsxzDiccLxOZ06l2610LWk92+1Awt+QvrYW3sfcHJqOag3rik3RVZ7vOlJnw=" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=debug msg="completed keyexchange" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=debug msg="completed challenge" Aug 28 15:54:01 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:01+09:00" level=info msg="authenticated AP" username="qy****13" Aug 28 15:54:02 kamo-volumio go-librespot[1832]: time="2026-08-28T15:54:02+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 15:54:02 kamo-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 15:54:02 kamo-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 15:54:02 kamo-volumio volumio[1055]: info: Initializing connection to go-librespot Websocket Aug 28 15:54:02 kamo-volumio volumio[1055]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 15:54:05 kamo-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 28 15:54:05 kamo-volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:54:05 kamo-volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:54:05 kamo-volumio go-librespot[1840]: go-librespot daemon starting... Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=info msg="running go-librespot 0.4.0" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="app state loaded" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 15:54:05 kamo-volumio volumio[1055]: info: Initializing connection to go-librespot Websocket Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="new websocket client" Aug 28 15:54:05 kamo-volumio volumio[1055]: info: Connection to go-librespot Websocket established Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=info msg="zeroconf server listening on port 42481" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="obtained new client token: AAHGdYIUpVRgzlOL05+zjcvZJxmbQsWzDQd8oLPud9Y9y4+1UAaU8p4se6Kge2WsDhc2ZV7MGB9LZb1qrjZeyLqJLWR0WL7Z9IaS5xoQWsQTjo3M9/1tiQHPcACphOr/vsXJ4xmQ3juNKM856Wbgtx7KLqYtMkfKAX9pffIcUt+K0V7m3uWk1X/QiZxi1ol4ihJRRQTG7UKvezCZxosw4K0J7PG03k4Yoc4znmtqyA9GOlD0/VmxmAF0ZeA=" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="completed keyexchange" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=debug msg="completed challenge" Aug 28 15:54:05 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:05+09:00" level=info msg="authenticated AP" username="qy****13" Aug 28 15:54:06 kamo-volumio go-librespot[1841]: time="2026-08-28T15:54:06+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 15:54:06 kamo-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 15:54:06 kamo-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 15:54:06 kamo-volumio volumio[1055]: info: Connection to go-librespot Websocket closed Aug 28 15:54:07 kamo-volumio sudo[1863]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 15:54:07 kamo-volumio sudo[1863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:54:07 kamo-volumio sudo[1865]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 15:54:07 kamo-volumio sudo[1865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:54:07 kamo-volumio sudo[1865]: pam_unix(sudo:session): session closed for user root Aug 28 15:54:07 kamo-volumio sudo[1863]: pam_unix(sudo:session): session closed for user root Aug 28 15:54:07 kamo-volumio volumio[1055]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.68 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioGetState Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CorePlayQueue::getTrack 0 Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 15:54:08 kamo-volumio volumio[1055]: info: Listing playlists Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 15:54:08 kamo-volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 15:54:08 kamo-volumio volumio[1055]: info: Getting Spotify volume Aug 28 15:54:08 kamo-volumio volumio[1055]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 15:54:08 kamo-volumio volumio[1055]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 15:54:08 kamo-volumio volumio[1055]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 28 15:54:08 kamo-volumio volumio[1055]: errno: -111, Aug 28 15:54:08 kamo-volumio volumio[1055]: code: 'ECONNREFUSED', Aug 28 15:54:08 kamo-volumio volumio[1055]: syscall: 'connect', Aug 28 15:54:08 kamo-volumio volumio[1055]: address: '127.0.0.1', Aug 28 15:54:08 kamo-volumio volumio[1055]: port: 9879, Aug 28 15:54:08 kamo-volumio volumio[1055]: response: undefined Aug 28 15:54:08 kamo-volumio volumio[1055]: } Aug 28 15:54:08 kamo-volumio volumio[1055]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 15:54:09 kamo-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 28 15:54:09 kamo-volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:54:09 kamo-volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 15:54:09 kamo-volumio go-librespot[1880]: go-librespot daemon starting... Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=info msg="running go-librespot 0.4.0" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=debug msg="app state loaded" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=info msg="zeroconf server listening on port 43905" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=debug msg="obtained new client token: AAEdDs2RV1nW5NRGFg6DxJV7YGWgHchffekxh6H82YSsjayw4Ad329ze75g0+tRVNOvq6GZzXtW41PQWLB/xAQUztB59obGoFvpn4siciM+Ct6UCnOuxA/iYEQoy1n1vbDxcG05H64xVoqgg2MJ5SIhf0RhoHA/oF0UazgYa5qaEnYacGwrk/YThK5/oZLY2T1b6BZAGhSgmcNf4WpE/kUMc1+igk1/+iv5wPNOm3/6hl1Qk48uuvEVegWs=" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=debug msg="completed keyexchange" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=debug msg="completed challenge" Aug 28 15:54:09 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:09+09:00" level=info msg="authenticated AP" username="qy****13" Aug 28 15:54:10 kamo-volumio go-librespot[1881]: time="2026-08-28T15:54:10+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 15:54:10 kamo-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 15:54:10 kamo-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 15:54:10 kamo-volumio sudo[1891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 15:53' Aug 28 15:54:10 kamo-volumio sudo[1891]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"