Jan 13 11:18:12 volumio go-librespot[1602]: time="2026-01-13T11:18:12+01:00" level=debug msg="fetched chunk 4/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:18:18 volumio go-librespot[1602]: time="2026-01-13T11:18:18+01:00" level=trace msg="sent dealer ping" Jan 13 11:18:18 volumio go-librespot[1602]: time="2026-01-13T11:18:18+01:00" level=trace msg="received dealer pong" Jan 13 11:18:26 volumio go-librespot[1602]: time="2026-01-13T11:18:26+01:00" level=debug msg="fetched chunk 5/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:18:38 volumio go-librespot[1602]: time="2026-01-13T11:18:38+01:00" level=debug msg="fetched chunk 6/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:18:48 volumio go-librespot[1602]: time="2026-01-13T11:18:48+01:00" level=trace msg="sent dealer ping" Jan 13 11:18:48 volumio go-librespot[1602]: time="2026-01-13T11:18:48+01:00" level=trace msg="received dealer pong" Jan 13 11:18:51 volumio go-librespot[1602]: time="2026-01-13T11:18:51+01:00" level=debug msg="fetched chunk 7/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:19:03 volumio go-librespot[1602]: time="2026-01-13T11:19:03+01:00" level=debug msg="fetched chunk 8/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:19:15 volumio go-librespot[1602]: time="2026-01-13T11:19:15+01:00" level=debug msg="fetched chunk 9/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:19:18 volumio go-librespot[1602]: time="2026-01-13T11:19:18+01:00" level=trace msg="received accesspoint ping" Jan 13 11:19:18 volumio go-librespot[1602]: time="2026-01-13T11:19:18+01:00" level=trace msg="received accesspoint pong ack" Jan 13 11:19:18 volumio go-librespot[1602]: time="2026-01-13T11:19:18+01:00" level=trace msg="sent dealer ping" Jan 13 11:19:18 volumio go-librespot[1602]: time="2026-01-13T11:19:18+01:00" level=trace msg="received dealer pong" Jan 13 11:19:27 volumio go-librespot[1602]: time="2026-01-13T11:19:27+01:00" level=debug msg="fetched chunk 10/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:19:28 volumio go-librespot[1602]: time="2026-01-13T11:19:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 13 11:19:28 volumio go-librespot[1602]: time="2026-01-13T11:19:28+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWUKw1j740sGk" Jan 13 11:19:28 volumio go-librespot[1602]: time="2026-01-13T11:19:28+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E38mjq9OATvzy" Jan 13 11:19:28 volumio go-librespot[1602]: time="2026-01-13T11:19:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 13 11:19:29 volumio go-librespot[1602]: time="2026-01-13T11:19:29+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 159" Jan 13 11:19:29 volumio go-librespot[1602]: time="2026-01-13T11:19:29+01:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Jan 13 11:19:31 volumio go-librespot[1602]: time="2026-01-13T11:19:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 13 11:19:31 volumio go-librespot[1602]: time="2026-01-13T11:19:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWSADWNdZfn11" Jan 13 11:19:40 volumio go-librespot[1602]: time="2026-01-13T11:19:40+01:00" level=debug msg="fetched chunk 11/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:19:48 volumio go-librespot[1602]: time="2026-01-13T11:19:48+01:00" level=trace msg="sent dealer ping" Jan 13 11:19:48 volumio go-librespot[1602]: time="2026-01-13T11:19:48+01:00" level=trace msg="received dealer pong" Jan 13 11:19:52 volumio go-librespot[1602]: time="2026-01-13T11:19:52+01:00" level=debug msg="fetched chunk 12/21, size: 524288" uri="spotify:track:5YSI1311X8t31PBjkBG4CZ" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=debug msg="handling play player command from 3a5804b675ea71348da8d2e2d5b29fba5ff95213" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=debug msg="resolved context of track" uri="spotify:artist:1KsASRNugxU85T0u6zSg32" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=trace msg="fetched new page 0 with 10 items (list: 10)" uri="spotify:artist:1KsASRNugxU85T0u6zSg32" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=trace msg="loading context page from artistplaycontext/v1/page/spotify/artist-top-tracks-extensions/1KsASRNugxU85T0u6zSg32?exclude_uri=spotify:track:1300POeOaj8qho30FRHjgf,spotify:track:0MT7RXj7XwkyvA0e05fgo6,spotify:track:6DVySQASssOCxJOyWMY1jW,spotify:track:11dfOCV0oBxzZ9fi2PZhvS,spotify:track:5kRDK0lC01ceWWuXgkjYLi,spotify:track:1kWqRB6PHLM9QTfaXuHpIV,spotify:track:4tAJsamJJYw5OeqAePeMtz,spotify:track:7bG5NDhlrDel1aNofKPNf6,spotify:track:2k2Nwz0FLUIKl7UyavRhjx,spotify:track:6JtjhPFfHeEnoMDtKN35Qs" uri="spotify:artist:1KsASRNugxU85T0u6zSg32" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=error msg="failed fetching next tracks" error="failed moving to next index 10 (page 1): failed fetching page: invalid status code from page at artistplaycontext/v1/page/spotify/artist-top-tracks-extensions/1KsASRNugxU85T0u6zSg32?exclude_uri=spotify:track:1300POeOaj8qho30FRHjgf,spotify:track:0MT7RXj7XwkyvA0e05fgo6,spotify:track:6DVySQASssOCxJOyWMY1jW,spotify:track:11dfOCV0oBxzZ9fi2PZhvS,spotify:track:5kRDK0lC01ceWWuXgkjYLi,spotify:track:1kWqRB6PHLM9QTfaXuHpIV,spotify:track:4tAJsamJJYw5OeqAePeMtz,spotify:track:7bG5NDhlrDel1aNofKPNf6,spotify:track:2k2Nwz0FLUIKl7UyavRhjx,spotify:track:6JtjhPFfHeEnoMDtKN35Qs: 404" uri="spotify:artist:1KsASRNugxU85T0u6zSg32" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=debug msg="loading track (paused: false, position: 185ms)" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:19:53 volumio go-librespot[1602]: time="2026-01-13T11:19:53+01:00" level=trace msg="emitting websocket event: will_play" Jan 13 11:19:53 volumio volumio[1139]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:artist:1KsASRNugxU85T0u6zSg32","uri":"spotify:track:11dfOCV0oBxzZ9fi2PZhvS","play_origin":"free-tier-artist"}} Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="selected format OGG_VORBIS_320 (30a9c1ca29c77180a2b4016ced10c5d44b8a75c8)" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="requested aes key for file 30a9c1ca29c77180a2b4016ced10c5d44b8a75c8, gid: 11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="fetched first chunk of 25, total size is 13096312 bytes" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=trace msg="seek to 185ms (diff: 185ms, samples: 8158, bytes: 0)" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=info msg="loaded track \"Winter - 2015 Remaster\" (paused: false, position: 185ms, duration: 341813ms, prefetched: false)" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 160" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="fetched chunk 1/24, size: 524288" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=trace msg="scheduling prefetch in 312s" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=trace msg="emitting websocket event: metadata" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="sending successful reply for dealer request" Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:11dfOCV0oBxzZ9fi2PZhvS","name":"Winter - 2015 Remaster","artist_names":["Tori Amos"],"album_name":"Little Earthquakes (Deluxe Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f2f65c08a66e03c69cbc0081","position":185,"duration":341813,"release_date":"year:1992 month:1 day:6","track_number":5,"disc_number":1}} Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="fetched chunk 2/24, size: 524288" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=trace msg="emitting websocket event: playing" Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:artist:1KsASRNugxU85T0u6zSg32","uri":"spotify:track:11dfOCV0oBxzZ9fi2PZhvS","resume":false,"play_origin":"free-tier-artist"}} Jan 13 11:19:54 volumio volumio[1139]: info: Spotify is playing in volatile mode Jan 13 11:19:54 volumio volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: UNSET VOLATILE Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: {"status":"play","position":2,"title":"Wuthering Heights","artist":"Kate Bush","album":"The Kick Inside","albumart":"https://i.scdn.co/image/ab67616d0000b2733c9aa0b150e1a982fd76b1c5","uri":"spotify:track:5YSI1311X8t31PBjkBG4CZ","trackType":"spotify","codec":"ogg","seek":1,"duration":269,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jan 13 11:19:54 volumio volumio[1139]: info: Setting Spotify stop after unset volatile call Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: PUSH STATE SPOTIFY Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: {"status":"play","service":"spop","title":"Winter - 2015 Remaster","artist":"Tori Amos","album":"Little Earthquakes (Deluxe Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02f2f65c08a66e03c69cbc0081","uri":"spotify:track:11dfOCV0oBxzZ9fi2PZhvS","trackType":"spotify","seek":185,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 13 11:19:54 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 13 11:19:54 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 13 11:19:54 volumio volumio[1139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 11:19:54 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 13 11:19:54 volumio go-librespot[1602]: time="2026-01-13T11:19:54+01:00" level=debug msg="fetched chunk 3/24, size: 524288" uri="spotify:track:11dfOCV0oBxzZ9fi2PZhvS" Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: PUSH STATE SPOTIFY Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: {"status":"play","service":"spop","title":"Winter - 2015 Remaster","artist":"Tori Amos","album":"Little Earthquakes (Deluxe Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02f2f65c08a66e03c69cbc0081","uri":"spotify:track:11dfOCV0oBxzZ9fi2PZhvS","trackType":"spotify","seek":185,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jan 13 11:19:54 volumio volumio[1139]: info: CoreCommandRouter::servicePushState Jan 13 11:19:54 volumio volumio[1139]: info: CoreStateMachine::pushState Jan 13 11:19:54 volumio volumio[1139]: info: CoreCommandRouter::volumioPushState Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 13 11:19:54 volumio volumio[1139]: info: Spotify Stop Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: SPOTIFY STOP Jan 13 11:19:54 volumio volumio[1139]: SPOTIFY: {"status":"play","title":"Winter - 2015 Remaster","artist":"Tori Amos","album":"Little Earthquakes (Deluxe Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02f2f65c08a66e03c69cbc0081","uri":"spotify:track:11dfOCV0oBxzZ9fi2PZhvS","trackType":"spotify","codec":"ogg","seek":185,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jan 13 11:19:59 volumio volumio[1139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 11:19:59 volumio volumio[1139]: Error: read ECONNRESET Jan 13 11:19:59 volumio volumio[1139]: at TCP.onStreamRead (node:internal/stream_base_commons:217:20) { Jan 13 11:19:59 volumio volumio[1139]: errno: -104, Jan 13 11:19:59 volumio volumio[1139]: code: 'ECONNRESET', Jan 13 11:19:59 volumio volumio[1139]: syscall: 'read' Jan 13 11:19:59 volumio volumio[1139]: } Jan 13 11:19:59 volumio volumio[1139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 11:19:59 volumio sudo[10506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 11:18' Jan 13 11:19:59 volumio sudo[10506]: 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"