Apr 03 06:05:43 streamer shairport-sync[2534]: warning: failed to create avahi service browser: An unexpected D-Bus error occurred Apr 03 06:05:43 streamer shairport-sync[2534]: WARNING: Unhandled message: interface=(null), path=(null), member=(null) Apr 03 06:05:43 streamer shairport-sync[2534]: warning: failed to create avahi service browser: An unexpected D-Bus error occurred Apr 03 06:05:43 streamer shairport-sync[2534]: WARNING: Unhandled message: interface=(null), path=(null), member=(null) Apr 03 06:05:43 streamer shairport-sync[2534]: warning: failed to create avahi service browser: An unexpected D-Bus error occurred Apr 03 06:05:43 streamer shairport-sync[2534]: warning: failed to create avahi service browser: An unexpected D-Bus error occurred Apr 03 06:05:43 streamer shairport-sync[2534]: WARNING: Unhandled message: interface=(null), path=(null), member=(null) Apr 03 06:05:43 streamer shairport-sync[2534]: warning: failed to create avahi service browser: An unexpected D-Bus error occurred Apr 03 06:05:43 streamer shairport-sync[2534]: warning: failed to create avahi service browser: An unexpected D-Bus error occurred Apr 03 06:05:43 streamer shairport-sync[2534]: WARNING: Unhandled message: interface=(null), path=(null), member=(null) Apr 03 06:05:43 streamer shairport-sync[2534]: WARNING: Unhandled message: interface=(null), path=(null), member=(null) Apr 03 06:05:43 streamer shairport-sync[2534]: WARNING: Unhandled message: interface=(null), path=(null), member=(null) Apr 03 06:05:43 streamer shairport-sync[2534]: WARNING: Unhandled message: interface=(null), path=(null), member=(null) Apr 03 06:05:43 streamer volumio-remote-updater[682]: [2026-04-03 06:00:59] [connect] Successful connection Apr 03 06:05:43 streamer volumio-remote-updater[682]: [2026-04-03 06:05:41] [info] Error getting remote endpoint: asio.system:9 (Bad file descriptor) Apr 03 06:05:43 streamer volumio-remote-updater[682]: [2026-04-03 06:05:41] [info] asio async_shutdown error: asio.system:9 (Bad file descriptor) Apr 03 06:05:43 streamer volumio-remote-updater[682]: [2026-04-03 06:05:41] [error] handle_connect error: Timer Expired Apr 03 06:05:43 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:623rRTKwGmgjH6sjE9uWLh","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:43 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:43 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Scatman (ski-ba-bop-ba-dop-bop)","artist":"Scatman John","album":"Scatman's World","albumart":"https://i.scdn.co/image/ab67616d00001e02c7ed51b9dc1014285cae2ae2","uri":"spotify:track:623rRTKwGmgjH6sjE9uWLh","trackType":"spotify","seek":46,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:43 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:30+01:00" level=debug msg="fetched chunk 17/20, size: 524288" uri="spotify:track:6VObnIkLVruX4UVyxWhlqm" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:33+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:33+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:39+01:00" level=debug msg="prefetching next track" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:43+01:00" level=debug msg="fetched chunk 18/20, size: 524288" uri="spotify:track:6VObnIkLVruX4UVyxWhlqm" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:43+01:00" level=debug msg="selected format OGG_VORBIS_320 (17fb9c73b61cbafe510ecbfed2c1c6a09b2b9969)" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:43+01:00" level=debug msg="requested aes key for file 17fb9c73b61cbafe510ecbfed2c1c6a09b2b9969, gid: 6YK0bf1uQjoZ4ghsQyhS83" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:44+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:44+01:00" level=debug msg="fetched first chunk of 6, total size is 2946437 bytes" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:44+01:00" level=info msg="prefetched track \"Leave It All To Me (iCarly chill)\" (duration: 76153ms)" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:44+01:00" level=debug msg="fetched chunk 3/5, size: 524288" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:44+01:00" level=debug msg="fetched chunk 1/5, size: 524288" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:44+01:00" level=debug msg="fetched chunk 2/5, size: 524288" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:01:56+01:00" level=debug msg="fetched chunk 19/20, size: 524288" uri="spotify:track:6VObnIkLVruX4UVyxWhlqm" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:02:03+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:02:03+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:02:09+01:00" level=debug msg="fetched chunk 20/20, size: 302698" uri="spotify:track:6VObnIkLVruX4UVyxWhlqm" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:02:33+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:02:33+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:03:03+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:03:04+01:00" level=trace msg="received accesspoint ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:03:04+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:03:04+01:00" level=trace msg="received accesspoint pong ack" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:03:33+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:03:33+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:04:03+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:04:04+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:04:33+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:04:33+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:05:03+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:05:04+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:05:04+01:00" level=trace msg="received accesspoint ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:05:04+01:00" level=trace msg="received accesspoint pong ack" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:05:33+01:00" level=trace msg="sent dealer ping" Apr 03 06:05:43 streamer go-librespot[3187]: time="2026-04-03T06:05:33+01:00" level=trace msg="received dealer pong" Apr 03 06:05:43 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:44 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:44 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:44 streamer volumio5-onboarding[2035]: time=2026-04-03T05:59:15.845+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp: lookup localhost: i/o timeout" Apr 03 06:05:44 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:44 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:44 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:623rRTKwGmgjH6sjE9uWLh","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:06aIJr6MYcTFZWlto1xWZo","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7lpHFS2YtG7C9hjEIIVEk7","name":"Honestly","artist_names":["Lil Peep"],"album_name":"Honestly","album_cover_url":"https://i.scdn.co/image/ab67616d00001e024b7b035d85fb42d409b69736","position":56,"duration":128679,"release_date":"year:2016 month:12 day:14","track_number":1,"disc_number":1}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:06aIJr6MYcTFZWlto1xWZo","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Honestly","artist":"Lil Peep","album":"Honestly","albumart":"https://i.scdn.co/image/ab67616d00001e024b7b035d85fb42d409b69736","uri":"spotify:track:7lpHFS2YtG7C9hjEIIVEk7","trackType":"spotify","seek":56,"duration":128,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:06aIJr6MYcTFZWlto1xWZo","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:49OMJ1prsRA7ZYgrAjz70c","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:49OMJ1prsRA7ZYgrAjz70c","name":"More Than A Woman","artist_names":["Bee Gees"],"album_name":"Greatest","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0252038992fc6d7868f31d23b7","position":40,"duration":197213,"release_date":"year:1979 month:1 day:1","track_number":4,"disc_number":2}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:49OMJ1prsRA7ZYgrAjz70c","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"More Than A Woman","artist":"Bee Gees","album":"Greatest","albumart":"https://i.scdn.co/image/ab67616d00001e0252038992fc6d7868f31d23b7","uri":"spotify:track:49OMJ1prsRA7ZYgrAjz70c","trackType":"spotify","seek":40,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:49OMJ1prsRA7ZYgrAjz70c","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:0lP4HYLmvowOKdsQ7CVkuq","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0lP4HYLmvowOKdsQ7CVkuq","name":"The Kill","artist_names":["Thirty Seconds To Mars"],"album_name":"A Beautiful Lie","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02865b538169f199dac610b854","position":64,"duration":231533,"release_date":"year:2005 month:8 day:16","track_number":3,"disc_number":1}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:0lP4HYLmvowOKdsQ7CVkuq","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"The Kill","artist":"Thirty Seconds To Mars","album":"A Beautiful Lie","albumart":"https://i.scdn.co/image/ab67616d00001e02865b538169f199dac610b854","uri":"spotify:track:0lP4HYLmvowOKdsQ7CVkuq","trackType":"spotify","seek":64,"duration":231,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:0lP4HYLmvowOKdsQ7CVkuq","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5SQwCLhJLRW5K3cE6qNzd8","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5SQwCLhJLRW5K3cE6qNzd8","name":"Put 'em High","artist_names":["Daire"],"album_name":"Put 'em High","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025d21a12b10466e2509ff630e","position":75,"duration":155578,"release_date":"year:2023 month:9 day:15","track_number":1,"disc_number":1}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5SQwCLhJLRW5K3cE6qNzd8","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Put 'em High","artist":"Daire","album":"Put 'em High","albumart":"https://i.scdn.co/image/ab67616d00001e025d21a12b10466e2509ff630e","uri":"spotify:track:5SQwCLhJLRW5K3cE6qNzd8","trackType":"spotify","seek":75,"duration":155,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5SQwCLhJLRW5K3cE6qNzd8","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:1LIvdWNdKmHvU0FJvw2diN","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1LIvdWNdKmHvU0FJvw2diN","name":"Big City Life","artist_names":["Luude","Mattafix"],"album_name":"Big City Life","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022a2c9dd7c941a90d3e75d9d7","position":44,"duration":146711,"release_date":"year:2022 month:6 day:16","track_number":1,"disc_number":1}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:1LIvdWNdKmHvU0FJvw2diN","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Big City Life","artist":"Luude, Mattafix","album":"Big City Life","albumart":"https://i.scdn.co/image/ab67616d00001e022a2c9dd7c941a90d3e75d9d7","uri":"spotify:track:1LIvdWNdKmHvU0FJvw2diN","trackType":"spotify","seek":44,"duration":146,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:1LIvdWNdKmHvU0FJvw2diN","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:0I3q5fE6wg7LIfHGngUTnV","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0I3q5fE6wg7LIfHGngUTnV","name":"Ms. Jackson","artist_names":["Outkast"],"album_name":"Stankonia","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02153b3d6495031cb813a7451c","position":175,"duration":270506,"release_date":"year:2000 month:10 day:31","track_number":5,"disc_number":1}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:0I3q5fE6wg7LIfHGngUTnV","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Ms. Jackson","artist":"Outkast","album":"Stankonia","albumart":"https://i.scdn.co/image/ab67616d00001e02153b3d6495031cb813a7451c","uri":"spotify:track:0I3q5fE6wg7LIfHGngUTnV","trackType":"spotify","seek":175,"duration":270,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:0I3q5fE6wg7LIfHGngUTnV","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5xYC48nOppVemY6U5GRGTb","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5xYC48nOppVemY6U5GRGTb","name":"Memories (feat. Kid Cudi)","artist_names":["David Guetta","Kid Cudi"],"album_name":"One More Love","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f45c50e7cff5f2376c1e36ea","position":18658,"duration":210853,"release_date":"year:2010 month:11 day:22","track_number":4,"disc_number":1}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5xYC48nOppVemY6U5GRGTb","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Memories (feat. Kid Cudi)","artist":"David Guetta, Kid Cudi","album":"One More Love","albumart":"https://i.scdn.co/image/ab67616d00001e02f45c50e7cff5f2376c1e36ea","uri":"spotify:track:5xYC48nOppVemY6U5GRGTb","trackType":"spotify","seek":18658,"duration":210,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5xYC48nOppVemY6U5GRGTb","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:31STZ8Uq8flfkwwyRGfHOA","play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:31STZ8Uq8flfkwwyRGfHOA","name":"Poison","artist_names":["Lil Peep","BEXEY"],"album_name":"Romeo's Regrets","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02577ee37988ceb5e26348756a","position":72,"duration":298600,"release_date":"year:2025 month:11 day:14","track_number":2,"disc_number":1}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:31STZ8Uq8flfkwwyRGfHOA","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:45 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Poison","artist":"Lil Peep, BEXEY","album":"Romeo's Regrets","albumart":"https://i.scdn.co/image/ab67616d00001e02577ee37988ceb5e26348756a","uri":"spotify:track:31STZ8Uq8flfkwwyRGfHOA","trackType":"spotify","seek":72,"duration":298,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:45 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:45 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:31STZ8Uq8flfkwwyRGfHOA","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:1NhPKVLsHhFUHIOZ32QnS2","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1NhPKVLsHhFUHIOZ32QnS2","name":"Secrets","artist_names":["OneRepublic"],"album_name":"Waking Up","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0277a7bd558d81172446e525bb","position":83,"duration":224693,"release_date":"year:2009 month:1 day:1","track_number":3,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:1NhPKVLsHhFUHIOZ32QnS2","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Secrets","artist":"OneRepublic","album":"Waking Up","albumart":"https://i.scdn.co/image/ab67616d00001e0277a7bd558d81172446e525bb","uri":"spotify:track:1NhPKVLsHhFUHIOZ32QnS2","trackType":"spotify","seek":83,"duration":224,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:1NhPKVLsHhFUHIOZ32QnS2","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:6KGggJ08SRmMuJojRYM4Lv","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6KGggJ08SRmMuJojRYM4Lv","name":"Chase The Devil","artist_names":["Max Romeo","The Upsetters"],"album_name":"War Ina Babylon (Expanded Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0200a00a66166f127f716c52d1","position":174,"duration":206306,"release_date":"year:1976 month:1 day:1","track_number":3,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:6KGggJ08SRmMuJojRYM4Lv","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Chase The Devil","artist":"Max Romeo, The Upsetters","album":"War Ina Babylon (Expanded Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e0200a00a66166f127f716c52d1","uri":"spotify:track:6KGggJ08SRmMuJojRYM4Lv","trackType":"spotify","seek":174,"duration":206,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:6KGggJ08SRmMuJojRYM4Lv","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7w5AOd6HrDIHewHfpABEss","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7w5AOd6HrDIHewHfpABEss","name":"Wicked Game","artist_names":["Chris Isaak"],"album_name":"Heart Shaped World","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b66c23534dd78ff7d3da83b8","position":71,"duration":289066,"release_date":"year:1989 month:6 day:13","track_number":5,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7w5AOd6HrDIHewHfpABEss","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Wicked Game","artist":"Chris Isaak","album":"Heart Shaped World","albumart":"https://i.scdn.co/image/ab67616d00001e02b66c23534dd78ff7d3da83b8","uri":"spotify:track:7w5AOd6HrDIHewHfpABEss","trackType":"spotify","seek":71,"duration":289,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7w5AOd6HrDIHewHfpABEss","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5WQQIDU3HRaMyPkob8mpFb","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5WQQIDU3HRaMyPkob8mpFb","name":"Where Have You Been","artist_names":["Rihanna"],"album_name":"Talk That Talk","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bef074de9ca825bddaeb9f46","position":42,"duration":242680,"release_date":"year:2011 month:11 day:18","track_number":2,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5WQQIDU3HRaMyPkob8mpFb","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Where Have You Been","artist":"Rihanna","album":"Talk That Talk","albumart":"https://i.scdn.co/image/ab67616d00001e02bef074de9ca825bddaeb9f46","uri":"spotify:track:5WQQIDU3HRaMyPkob8mpFb","trackType":"spotify","seek":42,"duration":242,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:5WQQIDU3HRaMyPkob8mpFb","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7bxon8K9DP6stYx5ZO9WlK","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7bxon8K9DP6stYx5ZO9WlK","name":"Seven Nation Army","artist_names":["The White Stripes"],"album_name":"The White Stripes Greatest Hits","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028ca14aa8a50549ed686cd87b","position":48,"duration":231746,"release_date":"year:2020 month:12 day:4","track_number":26,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7bxon8K9DP6stYx5ZO9WlK","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Seven Nation Army","artist":"The White Stripes","album":"The White Stripes Greatest Hits","albumart":"https://i.scdn.co/image/ab67616d00001e028ca14aa8a50549ed686cd87b","uri":"spotify:track:7bxon8K9DP6stYx5ZO9WlK","trackType":"spotify","seek":48,"duration":231,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7bxon8K9DP6stYx5ZO9WlK","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:09DFJg2KCnbBXkYItMubU5","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:09DFJg2KCnbBXkYItMubU5","name":"teen romance","artist_names":["Lil Peep","Lederrick"],"album_name":"teen romance","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0271b8b869ec7d7aa10705c641","position":43,"duration":170133,"release_date":"year:2024 month:6 day:19","track_number":2,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:09DFJg2KCnbBXkYItMubU5","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"teen romance","artist":"Lil Peep, Lederrick","album":"teen romance","albumart":"https://i.scdn.co/image/ab67616d00001e0271b8b869ec7d7aa10705c641","uri":"spotify:track:09DFJg2KCnbBXkYItMubU5","trackType":"spotify","seek":43,"duration":170,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:09DFJg2KCnbBXkYItMubU5","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7HWaB2AEfyhAUEPkW9G4ni","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7HWaB2AEfyhAUEPkW9G4ni","name":"Gravity Falls","artist_names":["L.Dre"],"album_name":"Gravity Falls","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0229ad8d09b6f69f5ffc2a2d40","position":65,"duration":114000,"release_date":"year:2019 month:12 day:20","track_number":1,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7HWaB2AEfyhAUEPkW9G4ni","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Gravity Falls","artist":"L.Dre","album":"Gravity Falls","albumart":"https://i.scdn.co/image/ab67616d00001e0229ad8d09b6f69f5ffc2a2d40","uri":"spotify:track:7HWaB2AEfyhAUEPkW9G4ni","trackType":"spotify","seek":65,"duration":114,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:7HWaB2AEfyhAUEPkW9G4ni","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:25Ydf3tBonBtvs3NhwYop7","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7q0NtdbzOqnsEznSgkuAnQ","name":"Claw Foot","artist_names":["Royel Otis"],"album_name":"PRATTS \u0026 PAIN","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02df9fb03895705cda5f1f16b9","position":148,"duration":166973,"release_date":"year:2024 month:5 day:2","track_number":14,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:25Ydf3tBonBtvs3NhwYop7","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"Claw Foot","artist":"Royel Otis","album":"PRATTS & PAIN","albumart":"https://i.scdn.co/image/ab67616d00001e02df9fb03895705cda5f1f16b9","uri":"spotify:track:7q0NtdbzOqnsEznSgkuAnQ","trackType":"spotify","seek":148,"duration":166,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:25Ydf3tBonBtvs3NhwYop7","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: error: Failed to decode event: not_playing Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:3y1EzRl5UEQxxd1f6Bv2aN","play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3y1EzRl5UEQxxd1f6Bv2aN","name":"When I'm Small","artist_names":["Phantogram"],"album_name":"Eyelid Movies","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0215f856c044360b26ac41f109","position":86,"duration":249066,"release_date":"year:2010","track_number":2,"disc_number":1}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:21fn2kil6u6seyxvuffxaxtuy:collection","uri":"spotify:track:3y1EzRl5UEQxxd1f6Bv2aN","resume":false,"play_origin":"collection/songs"}} Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: PUSH STATE SPOTIFY Apr 03 06:05:46 streamer volumio[1235]: SPOTIFY: {"status":"play","service":"spop","title":"When I'm Small","artist":"Phantogram","album":"Eyelid Movies","albumart":"https://i.scdn.co/image/ab67616d00001e0215f856c044360b26ac41f109","uri":"spotify:track:3y1EzRl5UEQxxd1f6Bv2aN","trackType":"spotify","seek":86,"duration":249,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::servicePushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreStateMachine::pushState Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioPushState Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output update for this device Apr 03 06:05:46 streamer volumio[1235]: info: MRS: Pushing multiroomSync output Apr 03 06:05:46 streamer volumio[1235]: info: CoreCommandRouter::volumioGetState Apr 03 06:05:51 streamer systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 03 06:05:51 streamer systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 03 06:05:51 streamer systemd[1]: setdatetime-helper.service: Consumed 2min 48.404s CPU time. Apr 03 06:05:51 streamer systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 03 06:05:52 streamer volumio-remote-updater[682]: [2026-04-03 06:05:52] [connect] Successful connection Apr 03 06:05:53 streamer systemd[1]: apt-daily.service: Deactivated successfully. Apr 03 06:05:53 streamer systemd[1]: Finished apt-daily.service - Daily apt download activities. Apr 03 06:05:53 streamer systemd[1]: apt-daily.service: Consumed 3min 51.534s CPU time. Apr 03 06:05:57 streamer volumio5-onboarding[2035]: time=2026-04-03T06:05:57.278+01:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:54994->127.0.0.1:3000: i/o timeout" Apr 03 06:05:59 streamer nmbd[1018]: [2026/04/03 06:05:59.178331, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 03 06:05:59 streamer nmbd[1018]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.159 for name WORKGROUP<1d>. Apr 03 06:05:59 streamer nmbd[1018]: This response was from IP 192.168.1.245, reporting an IP address of 192.168.1.245. Apr 03 06:06:03 streamer go-librespot[3187]: time="2026-04-03T06:06:03+01:00" level=trace msg="sent dealer ping" Apr 03 06:06:03 streamer go-librespot[3187]: time="2026-04-03T06:06:03+01:00" level=trace msg="received dealer pong" Apr 03 06:06:07 streamer volumio-remote-updater[682]: [2026-04-03 06:06:07] [connect] Successful connection Apr 03 06:06:10 streamer volumio5-onboarding[2035]: time=2026-04-03T06:06:10.606+01:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:57152->127.0.0.1:3000: i/o timeout" Apr 03 06:06:14 streamer systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 03 06:06:14 streamer systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 03 06:06:14 streamer systemd[1]: setdatetime-helper.service: Consumed 6.062s CPU time. Apr 03 06:06:15 streamer volumio[1235]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 06:06:16 streamer volumio[1235]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Apr 03 06:06:16 streamer volumio[1235]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Apr 03 06:06:16 streamer volumio[1235]: at new NodeError (node:internal/errors:405:5) Apr 03 06:06:16 streamer volumio[1235]: at assert (node:internal/assert:14:11) Apr 03 06:06:16 streamer volumio[1235]: at internalConnectMultiple (node:net:1118:3) Apr 03 06:06:16 streamer volumio[1235]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Apr 03 06:06:16 streamer volumio[1235]: at listOnTimeout (node:internal/timers:575:11) Apr 03 06:06:16 streamer volumio[1235]: at process.processTimers (node:internal/timers:514:7) { Apr 03 06:06:16 streamer volumio[1235]: code: 'ERR_INTERNAL_ASSERTION' Apr 03 06:06:16 streamer volumio[1235]: } Apr 03 06:06:16 streamer volumio[1235]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 06:06:20 streamer go-librespot[3187]: time="2026-04-03T06:06:20+01:00" level=trace msg="emitting websocket event: not_playing" Apr 03 06:06:20 streamer go-librespot[3187]: time="2026-04-03T06:06:20+01:00" level=debug msg="loading track (paused: false, position: 111ms)" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:21 streamer go-librespot[3187]: time="2026-04-03T06:06:21+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 03 06:06:21 streamer go-librespot[3187]: time="2026-04-03T06:06:21+01:00" level=trace msg="emitting websocket event: will_play" Apr 03 06:06:21 streamer go-librespot[3187]: time="2026-04-03T06:06:21+01:00" level=debug msg="selected format OGG_VORBIS_320 (17fb9c73b61cbafe510ecbfed2c1c6a09b2b9969)" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:21 streamer go-librespot[3187]: time="2026-04-03T06:06:21+01:00" level=debug msg="requested aes key for file 17fb9c73b61cbafe510ecbfed2c1c6a09b2b9969, gid: 6YK0bf1uQjoZ4ghsQyhS83" Apr 03 06:06:21 streamer go-librespot[3187]: time="2026-04-03T06:06:21+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=debug msg="fetched first chunk of 6, total size is 2946437 bytes" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=trace msg="seek to 111ms (diff: 111ms, samples: 4895, bytes: 0)" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=info msg="loaded track \"Leave It All To Me (iCarly chill)\" (paused: false, position: 111ms, duration: 76153ms, prefetched: false)" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=debug msg="fetched chunk 1/5, size: 524288" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=trace msg="scheduling prefetch in 47s" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=debug msg="fetched chunk 2/5, size: 524288" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=debug msg="fetched chunk 3/5, size: 524288" uri="spotify:track:4Vy9CFFGLFlbL9Pa12gLVQ" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=trace msg="emitting websocket event: metadata" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 03 06:06:22 streamer go-librespot[3187]: time="2026-04-03T06:06:22+01:00" level=trace msg="emitting websocket event: playing" Apr 03 06:06:22 streamer volumio-remote-updater[682]: [2026-04-03 06:06:22] [connect] Successful connection Apr 03 06:06:23 streamer volumio5-onboarding[2035]: time=2026-04-03T06:06:23.261+01:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:50880->127.0.0.1:3000: i/o timeout" Apr 03 06:06:26 streamer sudo[20552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-03 06:05' Apr 03 06:06:26 streamer sudo[20552]: 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"