-- Logs begin at Fri 2026-01-09 21:18:21 GMT, end at Sat 2026-01-10 12:12:03 GMT. -- Jan 10 12:11:10 volumio go-librespot[1316]: time="2026-01-10T12:11:10Z" level=trace msg="sent dealer ping" Jan 10 12:11:11 volumio go-librespot[1316]: time="2026-01-10T12:11:11Z" level=trace msg="received dealer pong" Jan 10 12:11:21 volumio go-librespot[1316]: time="2026-01-10T12:11:21Z" level=debug msg="handling transfer player command from d6a03b3fe4fc808a5ab478a8f1f3416f2904b91b" Jan 10 12:11:21 volumio go-librespot[1316]: time="2026-01-10T12:11:21Z" level=debug msg="renewing login5 access token" Jan 10 12:11:21 volumio go-librespot[1316]: time="2026-01-10T12:11:21Z" level=info msg="authenticated Login5" username="ca******47" Jan 10 12:11:21 volumio go-librespot[1316]: time="2026-01-10T12:11:21Z" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DWZMAcZLI8XCI" Jan 10 12:11:21 volumio go-librespot[1316]: time="2026-01-10T12:11:21Z" level=trace msg="fetched new page 0 with 60 items (list: 60)" uri="spotify:playlist:37i9dQZF1DWZMAcZLI8XCI" Jan 10 12:11:21 volumio go-librespot[1316]: time="2026-01-10T12:11:21Z" level=debug msg="loading track (paused: true, position: 81606ms)" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=trace msg="emitting websocket event: will_play" Jan 10 12:11:22 volumio volumio[982]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1DWZMAcZLI8XCI","uri":"spotify:track:5GXTMTZgD4kuvhhX50YRSN","play_origin":"playlist/ondemand"}} Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="selected format OGG_VORBIS_320 (55e1fb788123dd0c9c45721172d175a097904224)" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="requested aes key for file 55e1fb788123dd0c9c45721172d175a097904224, gid: 5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1668" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=trace msg="found 2 cdn urls" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1278" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="fetched first chunk of 27, total size is 14091952 bytes" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:22 volumio go-librespot[1316]: time="2026-01-10T12:11:22Z" level=debug msg="fetched chunk 1/26, size: 524288" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="fetched chunk 2/26, size: 524288" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="fetched chunk 3/26, size: 524288" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="fetched chunk 7/26, size: 524288" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="fetched chunk 6/26, size: 524288" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=trace msg="seek to 81606ms (diff: 83ms, samples: 3598824, bytes: 3261573)" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="created new output device" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=info msg="loaded track \"Horrostaja\" (paused: true, position: 81606ms, duration: 349500ms, prefetched: false)" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="fetched chunk 9/26, size: 524288" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="fetched chunk 8/26, size: 524288" uri="spotify:track:5GXTMTZgD4kuvhhX50YRSN" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=trace msg="emitting websocket event: metadata" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=trace msg="emitting websocket event: active" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="sending successful reply for dealer request" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5GXTMTZgD4kuvhhX50YRSN","name":"Horrostaja","artist_names":["Mesak"],"album_name":"The Meaning Of Time","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028eb1f7697ac2c8e3d5461099","position":81606,"duration":349500,"release_date":"year:2020 month:6 day:15","track_number":3,"disc_number":1}} Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: received: {"type":"active","data":null} Jan 10 12:11:23 volumio volumio[982]: info: Aligning Spotify Volume to Volumio Volume Jan 10 12:11:23 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:23 volumio volumio[982]: info: CorePlayQueue::getTrack 0 Jan 10 12:11:23 volumio volumio[982]: info: Setting Spotify Volume from Volumio: 45 Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=trace msg="emitting websocket event: paused" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="handling update_context player command from d6a03b3fe4fc808a5ab478a8f1f3416f2904b91b" Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1DWZMAcZLI8XCI","uri":"spotify:track:5GXTMTZgD4kuvhhX50YRSN","play_origin":"playlist/ondemand"}} Jan 10 12:11:23 volumio volumio[982]: info: Spotify is playing in volatile mode Jan 10 12:11:23 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: UNSET VOLATILE Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: {"status":"play","position":0,"title":"Dario G - Sunchyme","artist":"90s90s Hits","album":"","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music118/v4/18/e3/83/18e3833a-d38c-9177-8a6c-046dd666973a/source/600x600bb.jpg","uri":"http://streams.90s90s.de/pop/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":"227","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":45,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Jan 10 12:11:23 volumio volumio[982]: info: Setting Spotify stop after unset volatile call Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: {"status":"pause","service":"spop","title":"Horrostaja","artist":"Mesak","album":"The Meaning Of Time","albumart":"https://i.scdn.co/image/ab67616d00001e028eb1f7697ac2c8e3d5461099","uri":"spotify:track:5GXTMTZgD4kuvhhX50YRSN","trackType":"spotify","seek":81606,"duration":349,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 10 12:11:23 volumio volumio[982]: info: CoreCommandRouter::servicePushState Jan 10 12:11:23 volumio volumio[982]: info: CoreStateMachine::pushState Jan 10 12:11:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 12:11:23 volumio volumio[982]: info: CoreCommandRouter::volumioPushState Jan 10 12:11:23 volumio volumio[982]: info: MRS: Pushing multiroomSync output update for this device Jan 10 12:11:23 volumio volumio[982]: info: MRS: Pushing multiroomSync output Jan 10 12:11:23 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:23 volumio volumio[982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="sending successful reply for dealer request" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX76cnmxfAAhD" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXbMaZjhsGw5d" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX7ygx34a8k2z" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4yimuvBoS93o" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX4Jyx1uIhxnP" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4mHIEySep1sG" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EVJSvZp5AOML2" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIZ70xwgLGwWg" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIXne7ZV60GIh" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:23 volumio go-librespot[1316]: time="2026-01-10T12:11:23Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIY4JGEcmm4dD" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX4UtSsGT1Sbe" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQp62d3Dl7ECY" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:24 volumio volumio[982]: info: Spotify Stop Jan 10 12:11:24 volumio volumio[982]: SPOTIFY: SPOTIFY STOP Jan 10 12:11:24 volumio volumio[982]: SPOTIFY: {"status":"pause","title":"Horrostaja","artist":"Mesak","album":"The Meaning Of Time","albumart":"https://i.scdn.co/image/ab67616d00001e028eb1f7697ac2c8e3d5461099","uri":"spotify:track:5GXTMTZgD4kuvhhX50YRSN","trackType":"spotify","codec":"ogg","seek":81606,"duration":349,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":45,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8JPG11re7yJm" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8PiI7kWw08um" Jan 10 12:11:24 volumio go-librespot[1316]: time="2026-01-10T12:11:24Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 10 12:11:25 volumio volumio[982]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Jan 10 12:11:25 volumio volumio[982]: info: Sending Spotify command with payload to local API: /player/volume Jan 10 12:11:25 volumio go-librespot[1316]: time="2026-01-10T12:11:25Z" level=debug msg="update volume requested to 29490/65535" Jan 10 12:11:25 volumio go-librespot[1316]: time="2026-01-10T12:11:25Z" level=debug msg="put connect state because VOLUME_CHANGED" Jan 10 12:11:25 volumio go-librespot[1316]: time="2026-01-10T12:11:25Z" level=trace msg="emitting websocket event: volume" Jan 10 12:11:25 volumio volumio[982]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Jan 10 12:11:25 volumio volumio[982]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Jan 10 12:11:36 volumio go-librespot[1316]: time="2026-01-10T12:11:36Z" level=debug msg="handling play player command from d6a03b3fe4fc808a5ab478a8f1f3416f2904b91b" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="resolved context of track" uri="spotify:user:cassius747:collection" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=trace msg="fetched new page 0 with 1311 items (list: 1311)" uri="spotify:user:cassius747:collection" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="shuffled context with seed 11131329720743053531 (len: 1311, keep: 637)" uri="spotify:user:cassius747:collection" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="loading track (paused: false, position: 2ms)" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=trace msg="emitting websocket event: will_play" Jan 10 12:11:37 volumio volumio[982]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:cassius747:collection","uri":"spotify:track:7bLyV7vbRhhhjSlgWPrnZZ","play_origin":"playlist"}} Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="selected format OGG_VORBIS_320 (5811caa084dd60d47905805b3450de5f247ab1aa)" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="requested aes key for file 5811caa084dd60d47905805b3450de5f247ab1aa, gid: 7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=trace msg="found 2 cdn urls" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="fetched first chunk of 22, total size is 11399300 bytes" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=trace msg="seek to 2ms (diff: -27ms, samples: 88, bytes: 73)" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" 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" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=info msg="loaded track \"Bring in the Katz - Original Mix\" (paused: false, position: 2ms, duration: 264597ms, prefetched: false)" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 10 12:11:37 volumio volumio[982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=trace msg="scheduling prefetch in 235s" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=trace msg="emitting websocket event: metadata" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="sending successful reply for dealer request" Jan 10 12:11:37 volumio volumio[982]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7bLyV7vbRhhhjSlgWPrnZZ","name":"Bring in the Katz - Original Mix","artist_names":["KW Griff","Pork Chop"],"album_name":"Club Constructions Volume 3","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02fe44312120a080a462683d6f","position":2,"duration":264597,"release_date":"year:2012 month:9 day:12","track_number":1,"disc_number":1}} Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=trace msg="emitting websocket event: playing" Jan 10 12:11:37 volumio volumio[982]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:cassius747:collection","uri":"spotify:track:7bLyV7vbRhhhjSlgWPrnZZ","resume":false,"play_origin":"playlist"}} Jan 10 12:11:37 volumio volumio[982]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 12:11:37 volumio volumio[982]: SPOTIFY: {"status":"play","service":"spop","title":"Bring in the Katz - Original Mix","artist":"KW Griff, Pork Chop","album":"Club Constructions Volume 3","albumart":"https://i.scdn.co/image/ab67616d00001e02fe44312120a080a462683d6f","uri":"spotify:track:7bLyV7vbRhhhjSlgWPrnZZ","trackType":"spotify","seek":2,"duration":264,"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 10 12:11:37 volumio volumio[982]: info: CoreCommandRouter::servicePushState Jan 10 12:11:37 volumio volumio[982]: info: CoreStateMachine::pushState Jan 10 12:11:37 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 12:11:37 volumio volumio[982]: info: CoreCommandRouter::volumioPushState Jan 10 12:11:37 volumio volumio[982]: info: MRS: Pushing multiroomSync output update for this device Jan 10 12:11:37 volumio volumio[982]: info: MRS: Pushing multiroomSync output Jan 10 12:11:37 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:37 volumio volumio[982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 12:11:37 volumio go-librespot[1316]: time="2026-01-10T12:11:37Z" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:7bLyV7vbRhhhjSlgWPrnZZ" Jan 10 12:11:38 volumio volumio[982]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 12:11:38 volumio volumio[982]: SPOTIFY: {"status":"play","service":"spop","title":"Bring in the Katz - Original Mix","artist":"KW Griff, Pork Chop","album":"Club Constructions Volume 3","albumart":"https://i.scdn.co/image/ab67616d00001e02fe44312120a080a462683d6f","uri":"spotify:track:7bLyV7vbRhhhjSlgWPrnZZ","trackType":"spotify","seek":2,"duration":264,"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 10 12:11:38 volumio volumio[982]: info: CoreCommandRouter::servicePushState Jan 10 12:11:38 volumio volumio[982]: info: CoreStateMachine::pushState Jan 10 12:11:38 volumio volumio[982]: info: CoreCommandRouter::volumioPushState Jan 10 12:11:38 volumio volumio[982]: info: MRS: Pushing multiroomSync output update for this device Jan 10 12:11:38 volumio volumio[982]: info: MRS: Pushing multiroomSync output Jan 10 12:11:38 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:38 volumio volumio[982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 12:11:40 volumio go-librespot[1316]: time="2026-01-10T12:11:40Z" level=trace msg="sent dealer ping" Jan 10 12:11:41 volumio go-librespot[1316]: time="2026-01-10T12:11:41Z" level=trace msg="received dealer pong" Jan 10 12:11:45 volumio go-librespot[1316]: time="2026-01-10T12:11:45Z" level=debug msg="handling pause player command from d6a03b3fe4fc808a5ab478a8f1f3416f2904b91b" Jan 10 12:11:45 volumio go-librespot[1316]: time="2026-01-10T12:11:45Z" level=debug msg="pause track at 4145ms" Jan 10 12:11:45 volumio go-librespot[1316]: time="2026-01-10T12:11:45Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:45 volumio go-librespot[1316]: time="2026-01-10T12:11:45Z" level=debug msg="sending successful reply for dealer request" Jan 10 12:11:45 volumio go-librespot[1316]: time="2026-01-10T12:11:45Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 12:11:45 volumio go-librespot[1316]: time="2026-01-10T12:11:45Z" level=trace msg="emitting websocket event: paused" Jan 10 12:11:45 volumio volumio[982]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:user:cassius747:collection","uri":"spotify:track:7bLyV7vbRhhhjSlgWPrnZZ","play_origin":"playlist"}} Jan 10 12:11:45 volumio volumio[982]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 12:11:45 volumio volumio[982]: SPOTIFY: {"status":"pause","service":"spop","title":"Bring in the Katz - Original Mix","artist":"KW Griff, Pork Chop","album":"Club Constructions Volume 3","albumart":"https://i.scdn.co/image/ab67616d00001e02fe44312120a080a462683d6f","uri":"spotify:track:7bLyV7vbRhhhjSlgWPrnZZ","trackType":"spotify","seek":8002,"duration":264,"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 10 12:11:45 volumio volumio[982]: info: CoreCommandRouter::servicePushState Jan 10 12:11:45 volumio volumio[982]: info: CoreStateMachine::pushState Jan 10 12:11:45 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 12:11:45 volumio volumio[982]: info: CoreCommandRouter::volumioPushState Jan 10 12:11:45 volumio volumio[982]: info: MRS: Pushing multiroomSync output update for this device Jan 10 12:11:45 volumio volumio[982]: info: MRS: Pushing multiroomSync output Jan 10 12:11:45 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:45 volumio volumio[982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 12:11:53 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.15 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:53 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:53 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.15 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:53 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:53 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 10 12:11:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 10 12:11:54 volumio sudo[22701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 10 12:11:54 volumio sudo[22701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:54 volumio sudo[22701]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:54 volumio sudo[22709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 12:11:54 volumio sudo[22709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:54 volumio sudo[22709]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:54 volumio sudo[22716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 12:11:54 volumio sudo[22716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:54 volumio sudo[22716]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:54 volumio sudo[22722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 12:11:54 volumio sudo[22722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:54 volumio sudo[22722]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:54 volumio sudo[22728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 12:11:54 volumio sudo[22728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:54 volumio sudo[22728]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:54 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:54 volumio sudo[22731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 12:11:54 volumio sudo[22731]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:54 volumio sudo[22731]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:54 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.15 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:54 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:54 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:54 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.15 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 12:11:54 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 12:11:55 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 12:11:55 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 12:11:56 volumio sudo[22739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 12:11:56 volumio sudo[22739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:56 volumio sudo[22739]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:56 volumio sudo[22742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 12:11:56 volumio sudo[22742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:56 volumio sudo[22742]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:56 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.15 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Build/BP4A.251205.006.A4; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 10 12:11:56 volumio sudo[22745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 12:11:56 volumio sudo[22745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:56 volumio sudo[22745]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:56 volumio sudo[22748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 12:11:56 volumio sudo[22748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 12:11:56 volumio sudo[22748]: pam_unix(sudo:session): session closed for user root Jan 10 12:11:56 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.15 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Build/BP4A.251205.006.A4; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::volumioGetQueue Jan 10 12:11:57 volumio volumio[982]: info: CoreStateMachine::getQueue Jan 10 12:11:57 volumio volumio[982]: info: CorePlayQueue::getQueue Jan 10 12:11:57 volumio volumio[982]: info: Listing playlists Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 10 12:11:57 volumio volumio[982]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 10 12:11:57 volumio volumio[982]: info: Received Get System Info Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:57 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 12:11:57 volumio volumio[982]: info: FusionDsp - Soundtrack-FusionDsp Jan 10 12:11:57 volumio volumio[982]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jan 10 12:11:57 volumio volumio[982]: info: Jan 10 12:11:57 volumio volumio[982]: ---------------------------- MPD announces system playlist update Jan 10 12:11:57 volumio volumio[982]: info: Ignoring MPD Status Update Jan 10 12:11:57 volumio volumio[982]: info: Jan 10 12:11:57 volumio volumio[982]: ---------------------------- MPD announces state update: player Jan 10 12:11:57 volumio volumio[982]: info: ControllerMpd::getState Jan 10 12:11:57 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status Jan 10 12:11:57 volumio volumio[982]: info: ------------------------------ 6ms Jan 10 12:11:57 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds Jan 10 12:11:57 volumio volumio[982]: verbose: ControllerMpd::parseState Jan 10 12:11:57 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 12:11:57 volumio volumio[982]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 12:11:57 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo Jan 10 12:11:57 volumio volumio[982]: info: ControllerMpd::pushState Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::servicePushState Jan 10 12:11:57 volumio volumio[982]: info: CoreStateMachine::pushState Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::volumioPushState Jan 10 12:11:57 volumio volumio[982]: info: MRS: Pushing multiroomSync output update for this device Jan 10 12:11:57 volumio volumio[982]: info: MRS: Pushing multiroomSync output Jan 10 12:11:57 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:57 volumio volumio[982]: info: ------------------------------ 22ms Jan 10 12:11:57 volumio volumio[982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 12:11:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 12:11:58 volumio volumio[982]: info: Received Get System Info Jan 10 12:11:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:58 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:58 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:11:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 10 12:11:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 12:11:59 volumio volumio[982]: info: Received Get System Info Jan 10 12:11:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:11:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:11:59 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:11:59 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:11:59 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:12:02 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 12:12:02 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 12:12:02 volumio volumio[982]: info: Discovery: Getting this device information Jan 10 12:12:02 volumio volumio[982]: info: CoreCommandRouter::volumioGetState Jan 10 12:12:02 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 12:12:02 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.1.7:3000 from 192.168.1.15 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 10 12:12:02 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 12:12:02 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 12:12:02 volumio volumio[982]: info: [1768047122677] [80s80s] Pushing the next song state Liquido - Narcotic and getting next track. Jan 10 12:12:02 volumio volumio[982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 12:12:02 volumio volumio[982]: TypeError: Cannot set property 'name' of undefined Jan 10 12:12:02 volumio volumio[982]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Jan 10 12:12:02 volumio volumio[982]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Jan 10 12:12:02 volumio volumio[982]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Jan 10 12:12:02 volumio volumio[982]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Jan 10 12:12:02 volumio volumio[982]: at processImmediate (internal/timers.js:461:21) Jan 10 12:12:02 volumio volumio[982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 12:12:03 volumio sudo[22774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 12:11 Jan 10 12:12:03 volumio sudo[22774]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"