-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-31 18:58:37 CEST. -- May 31 18:57:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:57:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:57:02 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:57:02 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:57:02 volumio volumio[988]: info: CorePlayQueue::getTrack 0 May 31 18:57:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:57:03 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:10 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:16 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:57:16 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:57:16 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:57:16 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:57:16 volumio volumio[988]: info: CorePlayQueue::getTrack 0 May 31 18:57:16 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:57:17 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:18 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:57:18 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:57:18 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:57:18 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:57:18 volumio volumio[988]: info: CorePlayQueue::getTrack 0 May 31 18:57:18 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:57:19 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.2.159:3000 from 192.168.2.203 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 31 18:57:19 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.2.159:3000 from 192.168.2.203 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 May 31 18:57:24 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:26 volumio volumio[988]: Searching plugin music_service/spop May 31 18:57:26 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: spop , search May 31 18:57:27 volumio volumio[988]: info: All search sources collected, pushing search results May 31 18:57:28 volumio volumio[988]: Searching plugin music_service/spop May 31 18:57:28 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: spop , search May 31 18:57:28 volumio volumio[988]: info: All search sources collected, pushing search results May 31 18:57:29 volumio volumio[988]: Searching plugin music_service/spop May 31 18:57:29 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: spop , search May 31 18:57:30 volumio volumio[988]: info: All search sources collected, pushing search results May 31 18:57:31 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:31 volumio volumio[988]: Searching plugin music_service/spop May 31 18:57:31 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: spop , search May 31 18:57:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:57:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:57:32 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:57:32 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:57:32 volumio volumio[988]: info: CorePlayQueue::getTrack 0 May 31 18:57:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:57:32 volumio volumio[988]: info: All search sources collected, pushing search results May 31 18:57:33 volumio volumio[988]: Searching plugin music_service/spop May 31 18:57:33 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: spop , search May 31 18:57:34 volumio volumio[988]: info: All search sources collected, pushing search results May 31 18:57:36 volumio volumio[988]: Searching plugin music_service/spop May 31 18:57:36 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: spop , search May 31 18:57:36 volumio volumio[988]: info: All search sources collected, pushing search results May 31 18:57:38 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:38 volumio volumio[988]: info: CorePlayQueue::getTrack 0 May 31 18:57:38 volumio volumio[988]: info: CorePlayQueue::getTrack 1 May 31 18:57:38 volumio volumio[988]: info: Prefetching next song May 31 18:57:39 volumio volumio[988]: info: Preload queue cleared May 31 18:57:39 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::ClearQueue May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:57:39 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:57:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 May 31 18:57:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:57:39 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:57:39 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:57:39 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::serviceStop May 31 18:57:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 May 31 18:57:39 volumio volumio[988]: info: CoreCommandRouter::serviceStop May 31 18:57:39 volumio volumio[988]: info: Spotify Stop May 31 18:57:39 volumio volumio[988]: SPOTIFY: SPOTIFY STOP May 31 18:57:39 volumio volumio[988]: SPOTIFY: {"status":"play","position":0,"title":"My Way - 2008 Remastered","artist":"Frank Sinatra","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d0000b273b81d66d1416afa139d12767b","uri":"spotify:track:2YkIDPL5lGhRhomCq4S2RO","trackType":"spotify","codec":"ogg","seek":5393,"duration":276,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":98,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 31 18:57:39 volumio volumio[988]: info: Sending Spotify command to local API: /player/pause May 31 18:57:39 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue May 31 18:57:39 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:57:39 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:57:39 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:57:39 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:57:39 volumio volumio[988]: info: Preload queue cleared May 31 18:57:39 volumio volumio[988]: info: Adding Item to queue: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:57:39 volumio volumio[988]: info: Using cached record of: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:57:39 volumio volumio[988]: info: Adding Item to queue: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:57:39 volumio volumio[988]: info: Exploding uri spotify:track:4feXcsElKIVsGwkbnTHAfV in service spop May 31 18:57:39 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:57:39 volumio volumio[988]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:57:39 volumio volumio[988]: info: Exploding uri spotify:track:7FXj7Qg3YorUxdrzvrcY25 in service spop May 31 18:57:39 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:57:40 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4feXcsElKIVsGwkbnTHAfV","service":"spop","name":"Somethin' Stupid","artist":"Frank Sinatra","album":"The World We Knew","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b2733e2331ff09f59ea3c87fe5d6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","service":"spop","name":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra","album":"Nothing But The Best (2008 Remastered)","type":"song","duration":147,"albumart":"https://i.scdn.co/image/ab67616d0000b273b81d66d1416afa139d12767b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:57:40 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:57:40 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:57:40 volumio volumio[988]: info: CoreCommandRouter::volumioPlay May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::play index 2 May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:57:40 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:57:40 volumio volumio[988]: info: Preload queue cleared May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:57:40 volumio volumio[988]: info: Using cached record of: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:4QxDOjgpYtQDxxbWPuEJOy in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:4FmCUATNIarCQh72JYdvnm in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:6ft9PAgNOjmZ2kFVP7LGqb in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:4dt6XKr0xKdPzjFhwB8dBm in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:29U7stRjqHU6rMiS8BfaI9 in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:3aEJMh1cXKEjgh52claxQp in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:57:40 volumio volumio[988]: info: Using cached record of: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:5b7OgznPJJr1vHNYGyvxau in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:2WaYW84yWij5NSCpgSeU2R in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:0IU7UJ9nknVKWlHCECHVQw in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:13apJo5WpOSe3qmfIYOCp2 in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:57:40 volumio volumio[988]: info: Adding Item to queue: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:57:40 volumio volumio[988]: info: Exploding uri spotify:track:35MvAviAxdiPXuUwnz9cs6 in service spop May 31 18:57:40 volumio volumio[988]: SPOTIFY: EXPLODING URI:spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::play index undefined May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:57:40 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer May 31 18:57:40 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:57:40 volumio volumio[988]: info: CoreCommandRouter::volumioGetVisibleSources May 31 18:57:40 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 18:57:40 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 18:57:40 volumio volumio[988]: info: [1748710660250] ControllerSpotify::clearAddPlayTrack May 31 18:57:40 volumio volumio[988]: info: Sending Spotify command with payload to local API: /player/play May 31 18:57:40 volumio go-librespot[1311]: time="2025-05-31T18:57:40+02:00" level=debug msg="pause track at 274275ms" May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0IU7UJ9nknVKWlHCECHVQw","service":"spop","name":"All The Way","artist":"Frank Sinatra","album":"Platinum","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b2730d41ac3629a40e814cadf0a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4dt6XKr0xKdPzjFhwB8dBm","service":"spop","name":"That's Life - 2008 Remastered","artist":"Frank Sinatra","album":"Nothing But The Best (2008 Remastered)","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b273b81d66d1416afa139d12767b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4QxDOjgpYtQDxxbWPuEJOy","service":"spop","name":"L-O-V-E","artist":"Nat King Cole","album":"L-O-V-E","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b2738daeb3002870ebba8e8d1387","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4FmCUATNIarCQh72JYdvnm","service":"spop","name":"That's Life","artist":"Frank Sinatra","album":"That's Life","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c21d73934bb9760a2f791a2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5b7OgznPJJr1vHNYGyvxau","service":"spop","name":"Fly Me To The Moon (In Other Words)","artist":"Frank Sinatra","album":"Sinatra/Basie: The Complete Reprise Studio Recordings","type":"song","duration":147,"albumart":"https://i.scdn.co/image/ab67616d0000b273cb81eb3c1238c60f2bbfd3b5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2WaYW84yWij5NSCpgSeU2R","service":"spop","name":"You Make Me Feel So Young - Remastered 1998","artist":"Frank Sinatra","album":"Songs For Swingin' Lovers! (Remastered)","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273d7720a6a8b713b833313f396","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:13apJo5WpOSe3qmfIYOCp2","service":"spop","name":"I've Got The World On A String","artist":"Frank Sinatra","album":"Platinum","type":"song","duration":128,"albumart":"https://i.scdn.co/image/ab67616d0000b2730d41ac3629a40e814cadf0a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3aEJMh1cXKEjgh52claxQp","service":"spop","name":"I've Got You Under My Skin - Remastered 1998","artist":"Frank Sinatra","album":"Songs For Swingin' Lovers! (Remastered)","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b273d7720a6a8b713b833313f396","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:29U7stRjqHU6rMiS8BfaI9","service":"spop","name":"What A Wonderful World","artist":"Louis Armstrong","album":"What A Wonderful World","type":"song","duration":139,"albumart":"https://i.scdn.co/image/ab67616d0000b273845a5660b804e5f3e821fbed","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ft9PAgNOjmZ2kFVP7LGqb","service":"spop","name":"Can't Take My Eyes off You","artist":"Frankie Valli","album":"The Very Best of Frankie Valli & The 4 Seasons","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273b96c21e15c091eb98a6c88a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:35MvAviAxdiPXuUwnz9cs6","service":"spop","name":"Entends ma voix","artist":"Frank Michael","album":"Mes premiers amours (1975 - 1985)","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b2734c3fa61c55fba1aa1ccacf9b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 18:57:40 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:57:40 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:57:40 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:57:40 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:57:41 volumio volumio[988]: info: camilladsp spawned new process with pid 5874, instance 1, run: true May 31 18:57:45 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:46 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:57:46 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:57:46 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:57:46 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:57:46 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:57:46 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:57:52 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:52 volumio kernel: hwmon hwmon1: Voltage normalised May 31 18:57:58 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:57:58 volumio kernel: hwmon hwmon1: Undervoltage detected! May 31 18:58:00 volumio go-librespot[1311]: time="2025-05-31T18:58:00+02:00" level=error msg="failed put state after update" error="failed reading error response: EOF" May 31 18:58:00 volumio go-librespot[1311]: time="2025-05-31T18:58:00+02:00" level=trace msg="emitting websocket event: not_playing" May 31 18:58:00 volumio go-librespot[1311]: time="2025-05-31T18:58:00+02:00" level=debug msg="loading track spotify:track:2YkIDPL5lGhRhomCq4S2RO (paused: true, position: 1ms)" May 31 18:58:00 volumio volumio[988]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2YkIDPL5lGhRhomCq4S2RO","play_origin":"go-librespot"}} May 31 18:58:00 volumio volumio[988]: error: Failed to decode event: not_playing May 31 18:58:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:58:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:58:02 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:58:02 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:58:02 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:58:05 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:58:08 volumio volumio[988]: info: Preload queue cleared May 31 18:58:08 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::ClearQueue May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:08 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:08 volumio volumio[988]: info: Preload queue cleared May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:08 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:08 volumio volumio[988]: info: CoreCommandRouter::volumioPlay May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::play index 2 May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:08 volumio volumio[988]: info: Preload queue cleared May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:08 volumio volumio[988]: info: Adding Item to queue: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:08 volumio volumio[988]: info: Using cached record of: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:08 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::play index undefined May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:08 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer May 31 18:58:08 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:08 volumio volumio[988]: info: [1748710688265] ControllerSpotify::clearAddPlayTrack May 31 18:58:08 volumio volumio[988]: info: Sending Spotify command with payload to local API: /player/play May 31 18:58:09 volumio volumio[988]: info: Preload queue cleared May 31 18:58:09 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::ClearQueue May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:09 volumio volumio[988]: info: Preload queue cleared May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:09 volumio volumio[988]: info: CoreCommandRouter::volumioPlay May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::play index 2 May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:09 volumio volumio[988]: info: Preload queue cleared May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:09 volumio volumio[988]: info: Adding Item to queue: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:09 volumio volumio[988]: info: Using cached record of: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::play index undefined May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:09 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer May 31 18:58:09 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:09 volumio volumio[988]: info: [1748710689674] ControllerSpotify::clearAddPlayTrack May 31 18:58:09 volumio volumio[988]: info: Sending Spotify command with payload to local API: /player/play May 31 18:58:11 volumio volumio[988]: info: Preload queue cleared May 31 18:58:11 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::ClearQueue May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:11 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:11 volumio volumio[988]: info: Preload queue cleared May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:11 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:11 volumio volumio[988]: info: CoreCommandRouter::volumioPlay May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::play index 2 May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:11 volumio volumio[988]: info: Preload queue cleared May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:11 volumio volumio[988]: info: Adding Item to queue: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:11 volumio volumio[988]: info: Using cached record of: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:11 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::play index undefined May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:11 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer May 31 18:58:11 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:11 volumio volumio[988]: info: [1748710691838] ControllerSpotify::clearAddPlayTrack May 31 18:58:11 volumio volumio[988]: info: Sending Spotify command with payload to local API: /player/play May 31 18:58:12 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:58:12 volumio go-librespot[1311]: time="2025-05-31T18:58:12+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 18:58:12 volumio go-librespot[1311]: time="2025-05-31T18:58:12+02:00" level=trace msg="emitting websocket event: will_play" May 31 18:58:12 volumio volumio[988]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2YkIDPL5lGhRhomCq4S2RO","play_origin":"go-librespot"}} May 31 18:58:12 volumio go-librespot[1311]: time="2025-05-31T18:58:12+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2YkIDPL5lGhRhomCq4S2RO" May 31 18:58:12 volumio go-librespot[1311]: time="2025-05-31T18:58:12+02:00" level=debug msg="requested aes key for file b26ba4410e35c946a1616da8d0a1a6d3333220a5, gid: 2YkIDPL5lGhRhomCq4S2RO" May 31 18:58:12 volumio volumio[988]: info: Preload queue cleared May 31 18:58:12 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::ClearQueue May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:12 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:12 volumio volumio[988]: info: Preload queue cleared May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:12 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:12 volumio volumio[988]: info: CoreCommandRouter::volumioPlay May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::play index 2 May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:12 volumio volumio[988]: info: Preload queue cleared May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:12 volumio go-librespot[1311]: time="2025-05-31T18:58:12+02:00" level=debug msg="fetched first chunk of 23, total size is 11785478 bytes" May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:12 volumio go-librespot[1311]: time="2025-05-31T18:58:12+02:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" May 31 18:58:12 volumio volumio[988]: info: Adding Item to queue: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:12 volumio volumio[988]: info: Using cached record of: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:12 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::play index undefined May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:12 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer May 31 18:58:12 volumio volumio[988]: info: CorePlayQueue::getTrack 2 May 31 18:58:12 volumio volumio[988]: info: [1748710692792] ControllerSpotify::clearAddPlayTrack May 31 18:58:12 volumio volumio[988]: info: Sending Spotify command with payload to local API: /player/play May 31 18:58:12 volumio go-librespot[1311]: time="2025-05-31T18:58:12+02:00" level=info msg="loaded track \"My Way - 2008 Remastered\" (uri: spotify:track:2YkIDPL5lGhRhomCq4S2RO, paused: true, position: 1ms, duration: 276253ms)" May 31 18:58:12 volumio volumio[988]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 31 18:58:12 volumio volumio[988]: info: FusionDsp - ---- read samplerate from file: 44100 May 31 18:58:12 volumio volumio[988]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 18:58:12 volumio volumio[988]: error: FusionDsp - WebSocket error: [object Object] May 31 18:58:12 volumio volumio[988]: info: camilladsp spawned new process with pid 5975, instance 1, run: true May 31 18:58:13 volumio go-librespot[1311]: time="2025-05-31T18:58:13+02:00" level=debug msg="fetched chunk 1/22, size: 524288" May 31 18:58:13 volumio volumio[988]: info: Preload queue cleared May 31 18:58:13 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::ClearQueue May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:13 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:13 volumio volumio[988]: info: Preload queue cleared May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:3spdoTYpuCpmq19tuD0bOe May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:4feXcsElKIVsGwkbnTHAfV May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:7FXj7Qg3YorUxdrzvrcY25 May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:2YkIDPL5lGhRhomCq4S2RO May 31 18:58:13 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:13 volumio volumio[988]: info: CoreCommandRouter::volumioPlay May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::play index 3 May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::addQueueItems May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::addQueueItems May 31 18:58:13 volumio volumio[988]: info: Preload queue cleared May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:4QxDOjgpYtQDxxbWPuEJOy May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:4FmCUATNIarCQh72JYdvnm May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:6ft9PAgNOjmZ2kFVP7LGqb May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:4dt6XKr0xKdPzjFhwB8dBm May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:29U7stRjqHU6rMiS8BfaI9 May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:3aEJMh1cXKEjgh52claxQp May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:6lTTzSk1hRrxp4VMwXBp2l May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:5b7OgznPJJr1vHNYGyvxau May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:2WaYW84yWij5NSCpgSeU2R May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:0IU7UJ9nknVKWlHCECHVQw May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:13apJo5WpOSe3qmfIYOCp2 May 31 18:58:13 volumio volumio[988]: info: Adding Item to queue: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:13 volumio volumio[988]: info: Using cached record of: spotify:track:35MvAviAxdiPXuUwnz9cs6 May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::stop May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:13 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::saveQueue May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::play index undefined May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::getTrackBlock May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:13 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer May 31 18:58:13 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:13 volumio volumio[988]: info: [1748710693150] ControllerSpotify::clearAddPlayTrack May 31 18:58:13 volumio volumio[988]: info: Sending Spotify command with payload to local API: /player/play May 31 18:58:13 volumio go-librespot[1311]: time="2025-05-31T18:58:13+02:00" level=debug msg="fetched chunk 2/22, size: 524288" May 31 18:58:13 volumio go-librespot[1311]: time="2025-05-31T18:58:13+02:00" level=debug msg="fetched chunk 3/22, size: 524288" May 31 18:58:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:58:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:58:14 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:58:14 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:58:14 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:58:15 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.2.159:3000 from 192.168.2.203 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 31 18:58:15 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.2.159:3000 from 192.168.2.203 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 May 31 18:58:17 volumio go-librespot[1311]: time="2025-05-31T18:58:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 18:58:17 volumio go-librespot[1311]: time="2025-05-31T18:58:17+02:00" level=trace msg="emitting websocket event: metadata" May 31 18:58:17 volumio go-librespot[1311]: time="2025-05-31T18:58:17+02:00" level=trace msg="emitting websocket event: stopped" May 31 18:58:17 volumio volumio[988]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2YkIDPL5lGhRhomCq4S2RO","name":"My Way - 2008 Remastered","artist_names":["Frank Sinatra"],"album_name":"Nothing But The Best (2008 Remastered)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","position":1,"duration":276253,"release_date":"year:2008 month:5 day:13","track_number":20,"disc_number":1}} May 31 18:58:17 volumio volumio[988]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} May 31 18:58:17 volumio volumio[988]: SPOTIFY: PUSH STATE SPOTIFY May 31 18:58:17 volumio volumio[988]: SPOTIFY: {"status":"stop","service":"spop","title":"My Way - 2008 Remastered","artist":"Frank Sinatra","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:2YkIDPL5lGhRhomCq4S2RO","trackType":"spotify","seek":1,"duration":276,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:17 volumio volumio[988]: info: CoreCommandRouter::servicePushState May 31 18:58:17 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:58:17 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:17 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:58:17 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:58:17 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:58:17 volumio go-librespot[1311]: time="2025-05-31T18:58:17+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" May 31 18:58:17 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:58:17 volumio go-librespot[1311]: time="2025-05-31T18:58:17+02:00" level=debug msg="loading track spotify:track:7FXj7Qg3YorUxdrzvrcY25 (paused: false, position: 1ms)" May 31 18:58:17 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:17 volumio volumio[988]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"My Way - 2008 Remastered","artist":"Frank Sinatra","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:2YkIDPL5lGhRhomCq4S2RO","trackType":"spotify","seek":1,"duration":276,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:17 volumio volumio[988]: verbose: CURRENT POSITION 3 May 31 18:58:17 volumio volumio[988]: info: CoreStateMachine::syncState stateService stop May 31 18:58:17 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop May 31 18:58:17 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:58:17 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:17 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:58:17 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:58:17 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:58:17 volumio volumio[988]: info: No code May 31 18:58:17 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:58:17 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:17 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:58:17 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:58:17 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:58:17 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:58:17 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:58:17 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:58:19 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:58:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 31 18:58:19 volumio volumio[988]: info: In handleBrowseUri, curUri=spotify:artist:1Mxqyy3pSjf8kZZL4QVxS0 May 31 18:58:20 volumio volumio[988]: info: Preload queue cleared May 31 18:58:20 volumio volumio[988]: info: Preloading song: spotify:track:74VR3AkGPhbYXnxcOYa16x May 31 18:58:20 volumio volumio[988]: info: Preloading song: spotify:track:62KZRwymXq9Rgm8Cm5UCfx May 31 18:58:20 volumio volumio[988]: info: Preloading song: spotify:track:0K2WjMLZYr09LKwurGRYRE May 31 18:58:20 volumio volumio[988]: info: Preloading song: spotify:track:0elmUoU7eMPwZX1Mw1MnQo May 31 18:58:20 volumio volumio[988]: info: Preloading song: spotify:track:4hHbeIIKO5Y5uLyIEbY9Gn May 31 18:58:20 volumio volumio[988]: info: Preload queue cleared May 31 18:58:20 volumio volumio[988]: info: Preload queue cleared May 31 18:58:21 volumio volumio[988]: info: Executing endpoint getSimilarArtists May 31 18:58:21 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists May 31 18:58:21 volumio volumio[988]: info: Executing endpoint metavolumio May 31 18:58:21 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 18:58:21 volumio volumio[988]: info: Executing endpoint metavolumio May 31 18:58:21 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 18:58:21 volumio volumio[988]: info: Executing endpoint getSimilarArtists May 31 18:58:21 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists May 31 18:58:21 volumio go-librespot[1311]: time="2025-05-31T18:58:21+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 18:58:21 volumio go-librespot[1311]: time="2025-05-31T18:58:21+02:00" level=trace msg="emitting websocket event: will_play" May 31 18:58:21 volumio volumio[988]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","play_origin":"go-librespot"}} May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:7FXj7Qg3YorUxdrzvrcY25" May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="requested aes key for file bca4b481f81a33614deb3f7572b4d8adf37be364, gid: 7FXj7Qg3YorUxdrzvrcY25" May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="fetched first chunk of 12, total size is 6054384 bytes" May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=info msg="loaded track \"Fly Me To The Moon - 2008 Remastered\" (uri: spotify:track:7FXj7Qg3YorUxdrzvrcY25, paused: false, position: 1ms, duration: 147520ms)" May 31 18:58:22 volumio volumio[988]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 31 18:58:22 volumio volumio[988]: info: FusionDsp - ---- read samplerate from file: 44100 May 31 18:58:22 volumio volumio[988]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 18:58:22 volumio volumio[988]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 159" May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="fetched chunk 3/11, size: 524288" May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="fetched chunk 1/11, size: 524288" May 31 18:58:22 volumio go-librespot[1311]: time="2025-05-31T18:58:22+02:00" level=debug msg="fetched chunk 2/11, size: 524288" May 31 18:58:24 volumio go-librespot[1311]: time="2025-05-31T18:58:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 18:58:24 volumio go-librespot[1311]: time="2025-05-31T18:58:24+02:00" level=trace msg="emitting websocket event: metadata" May 31 18:58:24 volumio volumio[988]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","name":"Fly Me To The Moon - 2008 Remastered","artist_names":["Frank Sinatra","Count Basie"],"album_name":"Nothing But The Best (2008 Remastered)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","position":1,"duration":147520,"release_date":"year:2008 month:5 day:13","track_number":8,"disc_number":1}} May 31 18:58:26 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:58:26 volumio go-librespot[1311]: time="2025-05-31T18:58:26+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 18:58:26 volumio go-librespot[1311]: time="2025-05-31T18:58:26+02:00" level=trace msg="emitting websocket event: paused" May 31 18:58:26 volumio volumio[988]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","play_origin":"go-librespot"}} May 31 18:58:26 volumio volumio[988]: SPOTIFY: PUSH STATE SPOTIFY May 31 18:58:26 volumio volumio[988]: SPOTIFY: {"status":"pause","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState May 31 18:58:26 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:26 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:26 volumio volumio[988]: verbose: CURRENT POSITION 3 May 31 18:58:26 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause May 31 18:58:26 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop May 31 18:58:26 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:58:26 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:26 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:58:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:58:26 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:58:26 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:58:26 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:58:29 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:58:29 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:58:29 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:58:29 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:58:29 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:29 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:58:30 volumio go-librespot[1311]: time="2025-05-31T18:58:30+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 18:58:30 volumio go-librespot[1311]: time="2025-05-31T18:58:30+02:00" level=trace msg="emitting websocket event: paused" May 31 18:58:30 volumio volumio[988]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","play_origin":"go-librespot"}} May 31 18:58:30 volumio volumio[988]: SPOTIFY: PUSH STATE SPOTIFY May 31 18:58:30 volumio volumio[988]: SPOTIFY: {"status":"pause","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:30 volumio volumio[988]: info: CoreCommandRouter::servicePushState May 31 18:58:30 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:30 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:30 volumio volumio[988]: verbose: CURRENT POSITION 3 May 31 18:58:30 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause May 31 18:58:30 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop May 31 18:58:30 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:58:30 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:30 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:58:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:58:30 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:58:30 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:58:30 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:58:31 volumio go-librespot[1311]: time="2025-05-31T18:58:31+02:00" level=debug msg="fetched chunk 4/11, size: 524288" May 31 18:58:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:58:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:58:32 volumio volumio[988]: info: Discovery: Getting this device information May 31 18:58:32 volumio volumio[988]: info: CoreCommandRouter::volumioGetState May 31 18:58:32 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:58:33 volumio wpa_supplicant[895]: RRM: Ignoring radio measurement request: Not RRM network May 31 18:58:34 volumio go-librespot[1311]: time="2025-05-31T18:58:34+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 18:58:34 volumio go-librespot[1311]: time="2025-05-31T18:58:34+02:00" level=trace msg="emitting websocket event: playing" May 31 18:58:34 volumio volumio[988]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","play_origin":"go-librespot"}} May 31 18:58:34 volumio volumio[988]: SPOTIFY: PUSH STATE SPOTIFY May 31 18:58:34 volumio volumio[988]: SPOTIFY: {"status":"play","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:34 volumio volumio[988]: info: CoreCommandRouter::servicePushState May 31 18:58:34 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:34 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:34 volumio volumio[988]: verbose: CURRENT POSITION 3 May 31 18:58:34 volumio volumio[988]: info: CoreStateMachine::syncState stateService play May 31 18:58:34 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop May 31 18:58:34 volumio go-librespot[1311]: time="2025-05-31T18:58:34+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" May 31 18:58:34 volumio go-librespot[1311]: time="2025-05-31T18:58:34+02:00" level=debug msg="loading track spotify:track:7FXj7Qg3YorUxdrzvrcY25 (paused: false, position: 280ms)" May 31 18:58:34 volumio volumio[988]: SPOTIFY: PUSH STATE SPOTIFY May 31 18:58:34 volumio volumio[988]: SPOTIFY: {"status":"play","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:34 volumio volumio[988]: info: CoreCommandRouter::servicePushState May 31 18:58:34 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:34 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Fly Me To The Moon - 2008 Remastered","artist":"Frank Sinatra, Count Basie","album":"Nothing But The Best (2008 Remastered)","albumart":"https://i.scdn.co/image/ab67616d00001e02b81d66d1416afa139d12767b","uri":"spotify:track:7FXj7Qg3YorUxdrzvrcY25","trackType":"spotify","seek":1,"duration":147,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 18:58:34 volumio volumio[988]: verbose: CURRENT POSITION 3 May 31 18:58:34 volumio volumio[988]: info: CoreStateMachine::syncState stateService play May 31 18:58:34 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play May 31 18:58:34 volumio volumio[988]: info: Received an update from plugin. extracting info from payload May 31 18:58:34 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:58:34 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:34 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:58:34 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:58:34 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:58:34 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:58:34 volumio volumio[988]: info: CoreStateMachine::pushState May 31 18:58:34 volumio volumio[988]: info: CorePlayQueue::getTrack 3 May 31 18:58:34 volumio volumio[988]: info: CoreCommandRouter::volumioPushState May 31 18:58:34 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device May 31 18:58:34 volumio volumio[988]: info: MRS: Pushing multiroomSync output May 31 18:58:34 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:58:34 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 May 31 18:58:35 volumio volumio[988]: info: camilladsp spawned new process with pid 6016, instance 1, run: true May 31 18:58:36 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 18:58:36 volumio volumio[988]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net May 31 18:58:36 volumio volumio[988]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 31 18:58:36 volumio volumio[988]: errno: -3007, May 31 18:58:36 volumio volumio[988]: code: 'ENOTFOUND', May 31 18:58:36 volumio volumio[988]: syscall: 'getaddrinfo', May 31 18:58:36 volumio volumio[988]: hostname: 'lastfm.freetls.fastly.net' May 31 18:58:36 volumio volumio[988]: } May 31 18:58:36 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 18:58:37 volumio sudo[6046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 18:57 May 31 18:58:37 volumio sudo[6046]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"