Jan 12 20:42:53 volumio ntpd[1253]: CLOCK: time stepped by 5175.308466 Jan 12 20:42:53 volumio ntpd[1253]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 12 20:42:53 volumio volumio[1505]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 12 20:42:53 volumio volumio[1505]: info: Discovery: A device disappeared from network Jan 12 20:42:53 volumio volumio[1505]: info: Discovery: Device volumio disappeared from network Jan 12 20:42:53 volumio volumio[1505]: info: Discovery: A device disappeared from network Jan 12 20:42:53 volumio volumio[1505]: info: go-librespot daemon successfully initialized Jan 12 20:42:53 volumio volumio[1505]: info: Discovery: adding e019c714-0476-4ded-85bf-8327629f8c8a Jan 12 20:42:53 volumio volumio[1505]: info: Discovery: Found device Volumio Jan 12 20:42:53 volumio volumio[1505]: info: CoreCommandRouter::volumioGetState Jan 12 20:42:53 volumio volumio[1505]: info: CorePlayQueue::getTrack 0 Jan 12 20:42:53 volumio volumio[1505]: info: Discovery: this is already registered, e019c714-0476-4ded-85bf-8327629f8c8a Jan 12 20:42:53 volumio volumio[1505]: info: Discovery: Found device Volumio Jan 12 20:42:53 volumio volumio[1505]: info: CoreCommandRouter::volumioGetState Jan 12 20:42:53 volumio volumio[1505]: info: CorePlayQueue::getTrack 0 Jan 12 20:42:53 volumio volumio[1505]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 12 20:42:53 volumio sudo[2071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 20:42:53 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 20:42:53 volumio sudo[2073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 20:42:53 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 20:42:53 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 12 20:42:53 volumio mpd_monitor.sh[2076]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 20:42:53 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 12 20:42:53 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Jan 12 20:42:53 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 12 20:42:53 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 12 20:42:53 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 12 20:42:53 volumio volumio[1505]: info: Successfully started MPD Monitor Jan 12 20:42:53 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Jan 12 20:42:53 volumio mpd_monitor.sh[2079]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 20:42:53 volumio volumio[1505]: info: Successfully started MPD Monitor Jan 12 20:42:53 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::volumioGetState Jan 12 20:42:54 volumio volumio[1505]: info: CorePlayQueue::getTrack 0 Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 20:42:54 volumio volumio[1505]: info: Received Get System Info Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:42:54 volumio volumio[1505]: info: Discovery: Getting this device information Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::volumioGetState Jan 12 20:42:54 volumio volumio[1505]: info: CorePlayQueue::getTrack 0 Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::volumioGetState Jan 12 20:42:54 volumio volumio[1505]: info: CorePlayQueue::getTrack 0 Jan 12 20:42:54 volumio volumio[1505]: info: Listing playlists Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 20:42:54 volumio volumio-remote-updater[1045]: [2026-01-12 20:42:54] [connect] Successful connection Jan 12 20:42:54 volumio volumio-remote-updater[1045]: [2026-01-12 20:42:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768243374 101 Jan 12 20:42:54 volumio volumio[1505]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Jan 12 20:42:54 volumio volumio[1505]: info: CoreCommandRouter::volumioGetState Jan 12 20:42:54 volumio volumio[1505]: info: CorePlayQueue::getTrack 0 Jan 12 20:42:55 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 20:42:55 volumio volumio[1505]: info: Received Get System Info Jan 12 20:42:55 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 20:42:55 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 20:42:55 volumio volumio[1505]: info: Discovery: Getting this device information Jan 12 20:42:55 volumio volumio[1505]: info: CoreCommandRouter::volumioGetState Jan 12 20:42:55 volumio volumio[1505]: info: CorePlayQueue::getTrack 0 Jan 12 20:42:55 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 20:42:55 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 20:42:55 volumio volumio[1505]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 12 20:42:56 volumio go-librespot[1815]: time="2026-01-12T20:42:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 12 20:42:56 volumio go-librespot[1815]: time="2026-01-12T20:42:56+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 12 20:42:56 volumio go-librespot[1815]: time="2026-01-12T20:42:56+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 12 20:42:56 volumio go-librespot[1815]: time="2026-01-12T20:42:56+02:00" level=info msg="zeroconf server listening on port 34527" Jan 12 20:42:56 volumio volumio[1505]: SPOTIFY: User informations: {"country":"LT","display_name":"karaciakas","email":"karaciakas@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/karaciakas"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/karaciakas","id":"karaciakas","images":[],"product":"premium","type":"user","uri":"spotify:user:karaciakas"} Jan 12 20:42:56 volumio volumio[1505]: info: Spotify Successfully logged in Jan 12 20:42:56 volumio volumio[1505]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 20:42:56 volumio volumio[1505]: info: [1768243376127] CoreMusicLibrary::Adding element Spotify Jan 12 20:42:56 volumio volumio[1505]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 20:42:56 volumio volumio[1505]: Cannot find translation for source Spotify Jan 12 20:42:56 volumio volumio[1505]: info: Initializing connection to go-librespot Websocket Jan 12 20:42:56 volumio volumio[1505]: info: Connection to go-librespot Websocket established Jan 12 20:42:56 volumio go-librespot[1815]: time="2026-01-12T20:42:56+02:00" level=debug msg="new websocket client" Jan 12 20:42:56 volumio volumio[1505]: info: New access token = BQDiRvLl6I8FgxvvBk4fNHko-8xO8nkcYXMhm6a2HBVR30VBDRmwE6TZP1YE2UIXoD9e50exe3NfT7pIslLjAYK9Y-xdbB5dJ-UyMbba2-50OTf7DsEJqLJhBGk9CELZFa0dc7BEtz4WBUNb-mVDn5bzplxnar_OaYDn1LYvuGJQNOeqnkhjs71k-h95mcsEDJ1F0O_m_cXdajGBD_8b3R5oV8cpHSt0VmmyUSKxiu2BAfAImqormgiU8OoMfDL1zbbujDdI4AWl8UAqUeFeb3VL_1mMYM4Zbj2vV2D6sDh_Ttf0KSUacpWHRqPm0YPIABWNOxSjZd0ItZ5FUQg_gbgR4r-G0tNGEEc9nAHLSeO8jGZC95CGC0w Jan 12 20:42:56 volumio volumio[1505]: info: New access token = BQDYuVEqOc-sE13yZZmj7r34JixTKQyLVg2InbYDXW1UT0aql66Q5nGVtv2sLxgQ7-6ZAtyz66x__bcUb0DEzRKJnTo2vijVXXO1T5U660m6q_xKZ9r61yZhnYfMzLb3AAcVjM_Hhihqs3Bioh8sOx6VCUCbWyKWPt_xXkxoULAMLsJrGuccPDNv8boiNNth7C6qTgwP-9XMnBnUuJGRCZdgRgoxym9vl22LlmfCNvKS1Zq1oKXPUaHMtsJxjR0UHDqIcjwaMxs76Jk2Hcm5KykdGsEOME6yh6VaRjNCCEWnydBv3uOxAbdc5kF2LyICLAQVaNNPJRdUJ82q00REAzbsjcA14QyVXKa_-llihf_qBPdklYpr9Kw Jan 12 20:42:56 volumio volumio[1505]: info: New access token = BQAlA6DZ1xL5FVyk8vlLycKTLutoL4st0QDZpYD_PCsCdvBhyfZiFWozg-Il7uhSY0zrQaY0yu_uE-SQQzFqbw8TLU7AxQ_gWSmma-QSjwLPCaUxk2dMv_7_dlVyozXE_hX_t1wVD7zHY7Xpu1aixw53B26VdLHGFl0ef_PrmctjzUeDOk2jbf3_jYOCAVh2140rWLV_WhUPzaskSJjdFQDyFE7ZrPyNROEzAZOv_jQzpMf5_Aycr-BCFDWeow7VOsUnofRrqU5szghIs44trukoRdVXfZK-zRd1RUfCyuC8JxOc09t0WGu2ryD2h2eaFYLO-_3NydCN24Nh7X2qJU4AqV2_W1KrTzM0JlbJvOaAbsxzZI_QX4I Jan 12 20:42:56 volumio go-librespot[1815]: time="2026-01-12T20:42:56+02:00" level=debug msg="obtained new client token: AAD9xv1aa/G/2AQKgsIM6kEWKI0/O9OMykwezd6u1uWrfSSEkTnsKZMgVtnnqwmBQLcMUHwplDa5xX+eEX2u67iDnLAyPoPw5xm8B9DzFQ0yGlFpKcyKiNZJuIFiBH5qNWCBPxToMqrdSX8znsb/gwEwBX2Pscw8KpCVDY+w8JRClTMLT+r6QKCo27TMRUO5Pu/Yl30hAngikkHS9WkAcy9LIixNj20Ksm23qoeRORlHb+0QAI3QMJz3" Jan 12 20:42:56 volumio go-librespot[1815]: time="2026-01-12T20:42:56+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 12 20:42:57 volumio go-librespot[1815]: time="2026-01-12T20:42:57+02:00" level=debug msg="completed keyexchange" Jan 12 20:42:57 volumio go-librespot[1815]: time="2026-01-12T20:42:57+02:00" level=debug msg="completed challenge" Jan 12 20:42:57 volumio go-librespot[1815]: time="2026-01-12T20:42:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 12 20:42:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 12 20:42:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 12 20:42:57 volumio volumio[1505]: info: Connection to go-librespot Websocket closed Jan 12 20:42:57 volumio sudo[2108]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 20:42:57 volumio sudo[2108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 20:42:57 volumio sudo[2110]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 20:42:57 volumio sudo[2108]: pam_unix(sudo:session): session closed for user root Jan 12 20:42:57 volumio sudo[2110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 20:42:57 volumio sudo[2110]: pam_unix(sudo:session): session closed for user root Jan 12 20:42:57 volumio sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 12 20:42:57 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 20:42:59 volumio volumio[1505]: info: Getting Spotify volume Jan 12 20:42:59 volumio volumio[1505]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 20:42:59 volumio volumio[1505]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 12 20:42:59 volumio volumio[1505]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 12 20:42:59 volumio volumio[1505]: errno: -111, Jan 12 20:42:59 volumio volumio[1505]: code: 'ECONNREFUSED', Jan 12 20:42:59 volumio volumio[1505]: syscall: 'connect', Jan 12 20:42:59 volumio volumio[1505]: address: '127.0.0.1', Jan 12 20:42:59 volumio volumio[1505]: port: 9879, Jan 12 20:42:59 volumio volumio[1505]: response: undefined Jan 12 20:42:59 volumio volumio[1505]: } Jan 12 20:42:59 volumio volumio[1505]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 20:42:59 volumio sudo[2130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 20:41' Jan 12 20:42:59 volumio sudo[2130]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"