-- Logs begin at Wed 2025-02-26 19:47:58 CET, end at Thu 2025-02-27 12:40:51 CET. -- Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="handling play player command from c98767f2cd710266c0afd19f66bc5e3db454419c" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="resolved context of track" uri="spotify:album:18oofL5eKAr7LXtux0pVoI" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:album:18oofL5eKAr7LXtux0pVoI" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=trace msg="emitting websocket event: will_play" Feb 27 12:39:06 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","play_origin":"home"}} Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="selected format OGG_VORBIS_320 (765a499de134dacc9e7d5b5ba5d99a003f0642de)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="requested aes key for file 765a499de134dacc9e7d5b5ba5d99a003f0642de, gid: 1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="fetched first chunk of 15, total size is 7458483 bytes" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=info msg="loaded track \"Save Me\" (paused: false, position: 0ms, duration: 173868ms, prefetched: false)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=trace msg="scheduling prefetch in 144s" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=trace msg="emitting websocket event: metadata" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="sending successful reply for dealer request" Feb 27 12:39:06 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","name":"Save Me","artist_names":["fuseo","Jon Klaasen"],"album_name":"Save Me","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","position":0,"duration":173868,"release_date":"year:2025 month:2 day:21","track_number":1,"disc_number":1}} Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:39:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:06+01:00" level=trace msg="emitting websocket event: playing" Feb 27 12:39:06 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","play_origin":"home"}} Feb 27 12:39:06 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:39:06 volumio-mllse volumio[1364]: SPOTIFY: {"status":"play","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":0,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:39:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:39:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Feb 27 12:39:07 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:39:07 volumio-mllse volumio[1364]: SPOTIFY: {"status":"play","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":0,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:39:07 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:39:07 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:07 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:07 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:07 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:07 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Feb 27 12:39:09 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:09+01:00" level=debug msg="handling pause player command from c98767f2cd710266c0afd19f66bc5e3db454419c" Feb 27 12:39:09 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:09+01:00" level=debug msg="pause track at 2948ms" Feb 27 12:39:09 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:39:09 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:09+01:00" level=debug msg="sending successful reply for dealer request" Feb 27 12:39:09 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:39:09 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:09+01:00" level=trace msg="emitting websocket event: paused" Feb 27 12:39:09 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","play_origin":"home"}} Feb 27 12:39:09 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:39:09 volumio-mllse volumio[1364]: SPOTIFY: {"status":"pause","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":3000,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:39:09 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:39:09 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:09 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:09 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:09 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:09 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:09 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Feb 27 12:39:12 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:12+01:00" level=debug msg="update volume to 30145/65535" Feb 27 12:39:12 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:12+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:12 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:12+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:12 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":46,"max":100}} Feb 27 12:39:12 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 46 Feb 27 12:39:12 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 46 Feb 27 12:39:12 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume46 Feb 27 12:39:12 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:12 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:12 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:12 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:12 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:12 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Feb 27 12:39:12 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:12 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:13 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:13+01:00" level=debug msg="update volume to 30801/65535" Feb 27 12:39:13 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:13+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:13 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:13+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:13 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":47,"max":100}} Feb 27 12:39:13 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 47 Feb 27 12:39:13 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 47 Feb 27 12:39:13 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume47 Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:13 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:13 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:13 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Feb 27 12:39:13 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:13 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:13 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:13+01:00" level=debug msg="update volume to 31456/65535" Feb 27 12:39:13 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:13+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:13 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:13+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:13 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":48,"max":100}} Feb 27 12:39:13 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 48 Feb 27 12:39:13 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 48 Feb 27 12:39:13 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume48 Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:13 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:13 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:13 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Feb 27 12:39:13 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:13 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 12:39:13 volumio-mllse volumio[1364]: info: Discovery: Getting this device information Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 27 12:39:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 12:39:14 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:14+01:00" level=debug msg="update volume to 34077/65535" Feb 27 12:39:14 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:14+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:14 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:14+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:14 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":52,"max":100}} Feb 27 12:39:14 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 52 Feb 27 12:39:14 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 52 Feb 27 12:39:14 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume52 Feb 27 12:39:14 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:14 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:14 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:14 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:14 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:14 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Feb 27 12:39:14 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:14 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:14 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:14+01:00" level=debug msg="update volume to 38665/65535" Feb 27 12:39:14 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:14+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:14 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:14+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:14 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":59,"max":100}} Feb 27 12:39:14 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 59 Feb 27 12:39:14 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 59 Feb 27 12:39:14 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume59 Feb 27 12:39:14 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:14 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:14 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:14 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:14 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:14 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Feb 27 12:39:14 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:14 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:14 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:14+01:00" level=debug msg="update volume to 39320/65535" Feb 27 12:39:15 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:15 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:15+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:15 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":60,"max":100}} Feb 27 12:39:15 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 60 Feb 27 12:39:15 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 60 Feb 27 12:39:15 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume60 Feb 27 12:39:15 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:15 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:15 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:15 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:15 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:15 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Feb 27 12:39:15 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:15 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:15 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:15+01:00" level=debug msg="update volume to 38665/65535" Feb 27 12:39:15 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:15 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:15+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:15 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":59,"max":100}} Feb 27 12:39:15 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 59 Feb 27 12:39:15 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 59 Feb 27 12:39:15 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume59 Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:16 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:16 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:16 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Feb 27 12:39:16 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:16 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:16 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:16+01:00" level=debug msg="update volume to 38010/65535" Feb 27 12:39:16 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:16+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:16 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:16+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:16 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":58,"max":100}} Feb 27 12:39:16 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 58 Feb 27 12:39:16 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 58 Feb 27 12:39:16 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume58 Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:16 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:16 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:16 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Feb 27 12:39:16 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:16 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:16 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:16+01:00" level=debug msg="update volume to 36044/65535" Feb 27 12:39:16 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:16+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:16 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:16+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:16 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":55,"max":100}} Feb 27 12:39:16 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 55 Feb 27 12:39:16 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 55 Feb 27 12:39:16 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume55 Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:16 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:16 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:16 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:16 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 27 12:39:16 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:16 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:21 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:21+01:00" level=debug msg="update volume to 36699/65535" Feb 27 12:39:21 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:21+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:21 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:21+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:21 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":56,"max":100}} Feb 27 12:39:21 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 56 Feb 27 12:39:21 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 56 Feb 27 12:39:21 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume56 Feb 27 12:39:21 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:21 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:21 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:21 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:21 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:21 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Feb 27 12:39:21 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:21 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:21 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:21+01:00" level=debug msg="update volume to 37354/65535" Feb 27 12:39:21 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:21+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:21 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:21+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:21 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":57,"max":100}} Feb 27 12:39:21 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 57 Feb 27 12:39:21 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 57 Feb 27 12:39:21 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume57 Feb 27 12:39:21 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:21 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:21 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:21 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:21 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:21 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Feb 27 12:39:22 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:22 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:22 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:22+01:00" level=debug msg="update volume to 41942/65535" Feb 27 12:39:22 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:22+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:22 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:22+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:22 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":64,"max":100}} Feb 27 12:39:22 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 64 Feb 27 12:39:22 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 64 Feb 27 12:39:22 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume64 Feb 27 12:39:22 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:22 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:22 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:22 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:22 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:22 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Feb 27 12:39:22 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:22 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:22 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:22+01:00" level=debug msg="update volume to 42597/65535" Feb 27 12:39:22 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:22+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:39:22 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:22+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:39:22 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":65,"max":100}} Feb 27 12:39:22 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 65 Feb 27 12:39:22 volumio-mllse volumio[1364]: info: Setting Volumio Volume from Spotify: 65 Feb 27 12:39:22 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume65 Feb 27 12:39:22 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:22 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:22 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:22 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:22 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:22 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 27 12:39:22 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:39:22 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:39:24 volumio-mllse volumiologrotate[840]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 27 12:39:24 volumio-mllse volumiologrotate[840]: ls: cannot access 'MLLSE': No such file or directory Feb 27 12:39:37 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 27 12:39:37 volumio-mllse volumio[1364]: info: In handleBrowseUri, curUri=spotify Feb 27 12:39:37 volumio-mllse volumio[1364]: info: New access token = BQBS8SA4MBHI6DRIyxc7yl1iIL1t_jqxjy6WDBCDKu6lyRx7mIx8nOoL-zUbvrTYhtNTtnXr4lQLYzlNUerpQNakOa6mBOCG38zpbIaH-W_9vowKOHlx-SBBQlz6yceVL70j2QpYxKXWQ7ekQ9xTlgbuvjPag6RlLn6E9SSSC4iQM7-iu3HqAZimQFrEiWDRFVnh0OShYm2XYcFdger9rqjULCAlg04f1JZA3qMmpLcgczGmhWPPPqOvjF0i-ylUVYR6YAHcUe4uXOL04GM Feb 27 12:39:37 volumio-mllse volumio[1364]: info: New access token = BQCICbqADjCBGpi1yF0zVVQKixxGrHTLzi_QpGwSOB3N6iT0ZSlXWKj23jKuXf1B2xpUSpdITfVCFlUpkovuqHz6FSAUIt_pVjo_CuFSI9qPZViFXkiMX0hNbAsBHYEgGh-31Nx7z3zsZyhNUHzDJAPnoAPesJP2uPF3KJDgrxLgh5Rr_swNNmy02KsGJGDPPOW0AsXTc6P3KD4fV9ZUdGW7_MBtoPGP2lOsN6ALfqGMitt1347oGCO0X5_Bbcse6uWUymtwDeppY0Dokm4 Feb 27 12:39:38 volumio-mllse volumio[1364]: info: Tunnel connection is inactive, restarting it Feb 27 12:39:38 volumio-mllse volumio[1364]: info: Starting Tunnel 1 Feb 27 12:39:38 volumio-mllse volumio[1364]: info: Starting Tunnel Connection Checker Feb 27 12:39:38 volumio-mllse sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 27 12:39:38 volumio-mllse sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 12:39:38 volumio-mllse systemd[1]: Stopping MyVolumio SSH Tunnel... Feb 27 12:39:38 volumio-mllse autossh[1441]: received signal to exit (15) Feb 27 12:39:38 volumio-mllse systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Feb 27 12:39:38 volumio-mllse systemd[1]: sshtunnel.service: Succeeded. Feb 27 12:39:38 volumio-mllse volumio[1364]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 12:39:38 volumio-mllse systemd[1]: Stopped MyVolumio SSH Tunnel. Feb 27 12:39:38 volumio-mllse systemd[1]: Started MyVolumio SSH Tunnel. Feb 27 12:39:38 volumio-mllse sudo[2032]: pam_unix(sudo:session): session closed for user root Feb 27 12:39:38 volumio-mllse autossh[2035]: port set to 0, monitoring disabled Feb 27 12:39:38 volumio-mllse autossh[2035]: starting ssh (count 1) Feb 27 12:39:38 volumio-mllse autossh[2035]: ssh child pid is 2038 Feb 27 12:39:38 volumio-mllse volumio[1364]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 12:39:38 volumio-mllse volumio[1364]: info: Remote SSH Started Feb 27 12:39:38 volumio-mllse volumio[1364]: info: New access token = BQBpUlpfWrBJK8VpDh5LP-rXJqZ3z3AVPh3BIxzbjXM-HdtN8qcIhIfwMgxE7TCMcgetM1_sPE7MyAe9lVwVumkW-OFKsA980rSU8tEl9ORKmYHabQ2OGgw__74K-AeogdKGTEJnMMjdWk_vjZ63hHbNPmPDpQuAbR0hj4kZwalbPTz_S3JyqK7v1AxmBmUid6Kz7SLlt__vpkvjpKE-fgsyKWlV2x9hruQDb9F0rQyUKEZBYMKmko1CEenkd-tR-bP63oQU9cG9XhtNkc8 Feb 27 12:39:38 volumio-mllse volumio[1364]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 27 12:39:38 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 27 12:39:39 volumio-mllse volumio[1364]: info: Preload queue cleared Feb 27 12:39:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 12:39:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 12:39:43 volumio-mllse volumio[1364]: info: Discovery: Getting this device information Feb 27 12:39:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 27 12:39:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 12:39:45 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 27 12:39:45 volumio-mllse volumio[1364]: info: In handleBrowseUri, curUri=spotify/playlists Feb 27 12:39:46 volumio-mllse volumio[1364]: info: Preload queue cleared Feb 27 12:39:46 volumio-mllse volumiossh-tunnel[2034]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Feb 27 12:39:52 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 27 12:39:52 volumio-mllse volumio[1364]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:37i9dQZF1DWSlJG7YPUBHF Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preload queue cleared Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:4zoQ3EqopTIGmK2c2rPV5t Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2NrqjA3YUfNuZcmQzE2EJe Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2tAeN2TKlQLOoSPXtARzBV Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5Yp3e8RcjYevBTZYJ7OD7s Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:6pnwfWyaWjQiHCKTiZLItr Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:4u7EnebtmKWzUH433cf5Qv Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:3UsbGR71AjcBYOWoUlfTdm Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2o9VAWKD7yvnyrBRw9tzgH Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:3rUHyuFsFizq8gIQO0z2jH Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5qcE8RBMFpIvKBIr9YEkz8 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:0uco0wQkB909zpPlHvu5Cc Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:1uvyZBs4IZYRebHIB1747m Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5HNCy40Ni5BZJFw1TKzRsC Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5CQ30WqJwcep0pYcV4AMNc Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:3YRCqOhFifThpSRFJ1VWFM Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:37Tmv4NnfQeb0ZgUC4fOJj Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2K2M0TcglCRLLpFOzKeFZA Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:0w7lKfhxW190u1ckXi45E9 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:40riOy7x9W7GXjyGp4pjAv Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:6WE7jSshLCuVKoCmobVKVf Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:75zMKn5euxQdlkZgu4P42J Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:6QDbGdbJ57Mtkflsg42WV5 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5EWPGh7jbTNO2wakv8LjUI Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:7snQQk1zcKl8gZ92AnueZW Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:3Jnxngdff0lVu2rza1GVx6 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:7ACxUo21jtTHzy7ZEV56vU Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:0870QNicMawQH2cnzBVZ3P Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:6gX9x3F1ohlivkkKSZ8MAJ Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:4CyElmiM9EF9aURGWlPZLi Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:7Gq4o3qwY5kTdwNwyQ4lLu Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5UuikgHTxSRFRnC0zXx10i Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:0DnGfA1r8pAssJCuq4ojla Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2ekfM9Dcslm7l9Lh7HGT0i Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2tVQhBSJ1SQtb4gX0taaiR Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5m5I3KcV83zf2p2qbsnr3y Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:0Q2dRzhPWFOex89dOjbq5Y Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:6PUabSMXmPnZna361Wwmf7 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2xS1lC45sjj60M1eeRsOUp Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5XNpdKmlLJPUbwKQceX2tW Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:7GqIDx2QVGOpd4r1fZaUUW Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:389QX9Q1eUOEZ19vtzzI9O Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2bBAAVvOHsr7vxWFTuYxH8 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:1x1XQqhBViz4opcpwc7FVs Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:5qm0KiVKMXW1kq6VrnIhz5 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:7itr4N5bXkAWesPUomCNf7 Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:2PV9sorI2h94qEPke7SlGI Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Preloading song: spotify:track:1L94M3KIu7QluZe63g64rv Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:4zoQ3EqopTIGmK2c2rPV5t in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:4zoQ3EqopTIGmK2c2rPV5t Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2NrqjA3YUfNuZcmQzE2EJe in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2NrqjA3YUfNuZcmQzE2EJe Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2tAeN2TKlQLOoSPXtARzBV in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2tAeN2TKlQLOoSPXtARzBV Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4zoQ3EqopTIGmK2c2rPV5t","service":"spop","name":"Cause We've Ended as Lovers","artist":"Jeff Beck","album":"Blow By Blow","type":"song","duration":342,"albumart":"https://i.scdn.co/image/ab67616d0000b2733ed60b59aaa75ed572d7fc30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5Yp3e8RcjYevBTZYJ7OD7s in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5Yp3e8RcjYevBTZYJ7OD7s Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:6pnwfWyaWjQiHCKTiZLItr in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:6pnwfWyaWjQiHCKTiZLItr Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:4u7EnebtmKWzUH433cf5Qv in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:4u7EnebtmKWzUH433cf5Qv Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2NrqjA3YUfNuZcmQzE2EJe","service":"spop","name":"Flying in a Blue Dream","artist":"Joe Satriani","album":"Flying In A Blue Dream","type":"song","duration":323,"albumart":"https://i.scdn.co/image/ab67616d0000b27378730c4cc1b0008db37c9948","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:3UsbGR71AjcBYOWoUlfTdm in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:3UsbGR71AjcBYOWoUlfTdm Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2tAeN2TKlQLOoSPXtARzBV","service":"spop","name":"Eruption - 2015 Remaster","artist":"Van Halen","album":"Van Halen (Remastered)","type":"song","duration":102,"albumart":"https://i.scdn.co/image/ab67616d0000b27317dd812df38fed44d6d2036e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2o9VAWKD7yvnyrBRw9tzgH in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2o9VAWKD7yvnyrBRw9tzgH Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:3rUHyuFsFizq8gIQO0z2jH in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:3rUHyuFsFizq8gIQO0z2jH Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5qcE8RBMFpIvKBIr9YEkz8 in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5qcE8RBMFpIvKBIr9YEkz8 Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Yp3e8RcjYevBTZYJ7OD7s","service":"spop","name":"Europa (Earth's Cry Heaven's Smile)","artist":"Santana","album":"Ultimate Santana","type":"song","duration":305,"albumart":"https://i.scdn.co/image/ab67616d0000b2735ffffefb4343759b63f72598","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6pnwfWyaWjQiHCKTiZLItr","service":"spop","name":"Shine On You Crazy Diamond (Pts. 1-5)","artist":"Pink Floyd","album":"Wish You Were Here","type":"song","duration":811,"albumart":"https://i.scdn.co/image/ab67616d0000b2731a84d71391df7469c5ab8539","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:0uco0wQkB909zpPlHvu5Cc in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:0uco0wQkB909zpPlHvu5Cc Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:1uvyZBs4IZYRebHIB1747m in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:1uvyZBs4IZYRebHIB1747m Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4u7EnebtmKWzUH433cf5Qv","service":"spop","name":"Bohemian Rhapsody - Remastered 2011","artist":"Queen","album":"A Night At The Opera (2011 Remaster)","type":"song","duration":354,"albumart":"https://i.scdn.co/image/ab67616d0000b273e319baafd16e84f0408af2a0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3UsbGR71AjcBYOWoUlfTdm","service":"spop","name":"Statesboro Blues","artist":"Allman Brothers Band","album":"The 1971 Fillmore East Recordings","type":"song","duration":284,"albumart":"https://i.scdn.co/image/ab67616d0000b2735d20a64aabf010238688f3d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5HNCy40Ni5BZJFw1TKzRsC in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5HNCy40Ni5BZJFw1TKzRsC Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2o9VAWKD7yvnyrBRw9tzgH","service":"spop","name":"The Width of a Circle - 2015 Remaster","artist":"David Bowie","album":"The Man Who Sold the World (2015 Remaster)","type":"song","duration":492,"albumart":"https://i.scdn.co/image/ab67616d0000b27346926e777bd6665f03128d29","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3rUHyuFsFizq8gIQO0z2jH","service":"spop","name":"Spanish Fly - 2015 Remaster","artist":"Van Halen","album":"Van Halen II (Remastered)","type":"song","duration":61,"albumart":"https://i.scdn.co/image/ab67616d0000b273f09b618bc81755c0d817bd20","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5CQ30WqJwcep0pYcV4AMNc in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5CQ30WqJwcep0pYcV4AMNc Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5qcE8RBMFpIvKBIr9YEkz8","service":"spop","name":"Shock Me","artist":"KISS","album":"Love Gun (Remastered Version)","type":"song","duration":226,"albumart":"https://i.scdn.co/image/ab67616d0000b273e793cf5e09a590f652508743","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:3YRCqOhFifThpSRFJ1VWFM in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:3YRCqOhFifThpSRFJ1VWFM Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0uco0wQkB909zpPlHvu5Cc","service":"spop","name":"Bold as Love","artist":"Jimi Hendrix","album":"Axis: Bold As Love","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b27319dcd95d28b63d10164327f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1uvyZBs4IZYRebHIB1747m","service":"spop","name":"Purple Rain","artist":"Prince","album":"Purple Rain","type":"song","duration":521,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2ce3f148f57584269c3782","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5HNCy40Ni5BZJFw1TKzRsC","service":"spop","name":"Comfortably Numb","artist":"Pink Floyd","album":"The Wall","type":"song","duration":382,"albumart":"https://i.scdn.co/image/ab67616d0000b2735d48e2f56d691f9a4e4b0bdf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:37Tmv4NnfQeb0ZgUC4fOJj in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:37Tmv4NnfQeb0ZgUC4fOJj Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5CQ30WqJwcep0pYcV4AMNc","service":"spop","name":"Stairway to Heaven - Remaster","artist":"Led Zeppelin","album":"Led Zeppelin IV (Deluxe Edition)","type":"song","duration":482,"albumart":"https://i.scdn.co/image/ab67616d0000b273c8a11e48c91a982d086afc69","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2K2M0TcglCRLLpFOzKeFZA in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2K2M0TcglCRLLpFOzKeFZA Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:0w7lKfhxW190u1ckXi45E9 in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:0w7lKfhxW190u1ckXi45E9 Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3YRCqOhFifThpSRFJ1VWFM","service":"spop","name":"November Rain","artist":"Guns N' Roses","album":"Use Your Illusion I","type":"song","duration":536,"albumart":"https://i.scdn.co/image/ab67616d0000b273e44963b8bb127552ac761873","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:37Tmv4NnfQeb0ZgUC4fOJj","service":"spop","name":"Sultans Of Swing","artist":"Dire Straits","album":"Dire Straits","type":"song","duration":348,"albumart":"https://i.scdn.co/image/ab67616d0000b273b49d49cc95564aede7998bb8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:53 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:40riOy7x9W7GXjyGp4pjAv in service spop Feb 27 12:39:53 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:40riOy7x9W7GXjyGp4pjAv Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2K2M0TcglCRLLpFOzKeFZA","service":"spop","name":"Sunshine Of Your Love","artist":"Cream","album":"Disraeli Gears (Deluxe Edition)","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b273db1fb2dede292908e86d94d7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2aoo2jlRnM3A0NyLQqMN2f in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2aoo2jlRnM3A0NyLQqMN2f Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0w7lKfhxW190u1ckXi45E9","service":"spop","name":"Brighton Rock - Remastered 2011","artist":"Queen","album":"Sheer Heart Attack (Deluxe Edition 2011 Remaster)","type":"song","duration":310,"albumart":"https://i.scdn.co/image/ab67616d0000b27309e2ec500166cf2d5ac21050","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:6WE7jSshLCuVKoCmobVKVf in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:6WE7jSshLCuVKoCmobVKVf Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:75zMKn5euxQdlkZgu4P42J in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:75zMKn5euxQdlkZgu4P42J Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:40riOy7x9W7GXjyGp4pjAv","service":"spop","name":"Hotel California - 2013 Remaster","artist":"Eagles","album":"Hotel California (2013 Remaster)","type":"song","duration":391,"albumart":"https://i.scdn.co/image/ab67616d0000b2734637341b9f507521afa9a778","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:6QDbGdbJ57Mtkflsg42WV5 in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:6QDbGdbJ57Mtkflsg42WV5 Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2aoo2jlRnM3A0NyLQqMN2f","service":"spop","name":"All Along the Watchtower","artist":"Jimi Hendrix","album":"Electric Ladyland","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273522088789d49e216d9818292","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6WE7jSshLCuVKoCmobVKVf","service":"spop","name":"Heartbreaker - 1990 Remaster","artist":"Led Zeppelin","album":"Led Zeppelin II (1994 Remaster)","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc4f17340773c6c3579fea0d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5EWPGh7jbTNO2wakv8LjUI in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5EWPGh7jbTNO2wakv8LjUI Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:7snQQk1zcKl8gZ92AnueZW in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:7snQQk1zcKl8gZ92AnueZW Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:75zMKn5euxQdlkZgu4P42J","service":"spop","name":"Sympathy For The Devil","artist":"The Rolling Stones","album":"The Rolling Stones Singles Collection: The London Years","type":"song","duration":376,"albumart":"https://i.scdn.co/image/ab67616d0000b273329024dd3a0b300a24586db2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:3Jnxngdff0lVu2rza1GVx6 in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:3Jnxngdff0lVu2rza1GVx6 Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6QDbGdbJ57Mtkflsg42WV5","service":"spop","name":"Hot for Teacher - 2015 Remaster","artist":"Van Halen","album":"1984 (Remastered)","type":"song","duration":282,"albumart":"https://i.scdn.co/image/ab67616d0000b273b414c63fb435b622238c15ed","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5EWPGh7jbTNO2wakv8LjUI","service":"spop","name":"Free Bird","artist":"Lynyrd Skynyrd","album":"Pronounced' Leh-'Nerd 'Skin-'Nerd","type":"song","duration":547,"albumart":"https://i.scdn.co/image/ab67616d0000b273128450651c9f0442780d8eb8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:7ACxUo21jtTHzy7ZEV56vU in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:7ACxUo21jtTHzy7ZEV56vU Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7snQQk1zcKl8gZ92AnueZW","service":"spop","name":"Sweet Child O' Mine","artist":"Guns N' Roses","album":"Appetite For Destruction","type":"song","duration":356,"albumart":"https://i.scdn.co/image/ab67616d0000b27321ebf49b3292c3f0f575f0f5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:08mG3Y1vljYA6bvDt4Wqkj in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:08mG3Y1vljYA6bvDt4Wqkj Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Jnxngdff0lVu2rza1GVx6","service":"spop","name":"Paranoid - 2009 Remaster","artist":"Black Sabbath","album":"Paranoid","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273605b25c031f809d78054a13c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:0870QNicMawQH2cnzBVZ3P in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:0870QNicMawQH2cnzBVZ3P Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ACxUo21jtTHzy7ZEV56vU","service":"spop","name":"Crazy Train","artist":"Ozzy Osbourne","album":"Blizzard Of Ozz (40th Anniversary Expanded Edition)","type":"song","duration":293,"albumart":"https://i.scdn.co/image/ab67616d0000b273475ca6e5c1ce0ef70740c3c6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:6gX9x3F1ohlivkkKSZ8MAJ in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:6gX9x3F1ohlivkkKSZ8MAJ Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:08mG3Y1vljYA6bvDt4Wqkj","service":"spop","name":"Back In Black","artist":"AC/DC","album":"Back In Black","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b2730b51f8d91f3a21e8426361ae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0870QNicMawQH2cnzBVZ3P","service":"spop","name":"Hitch a Ride","artist":"Boston","album":"Boston","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c1fadcc997a65384f34d694","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:4CyElmiM9EF9aURGWlPZLi in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:4CyElmiM9EF9aURGWlPZLi Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:7Gq4o3qwY5kTdwNwyQ4lLu in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:7Gq4o3qwY5kTdwNwyQ4lLu Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6gX9x3F1ohlivkkKSZ8MAJ","service":"spop","name":"Rice Pudding - Remastered","artist":"Jeff Beck","album":"Remastered from the Archives","type":"song","duration":442,"albumart":"https://i.scdn.co/image/ab67616d0000b27353d91f08b0eafabfa9bba088","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5UuikgHTxSRFRnC0zXx10i in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5UuikgHTxSRFRnC0zXx10i Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4CyElmiM9EF9aURGWlPZLi","service":"spop","name":"La Villa Strangiato","artist":"Rush","album":"Hemispheres","type":"song","duration":574,"albumart":"https://i.scdn.co/image/ab67616d0000b273fe148db2d296efd0f703e61f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:0DnGfA1r8pAssJCuq4ojla in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:0DnGfA1r8pAssJCuq4ojla Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Gq4o3qwY5kTdwNwyQ4lLu","service":"spop","name":"The Bomber A: Closet Queen B: Bolero C: Cast Your Fate To The Wind - Medley","artist":"James Gang","album":"Rides Again","type":"song","duration":424,"albumart":"https://i.scdn.co/image/ab67616d0000b273598ea3c60eae9706375d8a7a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2ekfM9Dcslm7l9Lh7HGT0i in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2ekfM9Dcslm7l9Lh7HGT0i Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2tVQhBSJ1SQtb4gX0taaiR in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2tVQhBSJ1SQtb4gX0taaiR Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5UuikgHTxSRFRnC0zXx10i","service":"spop","name":"Aqualung","artist":"Jethro Tull","album":"Aqualung (Special Edition)","type":"song","duration":396,"albumart":"https://i.scdn.co/image/ab67616d0000b273be6e758fe8300a72eceddb8f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0DnGfA1r8pAssJCuq4ojla","service":"spop","name":"Still Got The Blues","artist":"Gary Moore","album":"Still Got The Blues","type":"song","duration":370,"albumart":"https://i.scdn.co/image/ab67616d0000b273b586fc87d8bde3ba953f233a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5m5I3KcV83zf2p2qbsnr3y in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5m5I3KcV83zf2p2qbsnr3y Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ekfM9Dcslm7l9Lh7HGT0i","service":"spop","name":"Surfing with the Alien","artist":"Joe Satriani","album":"Surfing With The Alien","type":"song","duration":266,"albumart":"https://i.scdn.co/image/ab67616d0000b27373dd51cf8328c824f38919e6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:0Q2dRzhPWFOex89dOjbq5Y in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:0Q2dRzhPWFOex89dOjbq5Y Feb 27 12:39:54 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:6PUabSMXmPnZna361Wwmf7 in service spop Feb 27 12:39:54 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:6PUabSMXmPnZna361Wwmf7 Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2xS1lC45sjj60M1eeRsOUp in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2xS1lC45sjj60M1eeRsOUp Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2tVQhBSJ1SQtb4gX0taaiR","service":"spop","name":"Jessica","artist":"Allman Brothers Band","album":"Dreams","type":"song","duration":450,"albumart":"https://i.scdn.co/image/ab67616d0000b273df38b20327832dfc283fe2e0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5XNpdKmlLJPUbwKQceX2tW in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5XNpdKmlLJPUbwKQceX2tW Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5m5I3KcV83zf2p2qbsnr3y","service":"spop","name":"For the Love of God","artist":"Steve Vai","album":"Passion And Warfare","type":"song","duration":363,"albumart":"https://i.scdn.co/image/ab67616d0000b273c64cc8bd8c3f931eee69c6ca","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:7GqIDx2QVGOpd4r1fZaUUW in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:7GqIDx2QVGOpd4r1fZaUUW Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:389QX9Q1eUOEZ19vtzzI9O in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:389QX9Q1eUOEZ19vtzzI9O Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6PUabSMXmPnZna361Wwmf7","service":"spop","name":"Crossroads - Live","artist":"Cream","album":"Wheels Of Fire","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c7731f5acdcb2d02d78b7ee","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioVolatilePlay Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreStateMachine::volatilePlay Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Spotify Play Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Sending Spotify command to local API: /player/resume Feb 27 12:39:55 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:55+01:00" level=trace msg="seek to 2948ms (diff: 216ms, samples: 130006, bytes: 100674)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Q2dRzhPWFOex89dOjbq5Y","service":"spop","name":"Watermelon In Easter Hay","artist":"Frank Zappa","album":"Joe's Garage Acts I, II & III","type":"song","duration":547,"albumart":"https://i.scdn.co/image/ab67616d0000b273aa7ac662f49e6ac185cba6be","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:3uMmllZo1AfoEnVT4ENCD3 in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:3uMmllZo1AfoEnVT4ENCD3 Feb 27 12:39:55 volumio-mllse volumio[1364]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 27 12:39:55 volumio-mllse volumio[1364]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 27 12:39:55 volumio-mllse volumio[1364]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 27 12:39:55 volumio-mllse volumio[1364]: info: FusionDsp - Effects disabled Feb 27 12:39:55 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:55+01:00" level=debug msg="resume track at 2732ms" Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2xS1lC45sjj60M1eeRsOUp","service":"spop","name":"I'm the One - 2015 Remaster","artist":"Van Halen","album":"Van Halen (Remastered)","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b27317dd812df38fed44d6d2036e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2bBAAVvOHsr7vxWFTuYxH8 in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2bBAAVvOHsr7vxWFTuYxH8 Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5XNpdKmlLJPUbwKQceX2tW","service":"spop","name":"Little Wing","artist":"Stevie Ray Vaughan","album":"Couldn't Stand The Weather (Legacy Edition)","type":"song","duration":408,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb72e98b6e645a68cdfe72a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7GqIDx2QVGOpd4r1fZaUUW","service":"spop","name":"25 or 6 to 4 - 2002 Remaster","artist":"Chicago","album":"Chicago II","type":"song","duration":289,"albumart":"https://i.scdn.co/image/ab67616d0000b273f97c928726d4abbaed5aebf9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:389QX9Q1eUOEZ19vtzzI9O","service":"spop","name":"While My Guitar Gently Weeps - Remastered 2009","artist":"The Beatles","album":"The Beatles (Remastered)","type":"song","duration":285,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ce8b4e42588bf18182a1ad2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:1x1XQqhBViz4opcpwc7FVs in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:1x1XQqhBViz4opcpwc7FVs Feb 27 12:39:55 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:55+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:39:55 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:55+01:00" level=trace msg="scheduling prefetch in 141s" Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3uMmllZo1AfoEnVT4ENCD3","service":"spop","name":"Highway Star - Remastered 2012","artist":"Deep Purple","album":"Machine Head (Remastered)","type":"song","duration":366,"albumart":"https://i.scdn.co/image/ab67616d0000b273a729c9c3dec04b99d889c66f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:5qm0KiVKMXW1kq6VrnIhz5 in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:5qm0KiVKMXW1kq6VrnIhz5 Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2bBAAVvOHsr7vxWFTuYxH8","service":"spop","name":"Under a Glass Moon","artist":"Dream Theater","album":"Images and Words","type":"song","duration":423,"albumart":"https://i.scdn.co/image/ab67616d0000b273f4d11b78efa76496f2acd618","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:7itr4N5bXkAWesPUomCNf7 in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:7itr4N5bXkAWesPUomCNf7 Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1x1XQqhBViz4opcpwc7FVs","service":"spop","name":"Reelin' In The Years","artist":"Steely Dan","album":"Can't Buy A Thrill","type":"song","duration":277,"albumart":"https://i.scdn.co/image/ab67616d0000b2730ddd7e736f870994f4707947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:55+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:39:55 volumio-mllse go-librespot[16721]: time="2025-02-27T12:39:55+01:00" level=trace msg="emitting websocket event: playing" Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","play_origin":"home"}} Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: {"status":"play","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":3000,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:55 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:55 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:2PV9sorI2h94qEPke7SlGI in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:2PV9sorI2h94qEPke7SlGI Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5qm0KiVKMXW1kq6VrnIhz5","service":"spop","name":"Cliffs Of Dover - Instrumental","artist":"Eric Johnson","album":"Ah Via Musicom","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b27351136aeb3f6b077a3f9d9a0f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: info: Exploding uri spotify:track:1L94M3KIu7QluZe63g64rv in service spop Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: EXPLODING URI:spotify:track:1L94M3KIu7QluZe63g64rv Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7itr4N5bXkAWesPUomCNf7","service":"spop","name":"Stranglehold","artist":"Ted Nugent","album":"Great Gonzos! The Best Of Ted Nugent","type":"song","duration":502,"albumart":"https://i.scdn.co/image/ab67616d0000b2736f190c15b945233a504ccb6a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2PV9sorI2h94qEPke7SlGI","service":"spop","name":"Baby's On Fire - Remastered 2004","artist":"Brian Eno","album":"Here Come The Warm Jets","type":"song","duration":319,"albumart":"https://i.scdn.co/image/ab67616d0000b273da85b0f131c23ccf6b631aad","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1L94M3KIu7QluZe63g64rv","service":"spop","name":"Alive","artist":"Pearl Jam","album":"Ten","type":"song","duration":340,"albumart":"https://i.scdn.co/image/ab67616d0000b273d400d27cba05bb0545533864","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: {"status":"play","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":3000,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:39:55 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:39:55 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:39:55 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:39:55 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 27 12:40:05 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:05 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 65 Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 66 Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 65 Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 67 Feb 27 12:40:05 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 27 12:40:05 volumio-mllse volumio[1364]: info: Setting Spotify Volume from Volumio: 67 Feb 27 12:40:05 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:05 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:05 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:05 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:05 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:05 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:05 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:05 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:05 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 67 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 69 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 27 12:40:06 volumio-mllse volumio[1364]: info: Setting Spotify Volume from Volumio: 69 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 69 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 72 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 27 12:40:06 volumio-mllse volumio[1364]: info: Setting Spotify Volume from Volumio: 72 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:06+01:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 72 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 76 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 27 12:40:06 volumio-mllse volumio[1364]: info: Setting Spotify Volume from Volumio: 76 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 76 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 79 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 27 12:40:06 volumio-mllse volumio[1364]: info: Setting Spotify Volume from Volumio: 79 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: info: VolumeController::SetAlsaVolume+ Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:06 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:06 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 79 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 80 Feb 27 12:40:06 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 27 12:40:06 volumio-mllse volumio[1364]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 27 12:40:06 volumio-mllse volumio[1364]: xcb_connection_has_error() returned true Feb 27 12:40:08 volumio-mllse volumio[1364]: SPOTIFY: SETTING SPOTIFY VOLUME 79 Feb 27 12:40:08 volumio-mllse volumio[1364]: info: Sending Spotify command with payload to local API: /player/volume Feb 27 12:40:08 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:08+01:00" level=debug msg="update volume to 51772/65535" Feb 27 12:40:08 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:08+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 27 12:40:08 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:08+01:00" level=trace msg="emitting websocket event: volume" Feb 27 12:40:08 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"volume","data":{"value":79,"max":100}} Feb 27 12:40:08 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED SPOTIFY VOLUME 79 Feb 27 12:40:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 12:40:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 12:40:13 volumio-mllse volumio[1364]: info: Discovery: Getting this device information Feb 27 12:40:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 27 12:40:13 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 12:40:17 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPause Feb 27 12:40:17 volumio-mllse volumio[1364]: info: CoreStateMachine::pause Feb 27 12:40:17 volumio-mllse volumio[1364]: info: CoreStateMachine::stPlaybackTimer Feb 27 12:40:17 volumio-mllse volumio[1364]: info: CoreStateMachine::servicePause Feb 27 12:40:17 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePause Feb 27 12:40:17 volumio-mllse volumio[1364]: info: Spotify Received pause Feb 27 12:40:17 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY PAUSE Feb 27 12:40:17 volumio-mllse volumio[1364]: SPOTIFY: {"status":"play","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","codec":"ogg","seek":14000,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":80,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Feb 27 12:40:17 volumio-mllse volumio[1364]: info: Sending Spotify command to local API: /player/pause Feb 27 12:40:17 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:17+01:00" level=debug msg="pause track at 24676ms" Feb 27 12:40:17 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:40:18 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:40:18 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:18+01:00" level=trace msg="emitting websocket event: paused" Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","play_origin":"home"}} Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: {"status":"pause","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":25000,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:40:18 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:40:18 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:18 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:18 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:18 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:18 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 79 Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 80 Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 27 12:40:18 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioNext Feb 27 12:40:18 volumio-mllse volumio[1364]: info: CoreStateMachine::next Feb 27 12:40:18 volumio-mllse volumio[1364]: info: Spotify next Feb 27 12:40:18 volumio-mllse volumio[1364]: info: Sending Spotify command to local API: /player/next Feb 27 12:40:18 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:18+01:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:18 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:40:18 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:18+01:00" level=trace msg="emitting websocket event: will_play" Feb 27 12:40:18 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","play_origin":"home"}} Feb 27 12:40:18 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:18+01:00" level=debug msg="selected format OGG_VORBIS_320 (765a499de134dacc9e7d5b5ba5d99a003f0642de)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:18 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:18+01:00" level=debug msg="requested aes key for file 765a499de134dacc9e7d5b5ba5d99a003f0642de, gid: 1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=debug msg="fetched first chunk of 15, total size is 7458483 bytes" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=info msg="loaded track \"Save Me\" (paused: true, position: 0ms, duration: 173868ms, prefetched: false)" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=trace msg="emitting websocket event: metadata" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=trace msg="emitting websocket event: stopped" Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","name":"Save Me","artist_names":["fuseo","Jon Klaasen"],"album_name":"Save Me","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","position":0,"duration":173868,"release_date":"year:2025 month:2 day:21","track_number":1,"disc_number":1}} Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"home"}} Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: {"status":"stop","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":0,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:19 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:19 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CorePlayQueue::getTrack 0 Feb 27 12:40:19 volumio-mllse volumio[1364]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 79 Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 80 Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=trace msg="emitting websocket event: paused" Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","play_origin":"home"}} Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: PUSH STATE SPOTIFY Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: {"status":"pause","service":"spop","title":"Save Me","artist":"fuseo, Jon Klaasen","album":"Save Me","albumart":"https://i.scdn.co/image/ab67616d00001e02575ed2858a11dc6112274cdf","uri":"spotify:track:1ayLpDCbKCGorBfWN4ycS0","trackType":"spotify","seek":0,"duration":173,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreCommandRouter::servicePushState Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreStateMachine::pushState Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 12:40:19 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioPushState Feb 27 12:40:19 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output update for this device Feb 27 12:40:19 volumio-mllse volumio[1364]: info: MRS: Pushing multiroomSync output Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: SPOTIFY VOLUME 79 Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: VOLUMIO VOLUME 80 Feb 27 12:40:19 volumio-mllse volumio[1364]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 27 12:40:19 volumio-mllse go-librespot[16721]: time="2025-02-27T12:40:19+01:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:1ayLpDCbKCGorBfWN4ycS0" Feb 27 12:40:25 volumio-mllse volumiologrotate[840]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 27 12:40:25 volumio-mllse volumiologrotate[840]: ls: cannot access 'MLLSE': No such file or directory Feb 27 12:40:34 volumio-mllse volumio[1364]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] Feb 27 12:40:34 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset Feb 27 12:40:35 volumio-mllse volumio[1364]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 27 12:40:35 volumio-mllse volumio[1364]: info: FusionDsp - Effects disabled Feb 27 12:40:35 volumio-mllse volumio[1364]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 27 12:40:35 volumio-mllse volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 12:40:35 volumio-mllse volumio[1364]: info: CoreCommandRouter::Close All Modals sent Feb 27 12:40:42 volumio-mllse volumio[1364]: info: CALLMETHOD: audio_interface fusiondsp enableeffect undefined Feb 27 12:40:42 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Feb 27 12:40:42 volumio-mllse volumio[1364]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 27 12:40:42 volumio-mllse volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 12:40:42 volumio-mllse volumio[1364]: info: CoreCommandRouter::Close All Modals sent Feb 27 12:40:42 volumio-mllse volumio[1364]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 27 12:40:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 12:40:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 12:40:43 volumio-mllse volumio[1364]: info: Discovery: Getting this device information Feb 27 12:40:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::volumioGetState Feb 27 12:40:43 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 12:40:44 volumio-mllse volumio[1364]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Feb 27 12:40:44 volumio-mllse volumio[1364]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Feb 27 12:40:44 volumio-mllse volumio[1364]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 27 12:40:44 volumio-mllse volumio[1364]: info: FusionDsp - Effects disabled Feb 27 12:40:44 volumio-mllse volumio[1364]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 12:40:44 volumio-mllse volumio[1364]: info: CoreCommandRouter::Close All Modals sent Feb 27 12:40:44 volumio-mllse volumio[1364]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 27 12:40:50 volumio-mllse volumio[1364]: info: [1740656450988] [80s80s] Pushing the next song state BLACK - Wonderful Life and getting next track. Feb 27 12:40:50 volumio-mllse volumio[1364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 12:40:50 volumio-mllse volumio[1364]: TypeError: Cannot set property 'name' of undefined Feb 27 12:40:50 volumio-mllse volumio[1364]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Feb 27 12:40:50 volumio-mllse volumio[1364]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Feb 27 12:40:50 volumio-mllse volumio[1364]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Feb 27 12:40:50 volumio-mllse volumio[1364]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Feb 27 12:40:50 volumio-mllse volumio[1364]: at processImmediate (internal/timers.js:464:21) Feb 27 12:40:50 volumio-mllse volumio[1364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 12:40:51 volumio-mllse sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-27 12:39 Feb 27 12:40:51 volumio-mllse sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"