Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=debug msg="handling transfer player command from 32230a1b7d4bf656b3c9099194b3116a9c00ee01" Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=debug msg="resolved context of track" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=trace msg="emitting websocket event: will_play" Oct 12 21:27:03 roffe-mp3 volumio[1341]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","play_origin":"go-librespot"}} Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=debug msg="selected format OGG_VORBIS_320 (496abdb28a9a84eef05d157323774af1703d1e2f)" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=debug msg="requested aes key for file 496abdb28a9a84eef05d157323774af1703d1e2f, gid: 3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:03 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:03+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="fetched first chunk of 19, total size is 9488436 bytes" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="track has no narration" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="created new output device" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=info msg="loaded track \"Fireflies\" (paused: true, position: 0ms, duration: 228346ms, prefetched: false)" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=trace msg="emitting websocket event: metadata" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=trace msg="emitting websocket event: active" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="sending successful reply for dealer request" Oct 12 21:27:04 roffe-mp3 volumio[1341]: SPOTIFY: received: {"type":"metadata","data":{"album_cover_url":"https://i.scdn.co/image/ab67616d00001e02785d4e702802da500fc78b32","album_name":"Ocean Eyes","artist_names":["Owl City"],"bit_depth":null,"bitrate":320,"codec":"vorbis","disc_number":1,"duration":228346,"format":"OGG_VORBIS_320","name":"Fireflies","position":0,"release_date":"year:2009 month:1 day:1","sample_rate":44100,"track_number":9,"uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ"}} Oct 12 21:27:04 roffe-mp3 volumio[1341]: SPOTIFY: received: {"type":"active","data":null} Oct 12 21:27:04 roffe-mp3 volumio[1341]: info: Aligning Spotify Volume to Volumio Volume Oct 12 21:27:04 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:04 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=trace msg="emitting websocket event: paused" Oct 12 21:27:04 roffe-mp3 volumio[1341]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","play_origin":"go-librespot"}} Oct 12 21:27:04 roffe-mp3 volumio[1341]: SPOTIFY: PUSH STATE SPOTIFY Oct 12 21:27:04 roffe-mp3 volumio[1341]: SPOTIFY: {"status":"pause","service":"spop","title":"Fireflies","artist":"Owl City","album":"Ocean Eyes","albumart":"https://i.scdn.co/image/ab67616d00001e02785d4e702802da500fc78b32","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 12 21:27:04 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:27:04 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:04 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:04 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:06 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:06+02:00" level=debug msg="handling resume player command from 32230a1b7d4bf656b3c9099194b3116a9c00ee01" Oct 12 21:27:06 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:06+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:06 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:06+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 12 21:27:06 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:06+02:00" level=debug msg="resume track at 0ms" Oct 12 21:27:07 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:07 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:07+02:00" level=trace msg="scheduling prefetch in 198s" Oct 12 21:27:07 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:07+02:00" level=debug msg="sending successful reply for dealer request" Oct 12 21:27:07 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:07+02:00" level=trace msg="emitting websocket event: playing" Oct 12 21:27:07 roffe-mp3 volumio[1341]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","resume":true,"play_origin":"go-librespot"}} Oct 12 21:27:07 roffe-mp3 volumio[1341]: SPOTIFY: PUSH STATE SPOTIFY Oct 12 21:27:07 roffe-mp3 volumio[1341]: SPOTIFY: {"status":"play","service":"spop","title":"Fireflies","artist":"Owl City","album":"Ocean Eyes","albumart":"https://i.scdn.co/image/ab67616d00001e02785d4e702802da500fc78b32","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 12 21:27:07 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:27:07 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:07 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Oct 12 21:27:07 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:07 roffe-mp3 volumio[1341]: SPOTIFY: PUSH STATE SPOTIFY Oct 12 21:27:07 roffe-mp3 volumio[1341]: SPOTIFY: {"status":"play","service":"spop","title":"Fireflies","artist":"Owl City","album":"Ocean Eyes","albumart":"https://i.scdn.co/image/ab67616d00001e02785d4e702802da500fc78b32","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","trackType":"spotify","seek":0,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 12 21:27:07 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:27:07 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:07 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Oct 12 21:27:19 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:19+02:00" level=debug msg="fetched chunk 4/18, size: 524288" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:23 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:23+02:00" level=debug msg="handling pause player command from 32230a1b7d4bf656b3c9099194b3116a9c00ee01" Oct 12 21:27:23 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:23+02:00" level=debug msg="pause track at 16588ms" Oct 12 21:27:23 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:23+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:23 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:23+02:00" level=debug msg="sending successful reply for dealer request" Oct 12 21:27:23 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:23+02:00" level=trace msg="emitting websocket event: paused" Oct 12 21:27:23 roffe-mp3 volumio[1341]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","play_origin":"go-librespot"}} Oct 12 21:27:23 roffe-mp3 volumio[1341]: SPOTIFY: PUSH STATE SPOTIFY Oct 12 21:27:23 roffe-mp3 volumio[1341]: SPOTIFY: {"status":"pause","service":"spop","title":"Fireflies","artist":"Owl City","album":"Ocean Eyes","albumart":"https://i.scdn.co/image/ab67616d00001e02785d4e702802da500fc78b32","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","trackType":"spotify","seek":16000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 12 21:27:23 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:27:23 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:23 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Oct 12 21:27:23 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:23+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:24 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:24+02:00" level=debug msg="handling resume player command from 32230a1b7d4bf656b3c9099194b3116a9c00ee01" Oct 12 21:27:24 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:24+02:00" level=trace msg="seek to 16588ms (diff: 108ms, samples: 731530, bytes: 652881)" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:24 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:24+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 12 21:27:24 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:24+02:00" level=debug msg="resume track at 16480ms" Oct 12 21:27:25 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:25 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:25+02:00" level=trace msg="scheduling prefetch in 182s" Oct 12 21:27:25 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:25+02:00" level=debug msg="sending successful reply for dealer request" Oct 12 21:27:25 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:25+02:00" level=trace msg="emitting websocket event: playing" Oct 12 21:27:25 roffe-mp3 volumio[1341]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","resume":true,"play_origin":"go-librespot"}} Oct 12 21:27:25 roffe-mp3 volumio[1341]: SPOTIFY: PUSH STATE SPOTIFY Oct 12 21:27:25 roffe-mp3 volumio[1341]: SPOTIFY: {"status":"play","service":"spop","title":"Fireflies","artist":"Owl City","album":"Ocean Eyes","albumart":"https://i.scdn.co/image/ab67616d00001e02785d4e702802da500fc78b32","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","trackType":"spotify","seek":16000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 12 21:27:25 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:27:25 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:25 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Oct 12 21:27:25 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:25+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 12 21:27:25 roffe-mp3 volumio[1341]: SPOTIFY: PUSH STATE SPOTIFY Oct 12 21:27:25 roffe-mp3 volumio[1341]: SPOTIFY: {"status":"play","service":"spop","title":"Fireflies","artist":"Owl City","album":"Ocean Eyes","albumart":"https://i.scdn.co/image/ab67616d00001e02785d4e702802da500fc78b32","uri":"spotify:track:3DamFFqW32WihKkTVlwTYQ","trackType":"spotify","seek":16000,"duration":228,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 12 21:27:25 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:27:25 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:25 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Oct 12 21:27:34 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:34+02:00" level=debug msg="fetched chunk 5/18, size: 524288" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.009+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=21.772677ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.026+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.048+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=21.174712ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.069+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=http://pushupdates.volumio.org duration=42.026114ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.086+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=58.713901ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.086+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=58.994472ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.152+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://www.googleapis.com duration=125.369183ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.181+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=154.746439ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.196+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://securetoken.googleapis.com duration=169.471492ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.196+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://functions.volumio.cloud duration=169.254865ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.198+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://functions.volumio.cloud duration=171.332932ms Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.209+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://database.volumio.cloud duration=182.495752ms Oct 12 21:27:38 roffe-mp3 sudo[2239]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 21:27:38 roffe-mp3 sudo[2239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 21:27:38 roffe-mp3 sudo[2241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 21:27:38 roffe-mp3 sudo[2241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 21:27:38 roffe-mp3 sudo[2239]: pam_unix(sudo:session): session closed for user root Oct 12 21:27:38 roffe-mp3 sudo[2241]: pam_unix(sudo:session): session closed for user root Oct 12 21:27:38 roffe-mp3 volumio[1341]: verbose: New Socket.io Connection to 192.168.86.26 from 192.168.86.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: Received Get System Info Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: Discovery: Getting this device information Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: Listing playlists Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 21:27:38 roffe-mp3 sudo[2245]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 21:27:38 roffe-mp3 sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 21:27:38 roffe-mp3 sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 21:27:38 roffe-mp3 sudo[2247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 21:27:38 roffe-mp3 sudo[2245]: pam_unix(sudo:session): session closed for user root Oct 12 21:27:38 roffe-mp3 sudo[2247]: pam_unix(sudo:session): session closed for user root Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.552+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=http://cddb.volumio.org duration=525.700553ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 21:27:38 roffe-mp3 volumio[1341]: verbose: New Socket.io Connection to 192.168.86.26 from 192.168.86.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.609+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=http://plugins.volumio.org duration=582.104946ms Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: Received Get System Info Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: Discovery: Getting this device information Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: Listing playlists Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 21:27:38 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:27:38.714+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" latency=23.822599ms timeout=10s endpoint=https://google.com duration=687.235628ms Oct 12 21:27:38 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: Discovery: Getting this device information Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 21:27:39 roffe-mp3 volumio[1341]: verbose: New Socket.io Connection to 192.168.86.26:3000 from 192.168.86.20 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 21:27:39 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: Received Get System Info Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: Discovery: Getting this device information Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:40 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetQueue Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CoreStateMachine::getQueue Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CorePlayQueue::getQueue Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: Received Get System Info Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: Discovery: Getting this device information Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:41 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 21:27:47 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:47+02:00" level=debug msg="fetched chunk 6/18, size: 524288" uri="spotify:track:3DamFFqW32WihKkTVlwTYQ" Oct 12 21:27:48 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 12 21:27:50 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 21:27:50 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: Received Get System Version Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: Received Get System Info Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: Discovery: Getting this device information Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:27:56 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 21:27:57 roffe-mp3 go-librespot[1765]: time="2026-10-12T21:27:57+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 12 21:27:57 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 12 21:27:57 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 21:27:57 roffe-mp3 volumio[1341]: Cannot find translation for source 80s80s Radio Oct 12 21:27:57 roffe-mp3 volumio[1341]: Cannot find translation for source Radio Paradise Oct 12 21:27:57 roffe-mp3 volumio[1341]: info: Disabling plugin spop Oct 12 21:27:57 roffe-mp3 volumio[1341]: info: Done. Oct 12 21:27:57 roffe-mp3 sudo[2265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Oct 12 21:27:57 roffe-mp3 sudo[2265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 21:27:57 roffe-mp3 volumio[1341]: info: Connection to go-librespot Websocket closed Oct 12 21:27:57 roffe-mp3 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Oct 12 21:27:57 roffe-mp3 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Oct 12 21:27:57 roffe-mp3 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 21:27:57 roffe-mp3 systemd[1]: go-librespot-daemon.service: Consumed 1.316s CPU time. Oct 12 21:27:57 roffe-mp3 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. Oct 12 21:27:57 roffe-mp3 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. Oct 12 21:27:57 roffe-mp3 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. Oct 12 21:27:57 roffe-mp3 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. Oct 12 21:27:57 roffe-mp3 sudo[2265]: pam_unix(sudo:session): session closed for user root Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: Enabling plugin spop Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: Loading plugin "spop"... Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: PLUGIN START: spop Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: Creating Spotify config file Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: Done. Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: Spotify config file written Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: No need to fix Spotify hosts Oct 12 21:27:59 roffe-mp3 sudo[2283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 12 21:27:59 roffe-mp3 sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 21:27:59 roffe-mp3 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. Oct 12 21:27:59 roffe-mp3 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. Oct 12 21:27:59 roffe-mp3 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 21:27:59 roffe-mp3 go-librespot[2285]: go-librespot daemon starting... Oct 12 21:27:59 roffe-mp3 sudo[2283]: pam_unix(sudo:session): session closed for user root Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=info msg="running go-librespot 0.9.1" Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=debug msg="app state loaded" Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+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 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+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 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=info msg="zeroconf server listening on port 41987" Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=info msg="loading previously persisted zeroconf credentials" username="sz*bo" Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: New Spotify access tokenBQB6KcywwN... Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=debug msg="obtained new client token: AAH03qDZb60+gkKykG7rm/UV3atxESV3gcETdkQ4mqLEXMbcwAd8iURbTJqZvMo5HYACjlbHqZZaKn8TrJ80ukNbobcyVmScIO/i5nnQB5Q09mI7wjx45N8w6wT0A/aXXDophOCxoheASeWXFNuMd4JRlrt7jhFAQTY7DVuHw/uRzzUQ0hrIu6RLm/jpHPeTgIZqA2b/SJarRo7W0Ui9EJvmUFqSyHToyfRi26xon4gMmaIqsGPXj48=" Oct 12 21:27:59 roffe-mp3 volumio[1341]: SPOTIFY: User informations: {"account_id":"8yP6ULHR6L","country":"SE","display_name":"szabo","email":"challe@mac.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/szabo"},"followers":{"href":null,"total":6},"href":"https://api.spotify.com/v1/users/szabo","id":"szabo","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856e0a46490d4e6bd8b4fd9251","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826e0a46490d4e6bd8b4fd9251","width":64}],"product":"premium","type":"user","uri":"spotify:user:szabo"} Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: Spotify Successfully logged in Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: [1791833279924] CoreMusicLibrary::Adding element Spotify Oct 12 21:27:59 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 21:27:59 roffe-mp3 volumio[1341]: Cannot find translation for source 80s80s Radio Oct 12 21:27:59 roffe-mp3 volumio[1341]: Cannot find translation for source Radio Paradise Oct 12 21:27:59 roffe-mp3 volumio[1341]: Cannot find translation for source Spotify Oct 12 21:27:59 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:27:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="completed keyexchange" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="completed challenge" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=info msg="authenticated AP" username="sz*bo" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=info msg="authenticated Login5" username="sz*bo" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="initializing zeroconf session" username="sz*bo" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="connected to gew4-dealer.spotify.com:443" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=trace msg="starting accesspoint recv loop" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=trace msg="starting dealer recv loop" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="received connection id: OWIwN2Q1NTAtOGU5...RTlBMzFGNzVCMg==" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=trace msg="emitting websocket event: playback_ready" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="update volume requested to 65535/65535" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="avahi service \"Roffe MP3\" established" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 12 21:28:00 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:00+02:00" level=trace msg="emitting websocket event: volume" Oct 12 21:28:02 roffe-mp3 volumio[1341]: info: go-librespot daemon successfully initialized Oct 12 21:28:05 roffe-mp3 volumio[1341]: info: Initializing connection to go-librespot Websocket Oct 12 21:28:05 roffe-mp3 go-librespot[2286]: time="2026-10-12T21:28:05+02:00" level=debug msg="new websocket client" Oct 12 21:28:05 roffe-mp3 volumio[1341]: info: Connection to go-librespot Websocket established Oct 12 21:28:08 roffe-mp3 volumio[1341]: info: Getting Spotify volume Oct 12 21:28:08 roffe-mp3 volumio[1341]: info: Spotify volume: 100 Oct 12 21:28:08 roffe-mp3 volumio[1341]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 12 21:28:08 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 12 21:28:08 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 21:28:13 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 21:28:20 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 12 21:28:20 roffe-mp3 volumio[1341]: info: In handleBrowseUri, curUri=spotify Oct 12 21:28:21 roffe-mp3 volumio[1341]: info: Preload queue cleared Oct 12 21:28:21 roffe-mp3 volumio[1341]: info: Preload queue cleared Oct 12 21:28:21 roffe-mp3 volumio[1341]: info: Preload queue cleared Oct 12 21:28:21 roffe-mp3 volumio[1341]: info: Preload queue cleared Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioPlay Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreStateMachine::play index undefined Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreStateMachine::startPlaybackTimer Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: [1791833302377] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: [1791833302377] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: [1791833302627] [80s80s] received new event containing 2 songs. Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: sendMpdCommand stop took 1 milliseconds Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: Oct 12 21:28:22 roffe-mp3 volumio[1341]: ---------------------------- MPD announces system playlist update Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: Ignoring MPD Status Update Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: sendMpdCommand clear took 2 milliseconds Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 12 21:28:22 roffe-mp3 volumio[1341]: error: updateQueue error: null Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: Oct 12 21:28:22 roffe-mp3 volumio[1341]: ---------------------------- MPD announces state update: options Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: ------------------------------ 3ms Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: ControllerMpd::getState Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: [1791833302633] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: sendMpdCommand status took 7 milliseconds Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: ControllerMpd::parseState Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: ControllerMpd::pushState Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreStateMachine::pushState Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioPushState Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Oct 12 21:28:22 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:28:22.645+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" state=STATUS_STOPPED positionMs=250 volume=100 Oct 12 21:28:22 roffe-mp3 volumio5-onboarding[1601]: time=2026-10-12T21:28:22.645+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.20:58329,00:00:00:00:00:00%01 @ 0x34fc600" id=http://streams.80s80s.de/love/mp3-192/volumio title="80s80s Love" Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: ------------------------------ 14ms Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: Oct 12 21:28:22 roffe-mp3 volumio[1341]: ---------------------------- MPD announces system playlist update Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: Ignoring MPD Status Update Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 12 milliseconds Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand play Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: ------------------------------ 5ms Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: sendMpdCommand play took 3 milliseconds Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: [1791833302652] [80s80s] Pushing the next song state: Level 42 - Lessons In Love Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/af/fd/a7/affda714-360c-9711-fbf8-a7b1a414da7b/source/600x600bb.jpg","name":"Level 42 - Lessons In Love","title":"Lessons In Love","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"229","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 12 21:28:22 roffe-mp3 volumio[1341]: verbose: CURRENT POSITION 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreStateMachine::syncState stateService play Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CoreStateMachine::syncState currentStatus stop Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: [1791833302653] [80s80s] PlayNextTrack API delay: 30 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: [1791833302658] [80s80s] Setting timer to: 229000 milliseconds. Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:22 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 1 Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Oct 12 21:28:23 roffe-mp3 volumio[1341]: ---------------------------- MPD announces state update: player Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ControllerMpd::getState Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Oct 12 21:28:23 roffe-mp3 volumio[1341]: ---------------------------- MPD announces state update: player Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: sendMpdCommand status took 4 milliseconds Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ControllerMpd::getState Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::parseState Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: sendMpdCommand status took 1 milliseconds Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::parseState Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ControllerMpd::pushState Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ------------------------------ 6ms Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ControllerMpd::pushState Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ------------------------------ 3ms Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Oct 12 21:28:23 roffe-mp3 volumio[1341]: ---------------------------- MPD announces system playlist update Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Ignoring MPD Status Update Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Oct 12 21:28:23 roffe-mp3 volumio[1341]: ---------------------------- MPD announces state update: player Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ControllerMpd::getState Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ------------------------------ 1ms Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: sendMpdCommand status took 0 milliseconds Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::parseState Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 21:28:23 roffe-mp3 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ControllerMpd::pushState Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: ------------------------------ 2ms Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 12 21:28:23 roffe-mp3 volumio[1341]: info: In handleBrowseUri, curUri=spotify/myartists Oct 12 21:28:24 roffe-mp3 volumio[1341]: info: Preload queue cleared Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: Preload queue cleared Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreStateMachine::ClearQueue Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreStateMachine::stop Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreStateMachine::stPlaybackTimer Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreStateMachine::updateTrackBlock Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrackBlock Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreStateMachine::pushState Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CorePlayQueue::getTrack 0 Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 21:28:32 roffe-mp3 volumio[1341]: info: CoreCommandRouter::volumioPushState Oct 12 21:28:32 roffe-mp3 volumio[1341]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 21:28:32 roffe-mp3 volumio[1341]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Oct 12 21:28:32 roffe-mp3 volumio[1341]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at /volumio/app/index.js:326:81 Oct 12 21:28:32 roffe-mp3 volumio[1341]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at Socket.emit (node:events:514:28) Oct 12 21:28:32 roffe-mp3 volumio[1341]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 12 21:28:32 roffe-mp3 volumio[1341]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 12 21:28:32 roffe-mp3 volumio[1341]: code: 'NumberExpected' Oct 12 21:28:32 roffe-mp3 volumio[1341]: } Oct 12 21:28:32 roffe-mp3 volumio[1341]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 21:28:32 roffe-mp3 sudo[2368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 21:27' Oct 12 21:28:32 roffe-mp3 sudo[2368]: 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"