Oct 11 12:54:10 marantz-player go-librespot[1685]: time="2026-10-11T12:54:10+02:00" level=debug msg="obtained new client token: AAHWZB0fcvdX6g7hD46W2B8UhuHidTeteJjYjl9/elHNAtaonlV8gvE7BmumUtz8fJCHvly0BOsnhNPmFrj9HfiARuTym4OMnDRcN+jCWMGnCxmbqCwtOE1ltitMZtpE56KaGFoYE4QmWP4OfxDjRfsglNzFzmt7pC3MhVzuml/sc5WsPLjPNMlNWPfVRRk3hUc0i3sCO+cRpxhnwPW337WHP1q2V4YYm3oIDPFwJwNXKDUD5J69WRLW" Oct 11 12:54:10 marantz-player go-librespot[1685]: time="2026-10-11T12:54:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 12:54:10 marantz-player go-librespot[1685]: time="2026-10-11T12:54:10+02:00" level=debug msg="completed keyexchange" Oct 11 12:54:10 marantz-player go-librespot[1685]: time="2026-10-11T12:54:10+02:00" level=debug msg="completed challenge" Oct 11 12:54:10 marantz-player go-librespot[1685]: time="2026-10-11T12:54:10+02:00" level=info msg="authenticated AP" username="21*********************zy" Oct 11 12:54:10 marantz-player go-librespot[1685]: time="2026-10-11T12:54:10+02:00" level=info msg="authenticated Login5" username="21*********************zy" Oct 11 12:54:10 marantz-player go-librespot[1685]: time="2026-10-11T12:54:10+02:00" level=info msg="accepted zeroconf from Xiaomi 15T" username="21*********************zy" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="dealer connection opened" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="starting accesspoint recv loop" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="starting dealer recv loop" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="received accesspoint ping" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="received connection id: NzEyOGQ4NzMtMGEy...REVENUFDQ0VGRA==" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="received accesspoint pong ack" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="handling transfer player command from 33ca937a9b1890c4c899c56e1c3bf75b490e56d3" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="resolved context of track" uri="spotify:list:popular-release-segments-main-roles:artist_4YLtscXsxbVgi031ovDDdh" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="fetched new page 0 with 43 items (list: 43)" uri="spotify:list:popular-release-segments-main-roles:artist_4YLtscXsxbVgi031ovDDdh" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="shuffled context with seed 746586363970655028 (len: 43, keep: 23)" uri="spotify:list:popular-release-segments-main-roles:artist_4YLtscXsxbVgi031ovDDdh" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="loading track (paused: false, position: 10869ms)" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="emitting websocket event: will_play" Oct 11 12:54:11 marantz-player volumio[1243]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:list:popular-release-segments-main-roles:artist_4YLtscXsxbVgi031ovDDdh","uri":"spotify:track:3fqwjXwUGN6vbzIwvyFMhx","play_origin":"artist"}} Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="selected format OGG_VORBIS_320 (9c8294f7abcb476f62eed70c2887b627b3c24371)" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="requested aes key for file 9c8294f7abcb476f62eed70c2887b627b3c24371, gid: 3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="fetched first chunk of 20, total size is 10403800 bytes" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=trace msg="seek to 10869ms (diff: 135ms, samples: 479322, bytes: 373868)" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="created new output device" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed setting stream for spotify:track:3fqwjXwUGN6vbzIwvyFMhx: ALSA error at snd_pcm_open: Device or resource busy" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:11 marantz-player go-librespot[1685]: time="2026-10-11T12:54:11+02:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:12 marantz-player go-librespot[1685]: time="2026-10-11T12:54:12+02:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:3fqwjXwUGN6vbzIwvyFMhx" Oct 11 12:54:20 marantz-player bluealsa[977]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_60_68_85_F4_CC_31, ...) Oct 11 12:54:22 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:22.495+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.583+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.619+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=http://pushupdates.volumio.org duration=34.79432ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.622+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=38.891121ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.627+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=43.315972ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.632+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=48.155522ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.697+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://www.googleapis.com duration=114.048894ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.729+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=145.128132ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.734+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://securetoken.googleapis.com duration=150.183938ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.741+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://database.volumio.cloud duration=155.717979ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.865+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://functions.volumio.cloud duration=280.208686ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.944+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://google.com duration=360.627857ms Oct 11 12:54:28 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:28.996+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=https://functions.volumio.cloud duration=412.188373ms Oct 11 12:54:29 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:29.162+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=http://cddb.volumio.org duration=578.006966ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 11 12:54:29 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:54:29.250+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.77.35:41958 @ 0x2000c30" latency=828.301124ms timeout=10s endpoint=http://plugins.volumio.org duration=665.942929ms Oct 11 12:54:31 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 12:54:31 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 12:54:41 marantz-player go-librespot[1685]: time="2026-10-11T12:54:41+02:00" level=trace msg="sent dealer ping" Oct 11 12:54:41 marantz-player go-librespot[1685]: time="2026-10-11T12:54:41+02:00" level=trace msg="received dealer pong" Oct 11 12:54:41 marantz-player volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 12:54:51 marantz-player volumio[1243]: info: Received OAUTH Data Oct 11 12:54:51 marantz-player volumio[1243]: info: Executing Spotify Oauth Login Oct 11 12:54:51 marantz-player volumio[1243]: info: Saving Spotify Refresh Token Oct 11 12:54:51 marantz-player volumio[1243]: info: New Spotify access tokenBQD8DRr8RN... Oct 11 12:54:51 marantz-player volumio[1243]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 11 12:54:52 marantz-player sudo[4184]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 12:54:52 marantz-player sudo[4184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 12:54:52 marantz-player sudo[4184]: pam_unix(sudo:session): session closed for user root Oct 11 12:54:52 marantz-player sudo[4186]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 12:54:52 marantz-player sudo[4186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 12:54:52 marantz-player volumio[1243]: SPOTIFY: User informations: {"account_id":"8ONLuvY1tz","country":"PL","display_name":"Waldek Łabno","email":"vascol@poczta.onet.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/21izut76u5u7wzyullqtb3xzy"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/21izut76u5u7wzyullqtb3xzy","id":"21izut76u5u7wzyullqtb3xzy","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1057473737677501&height=300&width=300&ext=1794304244&hash=AfuX80tdXN22B7IB1_DFFMZw","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1057473737677501&height=50&width=50&ext=1794304244&hash=Afud2wRZaMZ8dKF751bW7uTz","width":64}],"product":"premium","type":"user","uri":"spotify:user:21izut76u5u7wzyullqtb3xzy"} Oct 11 12:54:52 marantz-player sudo[4186]: pam_unix(sudo:session): session closed for user root Oct 11 12:54:52 marantz-player volumio[1243]: info: Creating Spotify config file Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 12:54:52 marantz-player volumio[1243]: info: Spotify config file written Oct 11 12:54:52 marantz-player sudo[4191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 11 12:54:52 marantz-player sudo[4191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 12:54:52 marantz-player volumio[1243]: verbose: New Socket.io Connection to 192.168.77.58 from 192.168.77.35 UA: Mozilla/5.0 (Linux; Android 16; 25069PTEBG Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 11 12:54:52 marantz-player systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Oct 11 12:54:52 marantz-player systemd[1]: go-librespot-daemon.service: Killing process 1701 (go-librespot) with signal SIGKILL. Oct 11 12:54:52 marantz-player volumio[1243]: info: Connection to go-librespot Websocket closed Oct 11 12:54:52 marantz-player systemd[1]: go-librespot-daemon.service: Deactivated successfully. Oct 11 12:54:52 marantz-player systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:54:52 marantz-player systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:54:52 marantz-player go-librespot[4193]: go-librespot daemon starting... Oct 11 12:54:52 marantz-player sudo[4191]: pam_unix(sudo:session): session closed for user root Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="app state loaded" Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 12:54:52 marantz-player volumio[1243]: info: Received Get System Info Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 12:54:52 marantz-player volumio[1243]: info: Discovery: Getting this device information Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 11 12:54:52 marantz-player volumio[1243]: info: Listing playlists Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 12:54:52 marantz-player volumio[1243]: info: New Spotify access tokenBQCz3a0Ehz... Oct 11 12:54:52 marantz-player volumio[1243]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=info msg="zeroconf server listening on port 44453" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="obtained new client token: AAGt3HkFCZtxvyQO6k3RNdz1JToO6MvdpDktK6u9mVG1iQryETwoIY6nTpVyK6cMyqaXuezV6BjwE/4OcIvHn20W/TkYE0yxs6lRsgBW8AMnl5LV8m0IKZysluS5R0KRogF5OFqqjXNRxjOckPGTgMHuJZvgrIUwJIaFLDR+y/GQP1wG3W8iivUzu8d9OA2k5z3ILFXx8AvjNz9ePJXfXr0ag7whfX1163ZWaHA9q+vBG63aHZnBE1oO" Oct 11 12:54:52 marantz-player volumio[1243]: SPOTIFY: User informations: {"account_id":"8ONLuvY1tz","country":"PL","display_name":"Waldek Łabno","email":"vascol@poczta.onet.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/21izut76u5u7wzyullqtb3xzy"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/21izut76u5u7wzyullqtb3xzy","id":"21izut76u5u7wzyullqtb3xzy","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1057473737677501&height=300&width=300&ext=1794304244&hash=AfuX80tdXN22B7IB1_DFFMZw","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=1057473737677501&height=50&width=50&ext=1794304244&hash=Afud2wRZaMZ8dKF751bW7uTz","width":64}],"product":"premium","type":"user","uri":"spotify:user:21izut76u5u7wzyullqtb3xzy"} Oct 11 12:54:52 marantz-player volumio[1243]: info: Spotify Successfully logged in Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 12:54:52 marantz-player volumio[1243]: info: [1791716092522] CoreMusicLibrary::Adding element Spotify Oct 11 12:54:52 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 12:54:52 marantz-player volumio[1243]: Cannot find translation for source YouTube2 Oct 11 12:54:52 marantz-player volumio[1243]: Cannot find translation for source RADIO 357 Oct 11 12:54:52 marantz-player volumio[1243]: Cannot find translation for source Spotify Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="completed keyexchange" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=debug msg="completed challenge" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=info msg="authenticated AP" username="21*********************zy" Oct 11 12:54:52 marantz-player go-librespot[4194]: time="2026-10-11T12:54:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 12:54:52 marantz-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 12:54:52 marantz-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 12:54:53 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 12:54:54 marantz-player volumio[1243]: info: Received Get System Info Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 12:54:54 marantz-player volumio[1243]: info: Discovery: Getting this device information Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 12:54:54 marantz-player volumio[1243]: info: Received Get System Info Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 12:54:54 marantz-player volumio[1243]: info: Discovery: Getting this device information Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 11 12:54:54 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 12:54:55 marantz-player volumio[1243]: info: Initializing connection to go-librespot Websocket Oct 11 12:54:55 marantz-player volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 12:54:55 marantz-player volumio[1243]: info: go-librespot daemon successfully initialized Oct 11 12:54:55 marantz-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 11 12:54:55 marantz-player systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:54:55 marantz-player systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:54:55 marantz-player go-librespot[4205]: go-librespot daemon starting... Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=debug msg="app state loaded" Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=info msg="zeroconf server listening on port 40291" Oct 11 12:54:55 marantz-player go-librespot[4206]: time="2026-10-11T12:54:55+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 12:54:56 marantz-player go-librespot[4206]: time="2026-10-11T12:54:56+02:00" level=debug msg="obtained new client token: AAHghBIJEUlAjMKjHzFZiW3/IcxtzFGB2YtDO1y9/WAI8uxzTW5hWYUNBzR9GA3OkUDgWLt3lG+GXdD60DlAYLLJaRr5DoGgiyrHF0ZEX0CHyhn019Mly4hq25sb+k/J5PeJ9bNE1QTK6vzFi4bDRm5Sd7+UsRWSpPywRQK77lG6WoyATXSO6kfFTU2ozk21/xmprnpI/mjBDdx6es9AXmiAVElgO69riNGLCXS8FRfu1Ejs+zO3eg==" Oct 11 12:54:56 marantz-player go-librespot[4206]: time="2026-10-11T12:54:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 12:54:56 marantz-player go-librespot[4206]: time="2026-10-11T12:54:56+02:00" level=debug msg="completed keyexchange" Oct 11 12:54:56 marantz-player go-librespot[4206]: time="2026-10-11T12:54:56+02:00" level=debug msg="completed challenge" Oct 11 12:54:56 marantz-player go-librespot[4206]: time="2026-10-11T12:54:56+02:00" level=info msg="authenticated AP" username="21*********************zy" Oct 11 12:54:56 marantz-player go-librespot[4206]: time="2026-10-11T12:54:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 12:54:56 marantz-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 12:54:56 marantz-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 12:54:58 marantz-player volumio[1243]: info: Initializing connection to go-librespot Websocket Oct 11 12:54:58 marantz-player volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 12:54:58 marantz-player volumio[1243]: info: Initializing connection to go-librespot Websocket Oct 11 12:54:58 marantz-player volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 12:54:59 marantz-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 11 12:54:59 marantz-player systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:54:59 marantz-player systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:54:59 marantz-player go-librespot[4219]: go-librespot daemon starting... Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="app state loaded" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=info msg="zeroconf server listening on port 40373" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="obtained new client token: AAHmNjq8GyEBZeMxTDdsbrMV12MPrrAiLIXiiOSZmIQ7kUt3sVXqGWwsjY7IaY0zrnjiwP64s1Ne2PE+zZjuQthFmOlJeXQo2DwKOtifMVQrVuvaryPu99SdvZzXqEgFrjBxadgbp8oX/539SzKQaMK6l5fSzV7g4qxS/iocTqmYTgxqUCXALiFlB36BEllnUNgy5lz+fieVcBdaiQymHoNDJzJo57g/NRJijmg9XuHqhdPcnEynEtw6" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="completed keyexchange" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=debug msg="completed challenge" Oct 11 12:54:59 marantz-player go-librespot[4221]: time="2026-10-11T12:54:59+02:00" level=info msg="authenticated AP" username="21*********************zy" Oct 11 12:55:00 marantz-player go-librespot[4221]: time="2026-10-11T12:55:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 12:55:00 marantz-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 12:55:00 marantz-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 12:55:01 marantz-player volumio[1243]: info: Initializing connection to go-librespot Websocket Oct 11 12:55:01 marantz-player volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 12:55:02 marantz-player volumio[1243]: info: Oct 11 12:55:02 marantz-player volumio[1243]: ---------------------------- MPD announces system playlist update Oct 11 12:55:02 marantz-player volumio[1243]: info: Ignoring MPD Status Update Oct 11 12:55:02 marantz-player volumio[1243]: info: Oct 11 12:55:02 marantz-player volumio[1243]: ---------------------------- MPD announces state update: player Oct 11 12:55:02 marantz-player volumio[1243]: info: ControllerMpd::getState Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:02 marantz-player volumio[1243]: info: Oct 11 12:55:02 marantz-player volumio[1243]: ---------------------------- MPD announces system playlist update Oct 11 12:55:02 marantz-player volumio[1243]: info: Ignoring MPD Status Update Oct 11 12:55:02 marantz-player volumio[1243]: info: Oct 11 12:55:02 marantz-player volumio[1243]: ---------------------------- MPD announces state update: player Oct 11 12:55:02 marantz-player volumio[1243]: info: ControllerMpd::getState Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:02 marantz-player volumio[1243]: info: Oct 11 12:55:02 marantz-player volumio[1243]: ---------------------------- MPD announces system playlist update Oct 11 12:55:02 marantz-player volumio[1243]: info: Ignoring MPD Status Update Oct 11 12:55:02 marantz-player volumio[1243]: info: Oct 11 12:55:02 marantz-player volumio[1243]: ---------------------------- MPD announces state update: player Oct 11 12:55:02 marantz-player volumio[1243]: info: ControllerMpd::getState Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 11 12:55:02 marantz-player volumio[1243]: info: ------------------------------ 6ms Oct 11 12:55:02 marantz-player volumio[1243]: info: sendMpdCommand status took 6 milliseconds Oct 11 12:55:02 marantz-player volumio[1243]: info: ------------------------------ 6ms Oct 11 12:55:02 marantz-player volumio[1243]: info: sendMpdCommand status took 4 milliseconds Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::parseState Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::parseState Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:02 marantz-player volumio[1243]: info: ------------------------------ 7ms Oct 11 12:55:02 marantz-player volumio[1243]: info: sendMpdCommand status took 6 milliseconds Oct 11 12:55:02 marantz-player volumio[1243]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 12:55:02 marantz-player volumio[1243]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::parseState Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:02 marantz-player volumio[1243]: info: ControllerMpd::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::servicePushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:02 marantz-player volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82586,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Radio 357 - Najlepszy radiowy adres na świecie!","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Oct 11 12:55:02 marantz-player volumio[1243]: verbose: CURRENT POSITION 0 Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:02 marantz-player volumio[1243]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:02 marantz-player volumio[1243]: info: ControllerMpd::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::servicePushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:02 marantz-player volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82586,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Radio 357 - Najlepszy radiowy adres na świecie!","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Oct 11 12:55:02 marantz-player volumio[1243]: verbose: CURRENT POSITION 0 Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:02 marantz-player volumio[1243]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.730+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" state=STATUS_PLAYING positionMs=81743 volume=17 Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.730+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" id=http://stream.rcs.revma.com/ye5kghkgcm0uv title="Radio 357 - Najlepszy radiowy adres na świecie!" Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.736+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" state=STATUS_PLAYING positionMs=81743 volume=17 Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.736+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" id=http://stream.rcs.revma.com/ye5kghkgcm0uv title="Radio 357 - Najlepszy radiowy adres na świecie!" Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.736+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" state=STATUS_PLAYING positionMs=81743 volume=17 Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.736+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" state=STATUS_PLAYING positionMs=81743 volume=17 Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.736+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" id=http://stream.rcs.revma.com/ye5kghkgcm0uv title="Radio 357 - Najlepszy radiowy adres na świecie!" Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" id=http://stream.rcs.revma.com/ye5kghkgcm0uv title="Radio 357 - Najlepszy radiowy adres na świecie!" Oct 11 12:55:02 marantz-player volumio[1243]: info: ------------------------------ 75ms Oct 11 12:55:02 marantz-player volumio[1243]: info: ------------------------------ 74ms Oct 11 12:55:02 marantz-player volumio[1243]: info: sendMpdCommand playlistinfo took 65 milliseconds Oct 11 12:55:02 marantz-player volumio[1243]: verbose: ControllerMpd::parseTrackInfo Oct 11 12:55:02 marantz-player volumio[1243]: info: ControllerMpd::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::servicePushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 11 12:55:02 marantz-player volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82586,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Radio 357 - Najlepszy radiowy adres na świecie!","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Oct 11 12:55:02 marantz-player volumio[1243]: verbose: CURRENT POSITION 0 Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::syncState stateService play Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::syncState currentStatus play Oct 11 12:55:02 marantz-player volumio[1243]: info: Received an update from plugin. extracting info from payload Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreStateMachine::pushState Oct 11 12:55:02 marantz-player volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.791+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" state=STATUS_PLAYING positionMs=81743 volume=17 Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.791+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" id=http://stream.rcs.revma.com/ye5kghkgcm0uv title="Radio 357 - Najlepszy radiowy adres na świecie!" Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.794+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" state=STATUS_PLAYING positionMs=81743 volume=17 Oct 11 12:55:02 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:02.794+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.77.35:41958 @ 0x2000c30" id=http://stream.rcs.revma.com/ye5kghkgcm0uv title="Radio 357 - Najlepszy radiowy adres na świecie!" Oct 11 12:55:02 marantz-player volumio[1243]: info: ------------------------------ 112ms Oct 11 12:55:02 marantz-player volumio[1243]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 11 12:55:02 marantz-player volumio[1243]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 11 12:55:02 marantz-player volumio[1243]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 11 12:55:02 marantz-player volumio[1243]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 11 12:55:02 marantz-player volumio[1243]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 11 12:55:02 marantz-player volumio[1243]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 11 12:55:03 marantz-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Oct 11 12:55:03 marantz-player systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:55:03 marantz-player systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:55:03 marantz-player go-librespot[4254]: go-librespot daemon starting... Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="app state loaded" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=info msg="zeroconf server listening on port 33503" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="obtained new client token: AAGqp+PuUJqcVdES+L/RC+V0+NXgexR3W2o8mJs+BYb9/W/Mv4xRskqw9gZhclopgLG9GOSNgaLz5lq3tBKKWeHHTrp/Z9VtBwKVA5EZY9g7wrKX0v4f48tXVEaDuURGLdtFp4DbXs8QyhU63YDOxSZlMq5Xe9Eh8ABGlFncz5PUlJpehFWvRVuwSJibR/sRk9WRkjJ+F3baTwry4DwzH7ZaZYHjOduDjzXzzle/3VgESt6WjF5Ig+nL" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="completed keyexchange" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=debug msg="completed challenge" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=info msg="authenticated AP" username="21*********************zy" Oct 11 12:55:03 marantz-player go-librespot[4255]: time="2026-10-11T12:55:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 12:55:03 marantz-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 12:55:03 marantz-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 12:55:04 marantz-player volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 12:55:04 marantz-player volumio[1243]: info: Initializing connection to go-librespot Websocket Oct 11 12:55:04 marantz-player volumio[1243]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 12:55:06 marantz-player systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 11 12:55:06 marantz-player systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:55:07 marantz-player systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 12:55:07 marantz-player go-librespot[4265]: go-librespot daemon starting... Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="app state loaded" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=info msg="zeroconf server listening on port 41443" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 12:55:07 marantz-player volumio[1243]: info: Initializing connection to go-librespot Websocket Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="new websocket client" Oct 11 12:55:07 marantz-player volumio[1243]: info: Connection to go-librespot Websocket established Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="obtained new client token: AAHqMPdR+woWCB46evsVCdyZYhyhOKTBZ891uImmBJyYq/aFr8NGUj0xJESzJCyHGGPBvZu1HbedvXri/iRdJdtBx772IdbYxgwFW12SVjQGgO1MLhtWBokFANC7Q/tiR3d+hOBE/DRildILfFmR0bVGrrFXXQmZ1893Q2d/w1+1qT7+aMeNhzd58djkFu1wTJTHNIB8+xCJF7/KzFpxaT/hporEXZM97f1VqzmPYpaLKr4R/41IeA==" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="completed keyexchange" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=debug msg="completed challenge" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=info msg="authenticated AP" username="21*********************zy" Oct 11 12:55:07 marantz-player go-librespot[4266]: time="2026-10-11T12:55:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 12:55:07 marantz-player systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 12:55:07 marantz-player systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 12:55:07 marantz-player volumio[1243]: info: Connection to go-librespot Websocket closed Oct 11 12:55:08 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:08.298+02:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.77.35:41958 error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 11 12:55:08 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:08.299+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.77.35:41958 Oct 11 12:55:08 marantz-player volumio5-onboarding[1551]: time=2026-10-11T12:55:08.299+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.77.35:41958 Oct 11 12:55:10 marantz-player volumio[1243]: info: Getting Spotify volume Oct 11 12:55:10 marantz-player volumio[1243]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 12:55:10 marantz-player volumio[1243]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 12:55:10 marantz-player volumio[1243]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 11 12:55:10 marantz-player volumio[1243]: errno: -111, Oct 11 12:55:10 marantz-player volumio[1243]: code: 'ECONNREFUSED', Oct 11 12:55:10 marantz-player volumio[1243]: syscall: 'connect', Oct 11 12:55:10 marantz-player volumio[1243]: address: '127.0.0.1', Oct 11 12:55:10 marantz-player volumio[1243]: port: 9879, Oct 11 12:55:10 marantz-player volumio[1243]: response: undefined Oct 11 12:55:10 marantz-player volumio[1243]: } Oct 11 12:55:10 marantz-player volumio[1243]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 12:55:10 marantz-player sudo[4304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 12:54' Oct 11 12:55:10 marantz-player sudo[4304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"